could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:15:28 INFO - 1500808528543 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
04:15:28 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500808528546 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:15:28 INFO - 1500808528547 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:15:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 158ms
04:15:28 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:15:28 INFO - 1500808528549 Marionette TRACE 352 <- [1,6,null,{}]
04:15:28 INFO - 1500808528551 Marionette DEBUG Closed connection 352
04:15:28 INFO - 1500808528552 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49785
04:15:28 INFO - 1500808528553 Marionette DEBUG Closed connection 353
04:15:28 INFO - 1500808528553 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49786
04:15:28 INFO - 1500808528554 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:28 INFO - 1500808528555 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528558 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528562 Marionette TRACE 354 <- [1,1,null,{"sessionId":"83355a3a-50cc-4794-8c3e-3274fce546c3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528564 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:15:28 INFO - 1500808528564 Marionette TRACE 354 <- [1,2,null,{}]
04:15:28 INFO - 1500808528565 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:28 INFO - 1500808528566 Marionette TRACE 354 <- [1,3,null,{}]
04:15:28 INFO - 1500808528566 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:28 INFO - 1500808528567 Marionette TRACE 354 <- [1,4,null,{}]
04:15:28 INFO - 1500808528569 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
04:15:28 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500808528572 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:15:28 INFO - 1500808528573 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:15:28 INFO - 1500808528575 Marionette TRACE 354 <- [1,6,null,{}]
04:15:28 INFO - 1500808528577 Marionette DEBUG Closed connection 354
04:15:28 INFO - 1500808528578 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49787
04:15:28 INFO - 1500808528579 Marionette DEBUG Closed connection 355
04:15:28 INFO - 1500808528580 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49788
04:15:28 INFO - 1500808528581 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:28 INFO - 1500808528581 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528584 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528588 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9cacbb81-0bb9-4549-9110-b651150f402e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528589 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:15:28 INFO - 1500808528590 Marionette TRACE 356 <- [1,2,null,{}]
04:15:28 INFO - 1500808528592 Marionette DEBUG Closed connection 356
04:15:28 INFO - 1500808528593 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49789
04:15:28 INFO - 1500808528594 Marionette DEBUG Closed connection 357
04:15:28 INFO - 1500808528595 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49790
04:15:28 INFO - 1500808528596 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:28 INFO - 1500808528597 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528599 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528603 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0073e113-6821-4a08-aecd-497c81cbc940","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528604 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49341/clicks.html"}]
04:15:28 INFO - 1500808528607 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/hidden.html"
04:15:28 INFO - 1500808528616 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/hidden.html"
04:15:28 INFO - 1500808528616 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/hidden.html"
04:15:28 INFO - 1500808528628 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49341/clicks.html"
04:15:28 INFO - 1500808528630 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49341/clicks.html"
04:15:28 INFO - 1500808528634 Marionette TRACE 358 <- [1,2,null,{}]
04:15:28 INFO - 1500808528645 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:15:28 INFO - 1500808528649 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a49c2279-ad18-44b2-b5b2-c33fb8eddf45","ELEMENT":"a49c2279-ad18-44b2-b5b2-c33fb8eddf45"}}]
04:15:28 INFO - 1500808528650 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a49c2279-ad18-44b2-b5b2-c33fb8eddf45"}]
04:15:28 INFO - 1500808528702 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/clicks.html"
04:15:28 INFO - 1500808528713 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/clicks.html"
04:15:28 INFO - 1500808528714 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/clicks.html"
04:15:28 INFO - 1500808528722 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49341/test.html"
04:15:28 INFO - 1500808528726 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49341/test.html"
04:15:28 INFO - 1500808528731 Marionette TRACE 358 <- [1,4,null,{}]
04:15:28 INFO - 1500808528736 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:15:28 INFO - 1500808528742 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d89e0f13-dfe7-4e61-b57e-185573187cd9","ELEMENT":"d89e0f13-dfe7-4e61-b57e-185573187cd9"}}]
04:15:28 INFO - 1500808528744 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:15:28 INFO - 1500808528744 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:15:28 INFO - 1500808528746 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
04:15:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 203ms
04:15:28 INFO - TEST-START | test_click.py TestClick.test_container_element
04:15:28 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500808528750 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:15:28 INFO - 1500808528751 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:15:28 INFO - 1500808528752 Marionette TRACE 358 <- [1,8,null,{}]
04:15:28 INFO - 1500808528754 Marionette DEBUG Closed connection 358
04:15:28 INFO - 1500808528755 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49793
04:15:28 INFO - 1500808528756 Marionette DEBUG Closed connection 359
04:15:28 INFO - 1500808528757 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49794
04:15:28 INFO - 1500808528758 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:28 INFO - 1500808528758 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528761 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528765 Marionette TRACE 360 <- [1,1,null,{"sessionId":"bff42248-f87a-4656-9945-37b17bec0ab5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528766 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:15:28 INFO - 1500808528767 Marionette TRACE 360 <- [1,2,null,{}]
04:15:28 INFO - 1500808528768 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:28 INFO - 1500808528768 Marionette TRACE 360 <- [1,3,null,{}]
04:15:28 INFO - 1500808528769 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:28 INFO - 1500808528770 Marionette TRACE 360 <- [1,4,null,{}]
04:15:28 INFO - 1500808528772 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
04:15:28 INFO - TEST-START: test_click.py TestClick.test_container_element1500808528775 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:15:28 INFO - 1500808528776 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:15:28 INFO - 1500808528777 Marionette TRACE 360 <- [1,6,null,{}]
04:15:28 INFO - 1500808528779 Marionette DEBUG Closed connection 360
04:15:28 INFO - 1500808528780 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49795
04:15:28 INFO - 1500808528781 Marionette DEBUG Closed connection 361
04:15:28 INFO - 1500808528782 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49796
04:15:28 INFO - 1500808528783 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:28 INFO - 1500808528783 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528786 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528790 Marionette TRACE 362 <- [1,1,null,{"sessionId":"8d38a414-4d4a-46b7-80cd-f6bee490c240","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528791 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:15:28 INFO - 1500808528793 Marionette TRACE 362 <- [1,2,null,{}]
04:15:28 INFO - 1500808528794 Marionette DEBUG Closed connection 362
04:15:28 INFO - 1500808528795 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49797
04:15:28 INFO - 1500808528796 Marionette DEBUG Closed connection 363
04:15:28 INFO - 1500808528797 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49798
04:15:28 INFO - 1500808528798 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:28 INFO - 1500808528798 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:28 INFO - 1500808528801 Marionette DEBUG Register listener.js for window 4294967297
04:15:28 INFO - 1500808528805 Marionette TRACE 364 <- [1,1,null,{"sessionId":"b6eb701b-99a3-4b9b-972d-3affbc006e86","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:28 INFO - 1500808528806 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:15:28 INFO - 1500808528808 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/test.html"
04:15:28 INFO - 1500808528812 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/test.html"
04:15:28 INFO - 1500808528812 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/test.html"
04:15:28 INFO - 1500808528821 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:28 INFO - 1500808528829 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:28 INFO - 1500808528839 Marionette TRACE 364 <- [1,2,null,{}]
04:15:28 INFO - 1500808528849 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:15:28 INFO - 1500808528852 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d67329cd-b14c-4f53-a7a9-308477c373fe","ELEMENT":"d67329cd-b14c-4f53-a7a9-308477c373fe"}}]
04:15:28 INFO - 1500808528853 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d67329cd-b14c-4f53-a7a9-308477c373fe"}]
04:15:29 INFO - 1500808529098 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:29 INFO - 1500808529099 Marionette TRACE 364 <- [1,4,null,{}]
04:15:29 INFO - 1500808529101 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d67329cd-b14c-4f53-a7a9-308477c373fe","name":"selected"}]
04:15:29 INFO - 1500808529106 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:15:29 INFO - 1500808529108 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
04:15:29 INFO - TEST-END: test_click.py TestClick.test_container_element1500808529111 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:15:29 INFO - 1500808529112 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:15:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
04:15:29 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:15:29 INFO - 1500808529114 Marionette TRACE 364 <- [1,7,null,{}]
04:15:29 INFO - 1500808529115 Marionette DEBUG Closed connection 364
04:15:29 INFO - 1500808529116 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49799
04:15:29 INFO - 1500808529117 Marionette DEBUG Closed connection 365
04:15:29 INFO - 1500808529118 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49800
04:15:29 INFO - 1500808529119 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529119 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529122 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529126 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1f10d803-c984-4d44-a704-8effa3018c4d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529127 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:15:29 INFO - 1500808529128 Marionette TRACE 366 <- [1,2,null,{}]
04:15:29 INFO - 1500808529129 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:29 INFO - 1500808529129 Marionette TRACE 366 <- [1,3,null,{}]
04:15:29 INFO - 1500808529130 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:29 INFO - 1500808529131 Marionette TRACE 366 <- [1,4,null,{}]
04:15:29 INFO - 1500808529132 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
04:15:29 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500808529135 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:15:29 INFO - 1500808529136 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:15:29 INFO - 1500808529138 Marionette TRACE 366 <- [1,6,null,{}]
04:15:29 INFO - 1500808529140 Marionette DEBUG Closed connection 366
04:15:29 INFO - 1500808529140 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49801
04:15:29 INFO - 1500808529141 Marionette DEBUG Closed connection 367
04:15:29 INFO - 1500808529142 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49802
04:15:29 INFO - 1500808529143 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529144 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529147 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529150 Marionette TRACE 368 <- [1,1,null,{"sessionId":"dac09a45-3746-4f14-a75c-76e21045cdc5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529151 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:15:29 INFO - 1500808529153 Marionette TRACE 368 <- [1,2,null,{}]
04:15:29 INFO - 1500808529155 Marionette DEBUG Closed connection 368
04:15:29 INFO - 1500808529155 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49803
04:15:29 INFO - 1500808529156 Marionette DEBUG Closed connection 369
04:15:29 INFO - 1500808529157 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49804
04:15:29 INFO - 1500808529158 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:29 INFO - 1500808529159 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529161 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529172 Marionette TRACE 370 <- [1,1,null,{"sessionId":"11f5dfd9-aeba-41f7-afda-789226ccfbbc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529175 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:15:29 INFO - 1500808529177 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529180 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529180 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529191 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529200 Marionette TRACE 370 <- [1,2,null,{}]
04:15:29 INFO - 1500808529204 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:15:29 INFO - 1500808529213 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f56d4ac-9ca5-49a7-bc26-ee123c9faf8d","ELEMENT":"2f56d4ac-9ca5-49a7-bc26-ee123c9faf8d"}}]
04:15:29 INFO - 1500808529214 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"2f56d4ac-9ca5-49a7-bc26-ee123c9faf8d"}]
04:15:29 INFO - 1500808529459 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:29 INFO - 1500808529461 Marionette TRACE 370 <- [1,4,null,{}]
04:15:29 INFO - 1500808529462 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"2f56d4ac-9ca5-49a7-bc26-ee123c9faf8d","name":"selected"}]
04:15:29 INFO - 1500808529467 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:15:29 INFO - 1500808529468 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
04:15:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500808529472 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:15:29 INFO - 1500808529473 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:15:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 360ms
04:15:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:15:29 INFO - 1500808529474 Marionette TRACE 370 <- [1,7,null,{}]
04:15:29 INFO - 1500808529476 Marionette DEBUG Closed connection 370
04:15:29 INFO - 1500808529477 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49805
04:15:29 INFO - 1500808529478 Marionette DEBUG Closed connection 371
04:15:29 INFO - 1500808529479 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49806
04:15:29 INFO - 1500808529480 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529480 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529484 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529487 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9843a493-bc41-410b-b528-41863f635b46","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529489 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:15:29 INFO - 1500808529489 Marionette TRACE 372 <- [1,2,null,{}]
04:15:29 INFO - 1500808529490 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:29 INFO - 1500808529491 Marionette TRACE 372 <- [1,3,null,{}]
04:15:29 INFO - 1500808529492 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:29 INFO - 1500808529492 Marionette TRACE 372 <- [1,4,null,{}]
04:15:29 INFO - 1500808529494 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
04:15:29 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500808529497 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:15:29 INFO - 1500808529498 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:15:29 INFO - 1500808529499 Marionette TRACE 372 <- [1,6,null,{}]
04:15:29 INFO - 1500808529501 Marionette DEBUG Closed connection 372
04:15:29 INFO - 1500808529502 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49807
04:15:29 INFO - 1500808529502 Marionette DEBUG Closed connection 373
04:15:29 INFO - 1500808529503 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49808
04:15:29 INFO - 1500808529504 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529505 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529508 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529511 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ad05a715-1d8c-4c32-9a06-aaa5554292aa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529512 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:15:29 INFO - 1500808529514 Marionette TRACE 374 <- [1,2,null,{}]
04:15:29 INFO - 1500808529515 Marionette DEBUG Closed connection 374
04:15:29 INFO - 1500808529516 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49809
04:15:29 INFO - 1500808529517 Marionette DEBUG Closed connection 375
04:15:29 INFO - 1500808529518 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49810
04:15:29 INFO - 1500808529519 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:29 INFO - 1500808529519 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529522 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529526 Marionette TRACE 376 <- [1,1,null,{"sessionId":"50593bd5-c741-43cf-9475-f93f59de9489","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529527 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
04:15:29 INFO - 1500808529529 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529531 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529532 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529542 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:15:29 INFO - 1500808529543 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:15:29 INFO - 1500808529554 Marionette TRACE 376 <- [1,2,null,{}]
04:15:29 INFO - 1500808529563 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:15:29 INFO - 1500808529566 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f07167e3-6a29-4ed1-b060-9ac1fff2c960","ELEMENT":"f07167e3-6a29-4ed1-b060-9ac1fff2c960"}}]
04:15:29 INFO - 1500808529567 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f07167e3-6a29-4ed1-b060-9ac1fff2c960"}]
04:15:29 INFO - 1500808529814 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:29 INFO - 1500808529815 Marionette TRACE 376 <- [1,4,null,{}]
04:15:29 INFO - 1500808529818 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
04:15:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500808529821 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:15:29 INFO - 1500808529822 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:15:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
04:15:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:15:29 INFO - 1500808529823 Marionette TRACE 376 <- [1,6,null,{}]
04:15:29 INFO - 1500808529825 Marionette DEBUG Closed connection 376
04:15:29 INFO - 1500808529826 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49811
04:15:29 INFO - 1500808529827 Marionette DEBUG Closed connection 377
04:15:29 INFO - 1500808529828 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49812
04:15:29 INFO - 1500808529829 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529829 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529832 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529836 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f033c652-5b5d-4dbf-9d7a-354903502ee9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529837 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:15:29 INFO - 1500808529837 Marionette TRACE 378 <- [1,2,null,{}]
04:15:29 INFO - 1500808529838 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:29 INFO - 1500808529839 Marionette TRACE 378 <- [1,3,null,{}]
04:15:29 INFO - 1500808529840 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:29 INFO - 1500808529841 Marionette TRACE 378 <- [1,4,null,{}]
04:15:29 INFO - 1500808529842 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
04:15:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500808529845 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:15:29 INFO - 1500808529846 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:15:29 INFO - 1500808529848 Marionette TRACE 378 <- [1,6,null,{}]
04:15:29 INFO - 1500808529850 Marionette DEBUG Closed connection 378
04:15:29 INFO - 1500808529850 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49813
04:15:29 INFO - 1500808529851 Marionette DEBUG Closed connection 379
04:15:29 INFO - 1500808529852 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49814
04:15:29 INFO - 1500808529853 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:29 INFO - 1500808529854 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529857 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529860 Marionette TRACE 380 <- [1,1,null,{"sessionId":"718f3589-7b16-49ee-a856-ed00d0adc848","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529862 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:15:29 INFO - 1500808529863 Marionette TRACE 380 <- [1,2,null,{}]
04:15:29 INFO - 1500808529865 Marionette DEBUG Closed connection 380
04:15:29 INFO - 1500808529865 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49815
04:15:29 INFO - 1500808529866 Marionette DEBUG Closed connection 381
04:15:29 INFO - 1500808529867 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49816
04:15:29 INFO - 1500808529868 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:29 INFO - 1500808529869 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:29 INFO - 1500808529871 Marionette DEBUG Register listener.js for window 4294967297
04:15:29 INFO - 1500808529875 Marionette TRACE 382 <- [1,1,null,{"sessionId":"35111453-3bd5-487e-9c72-2c7ce957b3e2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:29 INFO - 1500808529876 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
04:15:29 INFO - 1500808529878 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:15:29 INFO - 1500808529880 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:15:29 INFO - 1500808529881 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
04:15:29 INFO - 1500808529888 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529892 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:29 INFO - 1500808529900 Marionette TRACE 382 <- [1,2,null,{}]
04:15:29 INFO - 1500808529910 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:15:29 INFO - 1500808529913 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ca7ea0dd-5f4e-49c7-acbf-fd9eaf6f812d","ELEMENT":"ca7ea0dd-5f4e-49c7-acbf-fd9eaf6f812d"}}]
04:15:29 INFO - 1500808529914 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ca7ea0dd-5f4e-49c7-acbf-fd9eaf6f812d"}]
04:15:30 INFO - 1500808530158 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:30 INFO - 1500808530159 Marionette TRACE 382 <- [1,4,null,{}]
04:15:30 INFO - 1500808530161 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
04:15:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500808530164 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:15:30 INFO - 1500808530166 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:15:30 INFO - 1500808530167 Marionette TRACE 382 <- [1,6,null,{}]
04:15:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
04:15:30 INFO - TEST-START | test_click.py TestClick.test_input_file
04:15:30 INFO - 1500808530169 Marionette DEBUG Closed connection 382
04:15:30 INFO - 1500808530170 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49817
04:15:30 INFO - 1500808530171 Marionette DEBUG Closed connection 383
04:15:30 INFO - 1500808530171 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49818
04:15:30 INFO - 1500808530172 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530173 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530176 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530180 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3ec184ac-2567-421b-8d15-5bb82ad76795","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530181 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:15:30 INFO - 1500808530181 Marionette TRACE 384 <- [1,2,null,{}]
04:15:30 INFO - 1500808530182 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:30 INFO - 1500808530183 Marionette TRACE 384 <- [1,3,null,{}]
04:15:30 INFO - 1500808530184 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:30 INFO - 1500808530184 Marionette TRACE 384 <- [1,4,null,{}]
04:15:30 INFO - 1500808530186 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
04:15:30 INFO - TEST-START: test_click.py TestClick.test_input_file1500808530189 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:15:30 INFO - 1500808530190 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:15:30 INFO - 1500808530191 Marionette TRACE 384 <- [1,6,null,{}]
04:15:30 INFO - 1500808530193 Marionette DEBUG Closed connection 384
04:15:30 INFO - 1500808530194 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49819
04:15:30 INFO - 1500808530195 Marionette DEBUG Closed connection 385
04:15:30 INFO - 1500808530195 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49820
04:15:30 INFO - 1500808530196 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530197 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530200 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530203 Marionette TRACE 386 <- [1,1,null,{"sessionId":"961e76d5-7802-456d-9094-077a52891017","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530204 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:15:30 INFO - 1500808530206 Marionette TRACE 386 <- [1,2,null,{}]
04:15:30 INFO - 1500808530208 Marionette DEBUG Closed connection 386
04:15:30 INFO - 1500808530208 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49821
04:15:30 INFO - 1500808530209 Marionette DEBUG Closed connection 387
04:15:30 INFO - 1500808530210 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49822
04:15:30 INFO - 1500808530211 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:30 INFO - 1500808530212 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530214 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530218 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f91ed061-859f-476e-8b9b-d84a64ae2881","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530219 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:15:30 INFO - 1500808530221 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:30 INFO - 1500808530223 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:30 INFO - 1500808530224 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
04:15:30 INFO - 1500808530231 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:30 INFO - 1500808530236 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:30 INFO - 1500808530238 Marionette TRACE 388 <- [1,2,null,{}]
04:15:30 INFO - 1500808530249 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:15:30 INFO - 1500808530252 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f610c83-c9f8-45da-9acf-b78e60873f86","ELEMENT":"3f610c83-c9f8-45da-9acf-b78e60873f86"}}]
04:15:30 INFO - 1500808530253 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3f610c83-c9f8-45da-9acf-b78e60873f86"}]
04:15:30 INFO - 1500808530281 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:15:30 INFO - 1500808530283 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
04:15:30 INFO - TEST-END: test_click.py TestClick.test_input_file1500808530286 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:15:30 INFO - 1500808530287 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:15:30 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
04:15:30 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:15:30 INFO - 1500808530288 Marionette TRACE 388 <- [1,6,null,{}]
04:15:30 INFO - 1500808530290 Marionette DEBUG Closed connection 388
04:15:30 INFO - 1500808530291 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49823
04:15:30 INFO - 1500808530292 Marionette DEBUG Closed connection 389
04:15:30 INFO - 1500808530293 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49824
04:15:30 INFO - 1500808530294 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530294 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530297 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530301 Marionette TRACE 390 <- [1,1,null,{"sessionId":"4d43e002-7ec9-4f14-923b-8f48fcd924f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530302 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:15:30 INFO - 1500808530303 Marionette TRACE 390 <- [1,2,null,{}]
04:15:30 INFO - 1500808530304 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:30 INFO - 1500808530304 Marionette TRACE 390 <- [1,3,null,{}]
04:15:30 INFO - 1500808530305 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:30 INFO - 1500808530306 Marionette TRACE 390 <- [1,4,null,{}]
04:15:30 INFO - 1500808530307 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
04:15:30 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500808530310 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:15:30 INFO - 1500808530311 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:15:30 INFO - 1500808530313 Marionette TRACE 390 <- [1,6,null,{}]
04:15:30 INFO - 1500808530314 Marionette DEBUG Closed connection 390
04:15:30 INFO - 1500808530315 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49825
04:15:30 INFO - 1500808530316 Marionette DEBUG Closed connection 391
04:15:30 INFO - 1500808530317 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49826
04:15:30 INFO - 1500808530318 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530318 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530321 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530325 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0781685e-456d-474d-a123-ad0fa167dcc5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530326 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:15:30 INFO - 1500808530327 Marionette TRACE 392 <- [1,2,null,{}]
04:15:30 INFO - 1500808530329 Marionette DEBUG Closed connection 392
04:15:30 INFO - 1500808530329 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49827
04:15:30 INFO - 1500808530330 Marionette DEBUG Closed connection 393
04:15:30 INFO - 1500808530331 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49828
04:15:30 INFO - 1500808530332 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:30 INFO - 1500808530333 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530336 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530339 Marionette TRACE 394 <- [1,1,null,{"sessionId":"110f42e8-41b9-4633-806d-51989ee2d7dc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530340 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
04:15:30 INFO - 1500808530342 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:30 INFO - 1500808530344 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:30 INFO - 1500808530345 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:15:30 INFO - 1500808530352 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:15:30 INFO - 1500808530355 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:15:30 INFO - 1500808530365 Marionette TRACE 394 <- [1,2,null,{}]
04:15:30 INFO - 1500808530369 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:15:30 INFO - 1500808530372 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b96ceaa-216b-4056-9c2e-75256da0b1cf","ELEMENT":"0b96ceaa-216b-4056-9c2e-75256da0b1cf"}}]
04:15:30 INFO - 1500808530378 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:15:30 INFO - 1500808530381 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9bfcbfa1-8840-4665-9f68-8df5c2aec351","ELEMENT":"9bfcbfa1-8840-4665-9f68-8df5c2aec351"}}]
04:15:30 INFO - 1500808530382 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"0b96ceaa-216b-4056-9c2e-75256da0b1cf"}]
04:15:30 INFO - 1500808530627 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:15:30 INFO - 1500808530628 Marionette TRACE 394 <- [1,5,null,{}]
04:15:30 INFO - 1500808530630 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9bfcbfa1-8840-4665-9f68-8df5c2aec351"}]
04:15:30 INFO - 1500808530648 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:15:30 INFO - 1500808530650 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:15:30 INFO - 1500808530654 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:15:30 INFO - 1500808530655 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
04:15:30 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500808530658 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:15:30 INFO - 1500808530659 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:15:30 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 372ms
04:15:30 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:15:30 INFO - 1500808530661 Marionette TRACE 394 <- [1,9,null,{}]
04:15:30 INFO - 1500808530663 Marionette DEBUG Closed connection 394
04:15:30 INFO - 1500808530664 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49829
04:15:30 INFO - 1500808530665 Marionette DEBUG Closed connection 395
04:15:30 INFO - 1500808530665 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49830
04:15:30 INFO - 1500808530666 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530667 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530670 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530674 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e8dab3e6-133a-4a94-b79d-d7a5404ebf46","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530675 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:15:30 INFO - 1500808530676 Marionette TRACE 396 <- [1,2,null,{}]
04:15:30 INFO - 1500808530677 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:15:30 INFO - 1500808530677 Marionette TRACE 396 <- [1,3,null,{}]
04:15:30 INFO - 1500808530678 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:15:30 INFO - 1500808530679 Marionette TRACE 396 <- [1,4,null,{}]
04:15:30 INFO - 1500808530680 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
04:15:30 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500808530683 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:15:30 INFO - 1500808530684 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:15:30 INFO - 1500808530686 Marionette TRACE 396 <- [1,6,null,{}]
04:15:30 INFO - 1500808530688 Marionette DEBUG Closed connection 396
04:15:30 INFO - 1500808530689 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49831
04:15:30 INFO - 1500808530689 Marionette DEBUG Closed connection 397
04:15:30 INFO - 1500808530690 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49832
04:15:30 INFO - 1500808530691 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:15:30 INFO - 1500808530692 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530695 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530698 Marionette TRACE 398 <- [1,1,null,{"sessionId":"289fd62a-2b6b-4297-a302-c49894750049","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530699 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:15:30 INFO - 1500808530701 Marionette TRACE 398 <- [1,2,null,{}]
04:15:30 INFO - 1500808530703 Marionette DEBUG Closed connection 398
04:15:30 INFO - 1500808530703 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49833
04:15:30 INFO - 1500808530704 Marionette DEBUG Closed connection 399
04:15:30 INFO - 1500808530705 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49834
04:15:30 INFO - 1500808530708 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:15:30 INFO - 1500808530709 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}
04:15:30 INFO - 1500808530711 Marionette DEBUG Register listener.js for window 4294967297
04:15:30 INFO - 1500808530715 Marionette TRACE 400 <- [1,1,null,{"sessionId":"44f596de-3170-42b6-b990-dbedc2bb325e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":5516,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxbf31m.mozrunner","moz:accessibilityChecks":false}}]
04:15:30 INFO - 1500808530716 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
04:15:30 INFO - 1500808530718 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:15:30 INFO - 1500808530720 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:15:30 INFO - 1500808530721 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
04:15:30 INFO - 1500808530729 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:15:30 INFO - 1500808530731 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
04:15:30 INFO - 1500808530736 Marionette TRACE 400 <- [1,2,null,{}]
04:15:30 INFO - 1500808530749 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:15:30 INFO - 1500808530752 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b7d6f06-4de0-4446-a36c-994b5c396936","ELEMENT":"6b7d6f06-4de0-4446-a36c-994b5c396936"}}]
04:15:30 INFO - 1500808530753 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6b7d6f06-4de0-4446-a36c-994b5c396936"}]
04:15:30 INFO - 1500808530776 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:15:30 INFO - 1500808530777 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6b7d6f06-4de0-4446-a36c-994b5c396936"}]
04:15:30 INFO - 1500808530796 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element