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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:44:49 INFO - 1500929089446 Marionette TRACE 353 -> [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}]
13:44:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500929089453 Marionette TRACE 353 <- [1,5,null,{"value":null}]
13:44:49 INFO - 1500929089455 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089459 Marionette TRACE 353 <- [1,6,null,{}]
13:44:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 570ms
13:44:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089465 Marionette DEBUG Closed connection 353
13:44:49 INFO - 1500929089466 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49783
13:44:49 INFO - 1500929089468 Marionette DEBUG Closed connection 354
13:44:49 INFO - 1500929089469 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49784
13:44:49 INFO - 1500929089472 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:49 INFO - 1500929089474 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:49 INFO - 1500929089496 Marionette DEBUG Register listener.js for window 12
13:44:49 INFO - 1500929089507 Marionette TRACE 355 <- [1,1,null,{"sessionId":"674dfa2f-ae19-4e81-a462-115977010163","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:49 INFO - 1500929089513 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
13:44:49 INFO - 1500929089515 Marionette TRACE 355 <- [1,2,null,{}]
13:44:49 INFO - 1500929089517 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:49 INFO - 1500929089518 Marionette TRACE 355 <- [1,3,null,{}]
13:44:49 INFO - 1500929089520 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:49 INFO - 1500929089521 Marionette TRACE 355 <- [1,4,null,{}]
13:44:49 INFO - 1500929089524 Marionette TRACE 355 -> [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}]
13:44:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500929089534 Marionette TRACE 355 <- [1,5,null,{"value":null}]
13:44:49 INFO - 1500929089536 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089541 Marionette TRACE 355 <- [1,6,null,{}]
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089546 Marionette DEBUG Closed connection 355
13:44:49 INFO - 1500929089548 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49785
13:44:49 INFO - 1500929089549 Marionette DEBUG Closed connection 356
13:44:49 INFO - 1500929089551 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49786
13:44:49 INFO - 1500929089553 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:49 INFO - 1500929089555 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:49 INFO - 1500929089577 Marionette DEBUG Register listener.js for window 12
13:44:49 INFO - 1500929089588 Marionette TRACE 357 <- [1,1,null,{"sessionId":"ad2bb712-9cde-4331-8ff2-c2334bdc0b05","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:49 INFO - 1500929089594 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089598 Marionette TRACE 357 <- [1,2,null,{}]
13:44:49 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:49 INFO - 1500929089604 Marionette DEBUG Closed connection 357
13:44:49 INFO - 1500929089605 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49787
13:44:49 INFO - 1500929089607 Marionette DEBUG Closed connection 358
13:44:49 INFO - 1500929089608 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
13:44:49 INFO - 1500929089610 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:49 INFO - 1500929089613 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:49 INFO - 1500929089635 Marionette DEBUG Register listener.js for window 12
13:44:49 INFO - 1500929089646 Marionette TRACE 359 <- [1,1,null,{"sessionId":"7d0282fd-c30b-4f79-bd26-5ae57d0372fc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:49 INFO - 1500929089652 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49334/clicks.html"}]
13:44:49 INFO - 1500929089655 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/hidden.html"
13:44:49 INFO - 1500929089675 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/hidden.html"
13:44:49 INFO - 1500929089676 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/hidden.html"
13:44:49 INFO - ++DOMWINDOW == 22 (00000094FC5D3800) [pid = 688] [serial = 41] [outer = 00000094F2D2D000]
13:44:49 INFO - 1500929089732 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/clicks.html"
13:44:49 INFO - 1500929089738 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/clicks.html"
13:44:49 INFO - 1500929089757 Marionette TRACE 359 <- [1,2,null,{}]
13:44:49 INFO - 1500929089759 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:44:49 INFO - 1500929089766 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f650704b-8eb2-4643-b1f4-4cfad94b8c14","ELEMENT":"f650704b-8eb2-4643-b1f4-4cfad94b8c14"}}]
13:44:49 INFO - 1500929089768 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"f650704b-8eb2-4643-b1f4-4cfad94b8c14"}]
13:44:50 INFO - 1500929090014 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/clicks.html"
13:44:50 INFO - [Parent 688] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
13:44:50 INFO - 1500929090034 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/clicks.html"
13:44:50 INFO - 1500929090036 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/clicks.html"
13:44:50 INFO - ++DOMWINDOW == 23 (00000094F9D85000) [pid = 688] [serial = 42] [outer = 00000094F2D2D000]
13:44:50 INFO - 1500929090096 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/test.html"
13:44:50 INFO - 1500929090101 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/test.html"
13:44:50 INFO - 1500929090117 Marionette TRACE 359 <- [1,4,null,{}]
13:44:50 INFO - 1500929090592 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:44:50 INFO - 1500929090599 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"66b13031-1fa5-422c-b362-7717332307c0","ELEMENT":"66b13031-1fa5-422c-b362-7717332307c0"}}]
13:44:50 INFO - 1500929090601 Marionette TRACE 359 -> [0,6,"getTitle",{}]
13:44:50 INFO - 1500929090603 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
13:44:50 INFO - 1500929090605 Marionette TRACE 359 -> [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}]
13:44:50 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500929090612 Marionette TRACE 359 <- [1,7,null,{"value":null}]
13:44:50 INFO - 1500929090614 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090619 Marionette TRACE 359 <- [1,8,null,{}]
13:44:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1160ms
13:44:50 INFO - TEST-START | test_click.py TestClick.test_container_element
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090625 Marionette DEBUG Closed connection 359
13:44:50 INFO - 1500929090626 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49791
13:44:50 INFO - 1500929090628 Marionette DEBUG Closed connection 360
13:44:50 INFO - 1500929090629 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49792
13:44:50 INFO - 1500929090631 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:50 INFO - 1500929090633 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:50 INFO - 1500929090656 Marionette DEBUG Register listener.js for window 12
13:44:50 INFO - 1500929090679 Marionette TRACE 361 <- [1,1,null,{"sessionId":"62cdd286-1059-41a9-ba86-5a7b6ed79a21","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:50 INFO - 1500929090689 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
13:44:50 INFO - 1500929090690 Marionette TRACE 361 <- [1,2,null,{}]
13:44:50 INFO - 1500929090693 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:50 INFO - 1500929090694 Marionette TRACE 361 <- [1,3,null,{}]
13:44:50 INFO - 1500929090722 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:50 INFO - 1500929090724 Marionette TRACE 361 <- [1,4,null,{}]
13:44:50 INFO - 1500929090814 Marionette TRACE 361 -> [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}]
13:44:50 INFO - TEST-START: test_click.py TestClick.test_container_element1500929090824 Marionette TRACE 361 <- [1,5,null,{"value":null}]
13:44:50 INFO - 1500929090827 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090835 Marionette TRACE 361 <- [1,6,null,{}]
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090841 Marionette DEBUG Closed connection 361
13:44:50 INFO - 1500929090843 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49793
13:44:50 INFO - 1500929090844 Marionette DEBUG Closed connection 362
13:44:50 INFO - 1500929090846 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49794
13:44:50 INFO - 1500929090849 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:50 INFO - 1500929090852 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:50 INFO - 1500929090875 Marionette DEBUG Register listener.js for window 12
13:44:50 INFO - 1500929090886 Marionette TRACE 363 <- [1,1,null,{"sessionId":"847fb2be-9050-4519-a644-012cfb486a4e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:50 INFO - 1500929090921 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090925 Marionette TRACE 363 <- [1,2,null,{}]
13:44:50 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:50 INFO - 1500929090933 Marionette DEBUG Closed connection 363
13:44:50 INFO - 1500929090935 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49795
13:44:50 INFO - 1500929090955 Marionette DEBUG Closed connection 364
13:44:50 INFO - 1500929090956 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49796
13:44:50 INFO - 1500929090959 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:50 INFO - 1500929090964 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:50 INFO - 1500929090988 Marionette DEBUG Register listener.js for window 12
13:44:51 INFO - 1500929090999 Marionette TRACE 365 <- [1,1,null,{"sessionId":"594b778f-a2fa-4650-b466-d67ab1bbcd2e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:51 INFO - 1500929091009 Marionette TRACE 365 -> [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"}]
13:44:51 INFO - 1500929091014 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/test.html"
13:44:51 INFO - 1500929091019 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/test.html"
13:44:51 INFO - 1500929091021 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/test.html"
13:44:51 INFO - ++DOMWINDOW == 24 (00000094F9D88800) [pid = 688] [serial = 43] [outer = 00000094F2D2D000]
13:44:51 INFO - 1500929091068 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"
13:44:51 INFO - 1500929091083 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"
13:44:51 INFO - 1500929091090 Marionette TRACE 365 <- [1,2,null,{}]
13:44:51 INFO - 1500929091099 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:44:51 INFO - 1500929091109 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"465fd2fc-b034-4867-ba9c-83ee2b0b4c8e","ELEMENT":"465fd2fc-b034-4867-ba9c-83ee2b0b4c8e"}}]
13:44:51 INFO - 1500929091117 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"465fd2fc-b034-4867-ba9c-83ee2b0b4c8e"}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:44:51 INFO - 1500929091554 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:44:51 INFO - 1500929091556 Marionette TRACE 365 <- [1,4,null,{}]
13:44:51 INFO - 1500929091558 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"465fd2fc-b034-4867-ba9c-83ee2b0b4c8e","name":"selected"}]
13:44:51 INFO - 1500929091607 Marionette TRACE 365 <- [1,5,null,{"value":true}]
13:44:51 INFO - 1500929091617 Marionette TRACE 365 -> [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}]
13:44:51 INFO - TEST-END: test_click.py TestClick.test_container_element1500929091625 Marionette TRACE 365 <- [1,6,null,{"value":null}]
13:44:51 INFO - 1500929091628 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091632 Marionette TRACE 365 <- [1,7,null,{}]
13:44:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1014ms
13:44:51 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091655 Marionette DEBUG Closed connection 365
13:44:51 INFO - 1500929091657 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49797
13:44:51 INFO - 1500929091660 Marionette DEBUG Closed connection 366
13:44:51 INFO - 1500929091662 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49798
13:44:51 INFO - 1500929091664 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:51 INFO - 1500929091666 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:51 INFO - 1500929091689 Marionette DEBUG Register listener.js for window 12
13:44:51 INFO - 1500929091700 Marionette TRACE 367 <- [1,1,null,{"sessionId":"29a405d0-a089-4f40-b936-05d4a6e7a223","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:51 INFO - 1500929091706 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
13:44:51 INFO - 1500929091707 Marionette TRACE 367 <- [1,2,null,{}]
13:44:51 INFO - 1500929091709 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:51 INFO - 1500929091711 Marionette TRACE 367 <- [1,3,null,{}]
13:44:51 INFO - 1500929091712 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:51 INFO - 1500929091714 Marionette TRACE 367 <- [1,4,null,{}]
13:44:51 INFO - 1500929091717 Marionette TRACE 367 -> [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}]
13:44:51 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500929091724 Marionette TRACE 367 <- [1,5,null,{"value":null}]
13:44:51 INFO - 1500929091726 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091730 Marionette TRACE 367 <- [1,6,null,{}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091736 Marionette DEBUG Closed connection 367
13:44:51 INFO - 1500929091738 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49799
13:44:51 INFO - 1500929091767 Marionette DEBUG Closed connection 368
13:44:51 INFO - 1500929091768 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49800
13:44:51 INFO - 1500929091772 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:51 INFO - 1500929091774 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:51 INFO - 1500929091797 Marionette DEBUG Register listener.js for window 12
13:44:51 INFO - 1500929091808 Marionette TRACE 369 <- [1,1,null,{"sessionId":"2edfd48c-92ea-4838-bcb5-1a761ad8401b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:51 INFO - 1500929091817 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091821 Marionette TRACE 369 <- [1,2,null,{}]
13:44:51 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:51 INFO - 1500929091844 Marionette DEBUG Closed connection 369
13:44:51 INFO - 1500929091846 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49801
13:44:51 INFO - 1500929091850 Marionette DEBUG Closed connection 370
13:44:51 INFO - 1500929091851 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49802
13:44:51 INFO - 1500929091857 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:51 INFO - 1500929091860 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:51 INFO - 1500929091883 Marionette DEBUG Register listener.js for window 12
13:44:51 INFO - 1500929091904 Marionette TRACE 371 <- [1,1,null,{"sessionId":"248cebc2-99ec-4665-8cb1-1532912ccbcb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:51 INFO - 1500929091913 Marionette TRACE 371 -> [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"}]
13:44:51 INFO - 1500929091917 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"
13:44:51 INFO - 1500929091923 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"
13:44:51 INFO - 1500929091924 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"
13:44:51 INFO - ++DOMWINDOW == 25 (00000094F9D90800) [pid = 688] [serial = 44] [outer = 00000094F2D2D000]
13:44:51 INFO - 1500929091968 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"
13:44:51 INFO - 1500929091982 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"
13:44:51 INFO - 1500929091990 Marionette TRACE 371 <- [1,2,null,{}]
13:44:52 INFO - 1500929092001 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:44:52 INFO - 1500929092014 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b015bc3f-5ac4-4d03-a872-614509bf7f63","ELEMENT":"b015bc3f-5ac4-4d03-a872-614509bf7f63"}}]
13:44:52 INFO - 1500929092016 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"b015bc3f-5ac4-4d03-a872-614509bf7f63"}]
13:44:52 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:44:52 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:44:52 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:44:53 INFO - 1500929093304 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:44:53 INFO - 1500929093306 Marionette TRACE 371 <- [1,4,null,{}]
13:44:53 INFO - 1500929093308 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"b015bc3f-5ac4-4d03-a872-614509bf7f63","name":"selected"}]
13:44:53 INFO - 1500929093337 Marionette TRACE 371 <- [1,5,null,{"value":true}]
13:44:53 INFO - 1500929093342 Marionette TRACE 371 -> [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}]
13:44:53 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500929093349 Marionette TRACE 371 <- [1,6,null,{"value":null}]
13:44:53 INFO - 1500929093351 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093356 Marionette TRACE 371 <- [1,7,null,{}]
13:44:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1722ms
13:44:53 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093386 Marionette DEBUG Closed connection 371
13:44:53 INFO - 1500929093388 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49803
13:44:53 INFO - 1500929093390 Marionette DEBUG Closed connection 372
13:44:53 INFO - 1500929093391 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49804
13:44:53 INFO - 1500929093393 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:53 INFO - 1500929093395 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:53 INFO - 1500929093418 Marionette DEBUG Register listener.js for window 12
13:44:53 INFO - 1500929093429 Marionette TRACE 373 <- [1,1,null,{"sessionId":"47bc84b1-d290-414f-9552-27a64b0036ba","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:53 INFO - 1500929093441 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
13:44:53 INFO - 1500929093443 Marionette TRACE 373 <- [1,2,null,{}]
13:44:53 INFO - 1500929093444 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:53 INFO - 1500929093446 Marionette TRACE 373 <- [1,3,null,{}]
13:44:53 INFO - 1500929093447 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:53 INFO - 1500929093449 Marionette TRACE 373 <- [1,4,null,{}]
13:44:53 INFO - 1500929093451 Marionette TRACE 373 -> [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}]
13:44:53 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500929093458 Marionette TRACE 373 <- [1,5,null,{"value":null}]
13:44:53 INFO - 1500929093460 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093464 Marionette TRACE 373 <- [1,6,null,{}]
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093470 Marionette DEBUG Closed connection 373
13:44:53 INFO - 1500929093472 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49805
13:44:53 INFO - 1500929093473 Marionette DEBUG Closed connection 374
13:44:53 INFO - 1500929093475 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49806
13:44:53 INFO - 1500929093476 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:53 INFO - 1500929093479 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:53 INFO - 1500929093502 Marionette DEBUG Register listener.js for window 12
13:44:53 INFO - 1500929093513 Marionette TRACE 375 <- [1,1,null,{"sessionId":"a91d4054-c077-41e6-af1b-8f38f5774692","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:53 INFO - 1500929093518 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093523 Marionette TRACE 375 <- [1,2,null,{}]
13:44:53 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:53 INFO - 1500929093528 Marionette DEBUG Closed connection 375
13:44:53 INFO - 1500929093530 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49807
13:44:53 INFO - 1500929093531 Marionette DEBUG Closed connection 376
13:44:53 INFO - 1500929093533 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49808
13:44:53 INFO - 1500929093535 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:53 INFO - 1500929093537 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:53 INFO - 1500929093562 Marionette DEBUG Register listener.js for window 12
13:44:53 INFO - 1500929093573 Marionette TRACE 377 <- [1,1,null,{"sessionId":"997d15ac-448d-42e1-9628-e3304070d26d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:53 INFO - 1500929093579 Marionette TRACE 377 -> [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"}]
13:44:53 INFO - 1500929093582 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"
13:44:53 INFO - 1500929093593 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"
13:44:53 INFO - 1500929093594 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"
13:44:53 INFO - ++DOMWINDOW == 26 (00000094F8160000) [pid = 688] [serial = 45] [outer = 00000094F2D2D000]
13:44:53 INFO - 1500929093732 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"
13:44:53 INFO - 1500929093745 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"
13:44:53 INFO - 1500929093764 Marionette TRACE 377 <- [1,2,null,{}]
13:44:53 INFO - 1500929093767 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:44:53 INFO - 1500929093775 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7cd98e20-043a-4c5b-a6b9-c0b607e34c30","ELEMENT":"7cd98e20-043a-4c5b-a6b9-c0b607e34c30"}}]
13:44:53 INFO - 1500929093777 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"7cd98e20-043a-4c5b-a6b9-c0b607e34c30"}]
13:44:54 INFO - 1500929094222 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:44:54 INFO - 1500929094224 Marionette TRACE 377 <- [1,4,null,{}]
13:44:54 INFO - 1500929094226 Marionette TRACE 377 -> [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}]
13:44:54 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500929094233 Marionette TRACE 377 <- [1,5,null,{"value":null}]
13:44:54 INFO - 1500929094235 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094242 Marionette TRACE 377 <- [1,6,null,{}]
13:44:54 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 887ms
13:44:54 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094269 Marionette DEBUG Closed connection 377
13:44:54 INFO - 1500929094271 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49809
13:44:54 INFO - 1500929094272 Marionette DEBUG Closed connection 378
13:44:54 INFO - 1500929094274 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49810
13:44:54 INFO - 1500929094276 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:54 INFO - 1500929094278 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:54 INFO - 1500929094301 Marionette DEBUG Register listener.js for window 12
13:44:54 INFO - 1500929094312 Marionette TRACE 379 <- [1,1,null,{"sessionId":"f3e24474-79f7-4dc6-bb70-02944d08664c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:54 INFO - 1500929094324 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
13:44:54 INFO - 1500929094326 Marionette TRACE 379 <- [1,2,null,{}]
13:44:54 INFO - 1500929094328 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:54 INFO - 1500929094329 Marionette TRACE 379 <- [1,3,null,{}]
13:44:54 INFO - 1500929094331 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:54 INFO - 1500929094332 Marionette TRACE 379 <- [1,4,null,{}]
13:44:54 INFO - 1500929094335 Marionette TRACE 379 -> [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}]
13:44:54 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500929094343 Marionette TRACE 379 <- [1,5,null,{"value":null}]
13:44:54 INFO - 1500929094344 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094349 Marionette TRACE 379 <- [1,6,null,{}]
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094355 Marionette DEBUG Closed connection 379
13:44:54 INFO - 1500929094356 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49811
13:44:54 INFO - 1500929094358 Marionette DEBUG Closed connection 380
13:44:54 INFO - 1500929094360 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49812
13:44:54 INFO - 1500929094362 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:54 INFO - 1500929094364 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:54 INFO - 1500929094388 Marionette DEBUG Register listener.js for window 12
13:44:54 INFO - 1500929094399 Marionette TRACE 381 <- [1,1,null,{"sessionId":"e1163e6e-07c1-41d2-af86-d5e45f068399","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:54 INFO - 1500929094404 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094408 Marionette TRACE 381 <- [1,2,null,{}]
13:44:54 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:54 INFO - 1500929094414 Marionette DEBUG Closed connection 381
13:44:54 INFO - 1500929094416 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49813
13:44:54 INFO - 1500929094417 Marionette DEBUG Closed connection 382
13:44:54 INFO - 1500929094419 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49814
13:44:54 INFO - 1500929094421 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:54 INFO - 1500929094423 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:54 INFO - 1500929094447 Marionette DEBUG Register listener.js for window 12
13:44:54 INFO - 1500929094457 Marionette TRACE 383 <- [1,1,null,{"sessionId":"df9da598-2955-423d-9061-8c407505c487","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:54 INFO - 1500929094463 Marionette TRACE 383 -> [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"}]
13:44:54 INFO - 1500929094467 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"
13:44:54 INFO - 1500929094473 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"
13:44:54 INFO - 1500929094474 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"
13:44:54 INFO - ++DOMWINDOW == 27 (00000094F99C9000) [pid = 688] [serial = 46] [outer = 00000094F2D2D000]
13:44:54 INFO - 1500929094518 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"
13:44:54 INFO - 1500929094577 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"
13:44:54 INFO - 1500929094593 Marionette TRACE 383 <- [1,2,null,{}]
13:44:54 INFO - 1500929094610 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:44:54 INFO - 1500929094616 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5dfa8c7a-d069-40be-b8bb-989c440773de","ELEMENT":"5dfa8c7a-d069-40be-b8bb-989c440773de"}}]
13:44:54 INFO - 1500929094618 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"5dfa8c7a-d069-40be-b8bb-989c440773de"}]
13:44:55 INFO - 1500929095056 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:44:55 INFO - 1500929095059 Marionette TRACE 383 <- [1,4,null,{}]
13:44:55 INFO - 1500929095065 Marionette TRACE 383 -> [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}]
13:44:55 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500929095082 Marionette TRACE 383 <- [1,5,null,{"value":null}]
13:44:55 INFO - 1500929095086 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 853ms
13:44:55 INFO - TEST-START | test_click.py TestClick.test_input_file
13:44:55 INFO - 1500929095096 Marionette TRACE 383 <- [1,6,null,{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - 1500929095106 Marionette DEBUG Closed connection 383
13:44:55 INFO - 1500929095109 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49815
13:44:55 INFO - 1500929095112 Marionette DEBUG Closed connection 384
13:44:55 INFO - 1500929095114 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49816
13:44:55 INFO - 1500929095120 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:55 INFO - 1500929095131 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:55 INFO - 1500929095180 Marionette DEBUG Register listener.js for window 12
13:44:55 INFO - 1500929095213 Marionette TRACE 385 <- [1,1,null,{"sessionId":"9a52df14-0f79-4ad4-82e9-2de7c82d6421","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:55 INFO - 1500929095253 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
13:44:55 INFO - 1500929095256 Marionette TRACE 385 <- [1,2,null,{}]
13:44:55 INFO - 1500929095309 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:55 INFO - 1500929095314 Marionette TRACE 385 <- [1,3,null,{}]
13:44:55 INFO - 1500929095317 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:55 INFO - 1500929095320 Marionette TRACE 385 <- [1,4,null,{}]
13:44:55 INFO - 1500929095323 Marionette TRACE 385 -> [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}]
13:44:55 INFO - TEST-START: test_click.py TestClick.test_input_file1500929095337 Marionette TRACE 385 <- [1,5,null,{"value":null}]
13:44:55 INFO - 1500929095390 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - 1500929095397 Marionette TRACE 385 <- [1,6,null,{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - 1500929095408 Marionette DEBUG Closed connection 385
13:44:55 INFO - 1500929095411 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49817
13:44:55 INFO - 1500929095434 Marionette DEBUG Closed connection 386
13:44:55 INFO - 1500929095436 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49818
13:44:55 INFO - 1500929095488 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:55 INFO - 1500929095492 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:55 INFO - 1500929095541 Marionette DEBUG Register listener.js for window 12
13:44:55 INFO - 1500929095558 Marionette TRACE 387 <- [1,1,null,{"sessionId":"caf2e2cf-d94f-4d01-aeac-9f8ec461dca0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:55 INFO - 1500929095571 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - 1500929095579 Marionette TRACE 387 <- [1,2,null,{}]
13:44:55 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:55 INFO - 1500929095587 Marionette DEBUG Closed connection 387
13:44:55 INFO - 1500929095589 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49819
13:44:55 INFO - 1500929095626 Marionette DEBUG Closed connection 388
13:44:55 INFO - 1500929095628 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49820
13:44:55 INFO - 1500929095655 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:55 INFO - 1500929095661 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:55 INFO - 1500929095709 Marionette DEBUG Register listener.js for window 12
13:44:55 INFO - 1500929095726 Marionette TRACE 389 <- [1,1,null,{"sessionId":"f9b171f6-4b4d-4c79-9c94-09f951c0b53e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:55 INFO - 1500929095738 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:44:55 INFO - 1500929095744 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"
13:44:55 INFO - 1500929095754 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"
13:44:55 INFO - 1500929095757 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"
13:44:55 INFO - ++DOMWINDOW == 28 (00000094ED666000) [pid = 688] [serial = 47] [outer = 00000094F2D2D000]
13:44:55 INFO - 1500929095833 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:44:55 INFO - 1500929095841 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:44:55 INFO - 1500929095861 Marionette TRACE 389 <- [1,2,null,{}]
13:44:55 INFO - 1500929095891 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:44:55 INFO - 1500929095902 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"928585e9-9af0-475b-8d6f-a33d772a378b","ELEMENT":"928585e9-9af0-475b-8d6f-a33d772a378b"}}]
13:44:55 INFO - 1500929095904 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"928585e9-9af0-475b-8d6f-a33d772a378b"}]
13:44:56 INFO - 1500929096192 Marionette TRACE 389 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:44:56 INFO - 1500929096215 Marionette TRACE 389 -> [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}]
13:44:56 INFO - TEST-END: test_click.py TestClick.test_input_file1500929096226 Marionette TRACE 389 <- [1,5,null,{"value":null}]
13:44:56 INFO - 1500929096228 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096233 Marionette TRACE 389 <- [1,6,null,{}]
13:44:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1136ms
13:44:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096250 Marionette DEBUG Closed connection 389
13:44:56 INFO - 1500929096253 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49821
13:44:56 INFO - 1500929096275 Marionette DEBUG Closed connection 390
13:44:56 INFO - 1500929096278 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49822
13:44:56 INFO - 1500929096281 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:56 INFO - 1500929096284 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:56 INFO - 1500929096334 Marionette DEBUG Register listener.js for window 12
13:44:56 INFO - 1500929096350 Marionette TRACE 391 <- [1,1,null,{"sessionId":"071e85bc-9253-4420-a1b1-f9028e142094","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:56 INFO - 1500929096578 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
13:44:56 INFO - 1500929096585 Marionette TRACE 391 <- [1,2,null,{}]
13:44:56 INFO - 1500929096595 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:56 INFO - 1500929096600 Marionette TRACE 391 <- [1,3,null,{}]
13:44:56 INFO - 1500929096604 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:56 INFO - 1500929096609 Marionette TRACE 391 <- [1,4,null,{}]
13:44:56 INFO - 1500929096614 Marionette TRACE 391 -> [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}]
13:44:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500929096627 Marionette TRACE 391 <- [1,5,null,{"value":null}]
13:44:56 INFO - 1500929096630 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096639 Marionette TRACE 391 <- [1,6,null,{}]
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096669 Marionette DEBUG Closed connection 391
13:44:56 INFO - 1500929096673 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49823
13:44:56 INFO - 1500929096676 Marionette DEBUG Closed connection 392
13:44:56 INFO - 1500929096678 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49824
13:44:56 INFO - 1500929096696 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:56 INFO - 1500929096706 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:56 INFO - 1500929096735 Marionette DEBUG Register listener.js for window 12
13:44:56 INFO - 1500929096756 Marionette TRACE 393 <- [1,1,null,{"sessionId":"ebbafd52-5b52-4bb2-b016-479641b7aa6a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:56 INFO - 1500929096776 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096784 Marionette TRACE 393 <- [1,2,null,{}]
13:44:56 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:56 INFO - 1500929096846 Marionette DEBUG Closed connection 393
13:44:56 INFO - 1500929096849 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49825
13:44:56 INFO - 1500929096872 Marionette DEBUG Closed connection 394
13:44:56 INFO - 1500929096874 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49826
13:44:56 INFO - 1500929096878 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:56 INFO - 1500929096882 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:56 INFO - 1500929096911 Marionette DEBUG Register listener.js for window 12
13:44:56 INFO - 1500929096927 Marionette TRACE 395 <- [1,1,null,{"sessionId":"de3d6967-86e1-4571-ae8b-daed88466f15","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:56 INFO - 1500929096939 Marionette TRACE 395 -> [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"}]
13:44:56 INFO - 1500929096945 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:44:56 INFO - 1500929096956 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:44:56 INFO - 1500929096957 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:44:56 INFO - ++DOMWINDOW == 29 (00000094EF0B8000) [pid = 688] [serial = 48] [outer = 00000094F2D2D000]
13:44:57 INFO - 1500929097031 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"
13:44:57 INFO - 1500929097038 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"
13:44:57 INFO - 1500929097056 Marionette TRACE 395 <- [1,2,null,{}]
13:44:57 INFO - 1500929097060 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:44:57 INFO - 1500929097069 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70bc4c7b-1bdf-43f5-b0a8-b59e149112ec","ELEMENT":"70bc4c7b-1bdf-43f5-b0a8-b59e149112ec"}}]
13:44:57 INFO - 1500929097076 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:44:57 INFO - 1500929097089 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b829d9f1-1103-4a41-9805-3299c4abfa36","ELEMENT":"b829d9f1-1103-4a41-9805-3299c4abfa36"}}]
13:44:57 INFO - 1500929097091 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"70bc4c7b-1bdf-43f5-b0a8-b59e149112ec"}]
13:44:57 INFO - --DOMWINDOW == 28 (00000094FA460800) [pid = 688] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
13:44:57 INFO - --DOMWINDOW == 27 (00000094F76A0000) [pid = 688] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
13:44:57 INFO - --DOMWINDOW == 26 (00000094EF0CE800) [pid = 688] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
13:44:57 INFO - --DOMWINDOW == 25 (00000094F815B800) [pid = 688] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
13:44:57 INFO - 1500929097564 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:44:57 INFO - 1500929097566 Marionette TRACE 395 <- [1,5,null,{}]
13:44:57 INFO - 1500929097568 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"b829d9f1-1103-4a41-9805-3299c4abfa36"}]
13:44:57 INFO - 1500929097721 Marionette TRACE 395 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:44:57 INFO - 1500929097725 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:44:57 INFO - 1500929097735 Marionette TRACE 395 <- [1,7,null,{"value":false}]
13:44:57 INFO - 1500929097737 Marionette TRACE 395 -> [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}]
13:44:57 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500929097746 Marionette TRACE 395 <- [1,8,null,{"value":null}]
13:44:57 INFO - 1500929097748 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
13:44:57 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:57 INFO - 1500929097753 Marionette TRACE 395 <- [1,9,null,{}]
13:44:57 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1519ms
13:44:57 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:44:57 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:57 INFO - 1500929097760 Marionette DEBUG Closed connection 395
13:44:57 INFO - 1500929097762 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49827
13:44:57 INFO - 1500929097764 Marionette DEBUG Closed connection 396
13:44:57 INFO - 1500929097766 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49828
13:44:57 INFO - 1500929097769 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:57 INFO - 1500929097772 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:57 INFO - 1500929097797 Marionette DEBUG Register listener.js for window 12
13:44:57 INFO - 1500929097812 Marionette TRACE 397 <- [1,1,null,{"sessionId":"96dce8cf-72ac-4e32-920f-9ff5d7cbf402","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:57 INFO - 1500929097870 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:44:57 INFO - 1500929097872 Marionette TRACE 397 <- [1,2,null,{}]
13:44:58 INFO - 1500929098007 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:44:58 INFO - 1500929098009 Marionette TRACE 397 <- [1,3,null,{}]
13:44:58 INFO - 1500929098015 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:44:58 INFO - 1500929098016 Marionette TRACE 397 <- [1,4,null,{}]
13:44:58 INFO - 1500929098020 Marionette TRACE 397 -> [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}]
13:44:58 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500929098029 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:44:58 INFO - 1500929098032 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:44:58 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:58 INFO - 1500929098036 Marionette TRACE 397 <- [1,6,null,{}]
13:44:58 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:58 INFO - 1500929098061 Marionette DEBUG Closed connection 397
13:44:58 INFO - 1500929098063 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49829
13:44:58 INFO - 1500929098067 Marionette DEBUG Closed connection 398
13:44:58 INFO - 1500929098069 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49830
13:44:58 INFO - 1500929098074 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:44:58 INFO - 1500929098077 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:58 INFO - 1500929098102 Marionette DEBUG Register listener.js for window 12
13:44:58 INFO - 1500929098115 Marionette TRACE 399 <- [1,1,null,{"sessionId":"15a4e936-be94-4328-a613-bc28e0829101","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:58 INFO - 1500929098942 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:44:58 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:58 INFO - 1500929098946 Marionette TRACE 399 <- [1,2,null,{}]
13:44:58 INFO - [Parent 688] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:44:58 INFO - 1500929098969 Marionette DEBUG Closed connection 399
13:44:58 INFO - 1500929098970 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49831
13:44:59 INFO - 1500929099019 Marionette DEBUG Closed connection 400
13:44:59 INFO - 1500929099021 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49832
13:44:59 INFO - 1500929099079 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:44:59 INFO - 1500929099085 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}
13:44:59 INFO - 1500929099110 Marionette DEBUG Register listener.js for window 12
13:44:59 INFO - 1500929099125 Marionette TRACE 401 <- [1,1,null,{"sessionId":"4d862a04-2b82-400c-917d-198bf156175a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":688,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxqtqhe.mozrunner","moz:accessibilityChecks":false}}]
13:44:59 INFO - 1500929099183 Marionette TRACE 401 -> [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"}]
13:44:59 INFO - 1500929099188 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"
13:44:59 INFO - 1500929099197 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"
13:44:59 INFO - 1500929099198 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"
13:44:59 INFO - ++DOMWINDOW == 26 (00000094ED44D800) [pid = 688] [serial = 49] [outer = 00000094F2D2D000]
13:44:59 INFO - 1500929099315 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"
13:44:59 INFO - 1500929099321 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"
13:44:59 INFO - 1500929099332 Marionette TRACE 401 <- [1,2,null,{}]
13:44:59 INFO - 1500929099343 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:44:59 INFO - 1500929099355 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"482f9608-17bb-45ba-bd4c-b5cf25f44f66","ELEMENT":"482f9608-17bb-45ba-bd4c-b5cf25f44f66"}}]
13:44:59 INFO - 1500929099357 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"482f9608-17bb-45ba-bd4c-b5cf25f44f66"}]
13:44:59 INFO - 1500929099541 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
13:44:59 INFO - 1500929099593 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"482f9608-17bb-45ba-bd4c-b5cf25f44f66"}]
13:44:59 INFO - 1500929099793 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element