could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:35:34 INFO - 1500806134569 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
03:35:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500806134576 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:35:34 INFO - 1500806134578 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:35:34 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:34 INFO - 1500806134583 Marionette TRACE 352 <- [1,6,null,{}]
03:35:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 649ms
03:35:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:35:34 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:34 INFO - 1500806134595 Marionette DEBUG Closed connection 352
03:35:34 INFO - 1500806134597 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49768
03:35:34 INFO - 1500806134598 Marionette DEBUG Closed connection 353
03:35:34 INFO - 1500806134600 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
03:35:34 INFO - 1500806134602 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:34 INFO - 1500806134604 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:34 INFO - 1500806134625 Marionette DEBUG Register listener.js for window 15
03:35:34 INFO - 1500806134636 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c8064471-1940-4091-827a-e6563f5111c0","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:35 INFO - 1500806135456 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:35:35 INFO - 1500806135457 Marionette TRACE 354 <- [1,2,null,{}]
03:35:35 INFO - 1500806135460 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:35 INFO - 1500806135461 Marionette TRACE 354 <- [1,3,null,{}]
03:35:35 INFO - 1500806135509 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:35 INFO - 1500806135511 Marionette TRACE 354 <- [1,4,null,{}]
03:35:35 INFO - 1500806135563 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
03:35:35 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500806135570 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:35:35 INFO - 1500806135572 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:35:35 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:35 INFO - 1500806135576 Marionette TRACE 354 <- [1,6,null,{}]
03:35:35 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:35 INFO - 1500806135582 Marionette DEBUG Closed connection 354
03:35:35 INFO - 1500806135583 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
03:35:35 INFO - 1500806135585 Marionette DEBUG Closed connection 355
03:35:35 INFO - 1500806135587 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
03:35:35 INFO - 1500806135590 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:35 INFO - 1500806135592 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:35 INFO - 1500806135612 Marionette DEBUG Register listener.js for window 15
03:35:35 INFO - 1500806135623 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5f10957d-ec1f-4492-bb9a-fef4f40c7da6","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:35 INFO - 1500806135678 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:35:35 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:35 INFO - 1500806135682 Marionette TRACE 356 <- [1,2,null,{}]
03:35:35 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:35 INFO - 1500806135738 Marionette DEBUG Closed connection 356
03:35:35 INFO - 1500806135740 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
03:35:35 INFO - 1500806135791 Marionette DEBUG Closed connection 357
03:35:35 INFO - 1500806135792 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
03:35:35 INFO - 1500806135811 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:35 INFO - 1500806135813 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:35 INFO - 1500806135833 Marionette DEBUG Register listener.js for window 15
03:35:35 INFO - 1500806135872 Marionette TRACE 358 <- [1,1,null,{"sessionId":"72b7025a-f30c-4bdc-8e2c-f0d268ecb693","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:35 INFO - 1500806135889 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49319/clicks.html"}]
03:35:35 INFO - 1500806135896 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/hidden.html"
03:35:35 INFO - 1500806135913 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/hidden.html"
03:35:35 INFO - 1500806135914 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/hidden.html"
03:35:35 INFO - ++DOMWINDOW == 27 (000000C866014000) [pid = 3620] [serial = 40] [outer = 000000C87178E800]
03:35:35 INFO - 1500806135991 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49319/clicks.html"
03:35:35 INFO - 1500806135998 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49319/clicks.html"
03:35:36 INFO - 1500806136036 Marionette TRACE 358 <- [1,2,null,{}]
03:35:36 INFO - 1500806136056 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:35:36 INFO - 1500806136071 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"39c645be-973b-4521-983a-ab2571b78cae","ELEMENT":"39c645be-973b-4521-983a-ab2571b78cae"}}]
03:35:36 INFO - 1500806136076 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"39c645be-973b-4521-983a-ab2571b78cae"}]
03:35:36 INFO - 1500806136478 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/clicks.html"
03:35:36 INFO - [Parent 3620] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
03:35:36 INFO - 1500806136499 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/clicks.html"
03:35:36 INFO - 1500806136500 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/clicks.html"
03:35:36 INFO - ++DOMWINDOW == 28 (000000C86DFC4000) [pid = 3620] [serial = 41] [outer = 000000C87178E800]
03:35:36 INFO - 1500806136583 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49319/test.html"
03:35:36 INFO - 1500806136590 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49319/test.html"
03:35:36 INFO - 1500806136612 Marionette TRACE 358 <- [1,4,null,{}]
03:35:36 INFO - 1500806136616 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:35:36 INFO - 1500806136630 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0344a066-0259-4d1c-87cd-98a0489bfbba","ELEMENT":"0344a066-0259-4d1c-87cd-98a0489bfbba"}}]
03:35:36 INFO - 1500806136634 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:35:36 INFO - 1500806136637 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:35:36 INFO - 1500806136652 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
03:35:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500806136666 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:35:36 INFO - 1500806136670 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:35:36 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:36 INFO - 1500806136677 Marionette TRACE 358 <- [1,8,null,{}]
03:35:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 2088ms
03:35:36 INFO - TEST-START | test_click.py TestClick.test_container_element
03:35:36 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:36 INFO - 1500806136737 Marionette DEBUG Closed connection 358
03:35:36 INFO - 1500806136739 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49776
03:35:36 INFO - 1500806136742 Marionette DEBUG Closed connection 359
03:35:36 INFO - 1500806136745 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
03:35:36 INFO - 1500806136751 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:36 INFO - 1500806136755 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:36 INFO - 1500806136797 Marionette DEBUG Register listener.js for window 15
03:35:36 INFO - 1500806136830 Marionette TRACE 360 <- [1,1,null,{"sessionId":"da561957-7641-4e8f-bb6b-c2e38ea86aa0","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:36 INFO - 1500806136843 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:35:36 INFO - 1500806136846 Marionette TRACE 360 <- [1,2,null,{}]
03:35:36 INFO - 1500806136852 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:36 INFO - 1500806136857 Marionette TRACE 360 <- [1,3,null,{}]
03:35:36 INFO - 1500806136922 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:36 INFO - 1500806136927 Marionette TRACE 360 <- [1,4,null,{}]
03:35:36 INFO - 1500806136933 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
03:35:36 INFO - TEST-START: test_click.py TestClick.test_container_element1500806136944 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:35:37 INFO - 1500806136996 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:37 INFO - 1500806137002 Marionette TRACE 360 <- [1,6,null,{}]
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:37 INFO - 1500806137028 Marionette DEBUG Closed connection 360
03:35:37 INFO - 1500806137031 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
03:35:37 INFO - 1500806137036 Marionette DEBUG Closed connection 361
03:35:37 INFO - 1500806137038 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
03:35:37 INFO - 1500806137092 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:37 INFO - 1500806137096 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:37 INFO - 1500806137138 Marionette DEBUG Register listener.js for window 15
03:35:37 INFO - 1500806137158 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0da42613-2d4a-4723-ac03-71920b8e480a","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:37 INFO - 1500806137167 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:37 INFO - 1500806137173 Marionette TRACE 362 <- [1,2,null,{}]
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:37 INFO - 1500806137182 Marionette DEBUG Closed connection 362
03:35:37 INFO - 1500806137185 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
03:35:37 INFO - 1500806137189 Marionette DEBUG Closed connection 363
03:35:37 INFO - 1500806137192 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
03:35:37 INFO - 1500806137194 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:37 INFO - 1500806137199 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:37 INFO - 1500806137239 Marionette DEBUG Register listener.js for window 15
03:35:37 INFO - 1500806137255 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c7cfe4da-8b5a-47fe-b35e-ca21a38dccf0","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:37 INFO - 1500806137303 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:35:37 INFO - 1500806137309 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/test.html"
03:35:37 INFO - 1500806137317 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/test.html"
03:35:37 INFO - 1500806137318 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/test.html"
03:35:37 INFO - ++DOMWINDOW == 29 (000000C878B27800) [pid = 3620] [serial = 42] [outer = 000000C87178E800]
03:35:37 INFO - 1500806137385 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"
03:35:37 INFO - 1500806137398 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"
03:35:37 INFO - 1500806137409 Marionette TRACE 364 <- [1,2,null,{}]
03:35:37 INFO - 1500806137431 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:35:37 INFO - 1500806137442 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79be9dfb-f25f-4d3c-bf05-272eb68531e0","ELEMENT":"79be9dfb-f25f-4d3c-bf05-272eb68531e0"}}]
03:35:37 INFO - 1500806137450 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"79be9dfb-f25f-4d3c-bf05-272eb68531e0"}]
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:35:37 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:35:38 INFO - 1500806138068 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:35:38 INFO - 1500806138070 Marionette TRACE 364 <- [1,4,null,{}]
03:35:38 INFO - 1500806138074 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"79be9dfb-f25f-4d3c-bf05-272eb68531e0","name":"selected"}]
03:35:38 INFO - 1500806138132 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:35:38 INFO - 1500806138167 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
03:35:38 INFO - TEST-END: test_click.py TestClick.test_container_element1500806138182 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:35:38 INFO - 1500806138194 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138199 Marionette TRACE 364 <- [1,7,null,{}]
03:35:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1522ms
03:35:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138387 Marionette DEBUG Closed connection 364
03:35:38 INFO - 1500806138391 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
03:35:38 INFO - 1500806138415 Marionette DEBUG Closed connection 365
03:35:38 INFO - 1500806138418 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
03:35:38 INFO - 1500806138443 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:38 INFO - 1500806138455 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:38 INFO - 1500806138480 Marionette DEBUG Register listener.js for window 15
03:35:38 INFO - 1500806138502 Marionette TRACE 366 <- [1,1,null,{"sessionId":"5a7ec408-c45c-4ba7-b08f-6ac67b0953f4","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:38 INFO - 1500806138526 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:35:38 INFO - 1500806138529 Marionette TRACE 366 <- [1,2,null,{}]
03:35:38 INFO - 1500806138539 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:38 INFO - 1500806138543 Marionette TRACE 366 <- [1,3,null,{}]
03:35:38 INFO - 1500806138546 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:38 INFO - 1500806138550 Marionette TRACE 366 <- [1,4,null,{}]
03:35:38 INFO - 1500806138555 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
03:35:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500806138569 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:35:38 INFO - 1500806138621 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138629 Marionette TRACE 366 <- [1,6,null,{}]
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138658 Marionette DEBUG Closed connection 366
03:35:38 INFO - 1500806138662 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
03:35:38 INFO - 1500806138667 Marionette DEBUG Closed connection 367
03:35:38 INFO - 1500806138669 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
03:35:38 INFO - 1500806138707 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:38 INFO - 1500806138714 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:38 INFO - 1500806138747 Marionette DEBUG Register listener.js for window 15
03:35:38 INFO - 1500806138764 Marionette TRACE 368 <- [1,1,null,{"sessionId":"9a9fa1d6-871f-4fbc-af36-7d900f7f0e3c","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:38 INFO - 1500806138774 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138780 Marionette TRACE 368 <- [1,2,null,{}]
03:35:38 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:38 INFO - 1500806138808 Marionette DEBUG Closed connection 368
03:35:38 INFO - 1500806138810 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
03:35:38 INFO - 1500806138815 Marionette DEBUG Closed connection 369
03:35:38 INFO - 1500806138817 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
03:35:38 INFO - 1500806138824 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:38 INFO - 1500806138828 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:38 INFO - 1500806138850 Marionette DEBUG Register listener.js for window 15
03:35:38 INFO - 1500806138865 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7f2cce90-7574-4a22-af8d-69c0cfa62a1a","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:38 INFO - 1500806138876 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:35:38 INFO - 1500806138881 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"
03:35:38 INFO - 1500806138891 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"
03:35:38 INFO - 1500806138891 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"
03:35:38 INFO - ++DOMWINDOW == 30 (000000C86428D800) [pid = 3620] [serial = 43] [outer = 000000C87178E800]
03:35:38 INFO - 1500806138947 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"
03:35:38 INFO - 1500806138956 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"
03:35:38 INFO - 1500806138978 Marionette TRACE 370 <- [1,2,null,{}]
03:35:38 INFO - 1500806138995 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:35:39 INFO - 1500806139008 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a40cfa7d-a0ff-4511-b025-0ad2ca163fe1","ELEMENT":"a40cfa7d-a0ff-4511-b025-0ad2ca163fe1"}}]
03:35:39 INFO - 1500806139010 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a40cfa7d-a0ff-4511-b025-0ad2ca163fe1"}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:35:39 INFO - --DOMWINDOW == 29 (000000C8763A5000) [pid = 3620] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 28 (000000C8695D3800) [pid = 3620] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 27 (000000C875C1A000) [pid = 3620] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 26 (000000C86BE4A800) [pid = 3620] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 25 (000000C86DE50800) [pid = 3620] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 24 (000000C8721BF000) [pid = 3620] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - --DOMWINDOW == 23 (000000C87838B800) [pid = 3620] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49319/test_accessibility.html]
03:35:39 INFO - 1500806139447 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:35:39 INFO - 1500806139449 Marionette TRACE 370 <- [1,4,null,{}]
03:35:39 INFO - 1500806139452 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a40cfa7d-a0ff-4511-b025-0ad2ca163fe1","name":"selected"}]
03:35:39 INFO - 1500806139483 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:35:39 INFO - 1500806139486 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
03:35:39 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500806139495 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:35:39 INFO - 1500806139497 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139502 Marionette TRACE 370 <- [1,7,null,{}]
03:35:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1302ms
03:35:39 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139508 Marionette DEBUG Closed connection 370
03:35:39 INFO - 1500806139510 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
03:35:39 INFO - 1500806139512 Marionette DEBUG Closed connection 371
03:35:39 INFO - 1500806139515 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
03:35:39 INFO - 1500806139517 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:39 INFO - 1500806139520 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:39 INFO - 1500806139543 Marionette DEBUG Register listener.js for window 15
03:35:39 INFO - 1500806139557 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b5f4126f-2e9e-48ef-a99f-93939e3a55de","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:39 INFO - 1500806139565 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:35:39 INFO - 1500806139567 Marionette TRACE 372 <- [1,2,null,{}]
03:35:39 INFO - 1500806139569 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:39 INFO - 1500806139570 Marionette TRACE 372 <- [1,3,null,{}]
03:35:39 INFO - 1500806139572 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:39 INFO - 1500806139574 Marionette TRACE 372 <- [1,4,null,{}]
03:35:39 INFO - 1500806139577 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
03:35:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500806139586 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:35:39 INFO - 1500806139592 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139597 Marionette TRACE 372 <- [1,6,null,{}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139604 Marionette DEBUG Closed connection 372
03:35:39 INFO - 1500806139605 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
03:35:39 INFO - 1500806139607 Marionette DEBUG Closed connection 373
03:35:39 INFO - 1500806139609 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
03:35:39 INFO - 1500806139611 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:39 INFO - 1500806139617 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:39 INFO - 1500806139638 Marionette DEBUG Register listener.js for window 15
03:35:39 INFO - 1500806139653 Marionette TRACE 374 <- [1,1,null,{"sessionId":"dfaafc77-d0f2-4475-99c3-323def3daf2c","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:39 INFO - 1500806139717 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139722 Marionette TRACE 374 <- [1,2,null,{}]
03:35:39 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:39 INFO - 1500806139731 Marionette DEBUG Closed connection 374
03:35:39 INFO - 1500806139736 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
03:35:39 INFO - 1500806139738 Marionette DEBUG Closed connection 375
03:35:39 INFO - 1500806139740 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
03:35:39 INFO - 1500806139742 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:39 INFO - 1500806139744 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:39 INFO - 1500806139766 Marionette DEBUG Register listener.js for window 15
03:35:39 INFO - 1500806139780 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f2870532-4606-46c0-bf42-57f8b762f90e","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:39 INFO - 1500806139808 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
03:35:39 INFO - 1500806139813 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"
03:35:39 INFO - 1500806139821 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"
03:35:39 INFO - 1500806139822 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"
03:35:39 INFO - ++DOMWINDOW == 24 (000000C86AC43800) [pid = 3620] [serial = 44] [outer = 000000C87178E800]
03:35:39 INFO - 1500806139882 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"
03:35:39 INFO - 1500806139889 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"
03:35:39 INFO - 1500806139926 Marionette TRACE 376 <- [1,2,null,{}]
03:35:39 INFO - 1500806139934 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:35:39 INFO - 1500806139941 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"850bd294-4535-4ae9-bfa1-f4ac57a5e732","ELEMENT":"850bd294-4535-4ae9-bfa1-f4ac57a5e732"}}]
03:35:40 INFO - 1500806140680 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"850bd294-4535-4ae9-bfa1-f4ac57a5e732"}]
03:35:41 INFO - 1500806141214 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:35:41 INFO - 1500806141216 Marionette TRACE 376 <- [1,4,null,{}]
03:35:41 INFO - 1500806141236 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
03:35:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500806141249 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:35:41 INFO - 1500806141253 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141258 Marionette TRACE 376 <- [1,6,null,{}]
03:35:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1755ms
03:35:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141264 Marionette DEBUG Closed connection 376
03:35:41 INFO - 1500806141265 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
03:35:41 INFO - 1500806141267 Marionette DEBUG Closed connection 377
03:35:41 INFO - 1500806141269 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
03:35:41 INFO - 1500806141271 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:41 INFO - 1500806141273 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:41 INFO - 1500806141295 Marionette DEBUG Register listener.js for window 15
03:35:41 INFO - 1500806141306 Marionette TRACE 378 <- [1,1,null,{"sessionId":"bda55d26-d889-4130-94a8-b74efd946b03","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:41 INFO - 1500806141312 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:35:41 INFO - 1500806141314 Marionette TRACE 378 <- [1,2,null,{}]
03:35:41 INFO - 1500806141316 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:41 INFO - 1500806141318 Marionette TRACE 378 <- [1,3,null,{}]
03:35:41 INFO - 1500806141344 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:41 INFO - 1500806141346 Marionette TRACE 378 <- [1,4,null,{}]
03:35:41 INFO - 1500806141349 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
03:35:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500806141357 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:35:41 INFO - 1500806141360 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141364 Marionette TRACE 378 <- [1,6,null,{}]
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141386 Marionette DEBUG Closed connection 378
03:35:41 INFO - 1500806141388 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
03:35:41 INFO - 1500806141392 Marionette DEBUG Closed connection 379
03:35:41 INFO - 1500806141393 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
03:35:41 INFO - 1500806141395 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:41 INFO - 1500806141398 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:41 INFO - 1500806141420 Marionette DEBUG Register listener.js for window 15
03:35:41 INFO - 1500806141432 Marionette TRACE 380 <- [1,1,null,{"sessionId":"28f57c1d-7879-44b7-8a7a-7fe4ce89d338","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:41 INFO - 1500806141443 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141447 Marionette TRACE 380 <- [1,2,null,{}]
03:35:41 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:41 INFO - 1500806141469 Marionette DEBUG Closed connection 380
03:35:41 INFO - 1500806141470 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
03:35:41 INFO - 1500806141474 Marionette DEBUG Closed connection 381
03:35:41 INFO - 1500806141476 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
03:35:41 INFO - 1500806141478 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:41 INFO - 1500806141482 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:41 INFO - 1500806141503 Marionette DEBUG Register listener.js for window 15
03:35:41 INFO - 1500806141515 Marionette TRACE 382 <- [1,1,null,{"sessionId":"45067c68-a4ea-4875-8c7d-aab0eef21463","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:41 INFO - 1500806141525 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:35:41 INFO - 1500806141529 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"
03:35:41 INFO - 1500806141537 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"
03:35:41 INFO - 1500806141537 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"
03:35:41 INFO - ++DOMWINDOW == 25 (000000C8710EC800) [pid = 3620] [serial = 45] [outer = 000000C87178E800]
03:35:41 INFO - 1500806141597 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"
03:35:41 INFO - 1500806141602 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"
03:35:41 INFO - 1500806141623 Marionette TRACE 382 <- [1,2,null,{}]
03:35:41 INFO - 1500806141629 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:35:41 INFO - 1500806141639 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0de7bb16-7875-4569-ad34-b28a993a02d2","ELEMENT":"0de7bb16-7875-4569-ad34-b28a993a02d2"}}]
03:35:41 INFO - 1500806141641 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0de7bb16-7875-4569-ad34-b28a993a02d2"}]
03:35:42 INFO - 1500806142101 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:35:42 INFO - 1500806142103 Marionette TRACE 382 <- [1,4,null,{}]
03:35:42 INFO - 1500806142106 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
03:35:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500806142115 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:35:42 INFO - 1500806142117 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142121 Marionette TRACE 382 <- [1,6,null,{}]
03:35:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 862ms
03:35:42 INFO - TEST-START | test_click.py TestClick.test_input_file
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142127 Marionette DEBUG Closed connection 382
03:35:42 INFO - 1500806142128 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
03:35:42 INFO - 1500806142130 Marionette DEBUG Closed connection 383
03:35:42 INFO - 1500806142131 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
03:35:42 INFO - 1500806142133 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:42 INFO - 1500806142136 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142157 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142169 Marionette TRACE 384 <- [1,1,null,{"sessionId":"20aca59c-57f2-413b-93ad-419e617ecc5c","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142175 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:35:42 INFO - 1500806142177 Marionette TRACE 384 <- [1,2,null,{}]
03:35:42 INFO - 1500806142207 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:42 INFO - 1500806142209 Marionette TRACE 384 <- [1,3,null,{}]
03:35:42 INFO - 1500806142211 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:42 INFO - 1500806142213 Marionette TRACE 384 <- [1,4,null,{}]
03:35:42 INFO - 1500806142215 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
03:35:42 INFO - TEST-START: test_click.py TestClick.test_input_file1500806142223 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:35:42 INFO - 1500806142225 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142229 Marionette TRACE 384 <- [1,6,null,{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142235 Marionette DEBUG Closed connection 384
03:35:42 INFO - 1500806142237 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
03:35:42 INFO - 1500806142238 Marionette DEBUG Closed connection 385
03:35:42 INFO - 1500806142239 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
03:35:42 INFO - 1500806142242 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:42 INFO - 1500806142245 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142271 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142286 Marionette TRACE 386 <- [1,1,null,{"sessionId":"12692edb-fdc0-41f7-8937-676259c16408","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142292 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142296 Marionette TRACE 386 <- [1,2,null,{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142302 Marionette DEBUG Closed connection 386
03:35:42 INFO - 1500806142304 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
03:35:42 INFO - 1500806142305 Marionette DEBUG Closed connection 387
03:35:42 INFO - 1500806142307 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
03:35:42 INFO - 1500806142309 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:42 INFO - 1500806142311 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142333 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142344 Marionette TRACE 388 <- [1,1,null,{"sessionId":"df1d0cea-2bc7-4a86-b87d-2e801a7bdc60","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142351 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:35:42 INFO - 1500806142355 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"
03:35:42 INFO - 1500806142364 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"
03:35:42 INFO - 1500806142365 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"
03:35:42 INFO - ++DOMWINDOW == 26 (000000C875C0A800) [pid = 3620] [serial = 46] [outer = 000000C87178E800]
03:35:42 INFO - 1500806142419 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:35:42 INFO - 1500806142432 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:35:42 INFO - 1500806142443 Marionette TRACE 388 <- [1,2,null,{}]
03:35:42 INFO - 1500806142453 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:35:42 INFO - 1500806142461 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a893542-f457-4108-82c2-2e64e07a4ae9","ELEMENT":"4a893542-f457-4108-82c2-2e64e07a4ae9"}}]
03:35:42 INFO - 1500806142464 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4a893542-f457-4108-82c2-2e64e07a4ae9"}]
03:35:42 INFO - 1500806142698 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:35:42 INFO - 1500806142705 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
03:35:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500806142713 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:35:42 INFO - 1500806142715 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142719 Marionette TRACE 388 <- [1,6,null,{}]
03:35:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 598ms
03:35:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142743 Marionette DEBUG Closed connection 388
03:35:42 INFO - 1500806142744 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
03:35:42 INFO - 1500806142749 Marionette DEBUG Closed connection 389
03:35:42 INFO - 1500806142750 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
03:35:42 INFO - 1500806142753 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:42 INFO - 1500806142755 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142777 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142788 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7cf875f9-6a97-402d-aca1-1082d553fef7","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142798 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:35:42 INFO - 1500806142799 Marionette TRACE 390 <- [1,2,null,{}]
03:35:42 INFO - 1500806142801 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:42 INFO - 1500806142803 Marionette TRACE 390 <- [1,3,null,{}]
03:35:42 INFO - 1500806142805 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:42 INFO - 1500806142806 Marionette TRACE 390 <- [1,4,null,{}]
03:35:42 INFO - 1500806142808 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
03:35:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500806142816 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:35:42 INFO - 1500806142818 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142822 Marionette TRACE 390 <- [1,6,null,{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142847 Marionette DEBUG Closed connection 390
03:35:42 INFO - 1500806142848 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
03:35:42 INFO - 1500806142852 Marionette DEBUG Closed connection 391
03:35:42 INFO - 1500806142853 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
03:35:42 INFO - 1500806142855 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:42 INFO - 1500806142857 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142879 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142890 Marionette TRACE 392 <- [1,1,null,{"sessionId":"9c22890f-8965-4fbb-b2e3-6e726583d292","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142900 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142905 Marionette TRACE 392 <- [1,2,null,{}]
03:35:42 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:42 INFO - 1500806142926 Marionette DEBUG Closed connection 392
03:35:42 INFO - 1500806142928 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
03:35:42 INFO - 1500806142932 Marionette DEBUG Closed connection 393
03:35:42 INFO - 1500806142933 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
03:35:42 INFO - 1500806142936 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:42 INFO - 1500806142939 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:42 INFO - 1500806142960 Marionette DEBUG Register listener.js for window 15
03:35:42 INFO - 1500806142971 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0c5a5dee-3961-4d95-8e30-2d0d628b8263","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:42 INFO - 1500806142982 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
03:35:42 INFO - 1500806142986 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:35:42 INFO - 1500806142993 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:35:42 INFO - 1500806142993 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:35:42 INFO - ++DOMWINDOW == 27 (000000C875D11800) [pid = 3620] [serial = 47] [outer = 000000C87178E800]
03:35:43 INFO - 1500806143053 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"
03:35:43 INFO - 1500806143059 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"
03:35:43 INFO - 1500806143075 Marionette TRACE 394 <- [1,2,null,{}]
03:35:43 INFO - 1500806143081 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:35:43 INFO - 1500806143089 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"540e0469-4e93-4b5d-87e6-251e72b54ae9","ELEMENT":"540e0469-4e93-4b5d-87e6-251e72b54ae9"}}]
03:35:43 INFO - 1500806143091 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:35:43 INFO - 1500806143098 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fd68c09-154d-4865-b6c5-e16d5789af3c","ELEMENT":"4fd68c09-154d-4865-b6c5-e16d5789af3c"}}]
03:35:43 INFO - 1500806143100 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"540e0469-4e93-4b5d-87e6-251e72b54ae9"}]
03:35:44 INFO - 1500806144215 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:35:44 INFO - 1500806144219 Marionette TRACE 394 <- [1,5,null,{}]
03:35:44 INFO - 1500806144224 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4fd68c09-154d-4865-b6c5-e16d5789af3c"}]
03:35:44 INFO - 1500806144510 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:35:44 INFO - 1500806144516 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:35:44 INFO - 1500806144530 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:35:44 INFO - 1500806144534 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
03:35:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500806144548 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:35:44 INFO - 1500806144552 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:35:44 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:44 INFO - 1500806144560 Marionette TRACE 394 <- [1,9,null,{}]
03:35:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1841ms
03:35:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:35:44 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:44 INFO - 1500806144570 Marionette DEBUG Closed connection 394
03:35:44 INFO - 1500806144574 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
03:35:44 INFO - 1500806144623 Marionette DEBUG Closed connection 395
03:35:44 INFO - 1500806144626 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
03:35:44 INFO - 1500806144641 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:44 INFO - 1500806144651 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:44 INFO - 1500806144696 Marionette DEBUG Register listener.js for window 15
03:35:44 INFO - 1500806144718 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6544033c-3d64-47a9-be43-a5b2d8c83daa","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:44 INFO - 1500806144734 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:35:44 INFO - 1500806144737 Marionette TRACE 396 <- [1,2,null,{}]
03:35:44 INFO - 1500806144790 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:35:44 INFO - 1500806144792 Marionette TRACE 396 <- [1,3,null,{}]
03:35:44 INFO - 1500806144796 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:35:44 INFO - 1500806144799 Marionette TRACE 396 <- [1,4,null,{}]
03:35:44 INFO - 1500806144804 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
03:35:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500806144816 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:35:44 INFO - 1500806144869 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:35:44 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:44 INFO - 1500806144876 Marionette TRACE 396 <- [1,6,null,{}]
03:35:44 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:44 INFO - 1500806144903 Marionette DEBUG Closed connection 396
03:35:44 INFO - 1500806144906 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
03:35:44 INFO - 1500806144911 Marionette DEBUG Closed connection 397
03:35:44 INFO - 1500806144913 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
03:35:44 INFO - 1500806144970 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:35:44 INFO - 1500806144976 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:45 INFO - 1500806145021 Marionette DEBUG Register listener.js for window 15
03:35:45 INFO - 1500806145038 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3dea1718-113e-456b-86fc-a8d71571ec48","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:45 INFO - 1500806145051 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:35:45 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:45 INFO - 1500806145058 Marionette TRACE 398 <- [1,2,null,{}]
03:35:45 INFO - [Parent 3620] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:35:45 INFO - 1500806145088 Marionette DEBUG Closed connection 398
03:35:45 INFO - 1500806145090 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
03:35:45 INFO - 1500806145112 Marionette DEBUG Closed connection 399
03:35:45 INFO - 1500806145114 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
03:35:45 INFO - 1500806145117 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:35:45 INFO - 1500806145121 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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}
03:35:45 INFO - 1500806145166 Marionette DEBUG Register listener.js for window 15
03:35:45 INFO - 1500806145182 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f3052585-0167-459e-8f3b-119e732edbab","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":3620,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-203.001\\AppData\\Local\\Temp\\tmpnhndo5.mozrunner","moz:accessibilityChecks":false}}]
03:35:45 INFO - 1500806145364 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
03:35:45 INFO - 1500806145371 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"
03:35:45 INFO - 1500806145384 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"
03:35:45 INFO - 1500806145385 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"
03:35:45 INFO - ++DOMWINDOW == 28 (000000C866086800) [pid = 3620] [serial = 48] [outer = 000000C87178E800]
03:35:45 INFO - 1500806145462 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"
03:35:45 INFO - 1500806145470 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"
03:35:45 INFO - 1500806145490 Marionette TRACE 400 <- [1,2,null,{}]
03:35:45 INFO - 1500806145503 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:35:45 INFO - 1500806145516 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54ef6e5c-45ce-4a08-9512-e9d49aef575d","ELEMENT":"54ef6e5c-45ce-4a08-9512-e9d49aef575d"}}]
03:35:45 INFO - 1500806145521 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"54ef6e5c-45ce-4a08-9512-e9d49aef575d"}]
03:35:45 INFO - 1500806145669 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:35:45 INFO - 1500806145674 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"54ef6e5c-45ce-4a08-9512-e9d49aef575d"}]
03:35:45 INFO - 1500806145839 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element