could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:38:28 INFO - 1500485908240 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
10:38:28 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500485908250 Marionette TRACE 353 <- [1,5,null,{"value":null}]
10:38:28 INFO - 1500485908252 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908257 Marionette TRACE 353 <- [1,6,null,{}]
10:38:28 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 644ms
10:38:28 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908264 Marionette DEBUG Closed connection 353
10:38:28 INFO - 1500485908266 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
10:38:28 INFO - 1500485908267 Marionette DEBUG Closed connection 354
10:38:28 INFO - 1500485908269 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
10:38:28 INFO - 1500485908271 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:28 INFO - 1500485908273 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:28 INFO - 1500485908293 Marionette DEBUG Register listener.js for window 15
10:38:28 INFO - 1500485908304 Marionette TRACE 355 <- [1,1,null,{"sessionId":"7a13c5c1-ddbd-4d10-83aa-9f94c0282cf9","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:28 INFO - 1500485908310 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
10:38:28 INFO - 1500485908311 Marionette TRACE 355 <- [1,2,null,{}]
10:38:28 INFO - 1500485908313 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:28 INFO - 1500485908315 Marionette TRACE 355 <- [1,3,null,{}]
10:38:28 INFO - 1500485908316 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:28 INFO - 1500485908318 Marionette TRACE 355 <- [1,4,null,{}]
10:38:28 INFO - 1500485908320 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
10:38:28 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500485908327 Marionette TRACE 355 <- [1,5,null,{"value":null}]
10:38:28 INFO - 1500485908329 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908334 Marionette TRACE 355 <- [1,6,null,{}]
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908339 Marionette DEBUG Closed connection 355
10:38:28 INFO - 1500485908341 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
10:38:28 INFO - 1500485908342 Marionette DEBUG Closed connection 356
10:38:28 INFO - 1500485908344 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
10:38:28 INFO - 1500485908346 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:28 INFO - 1500485908348 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:28 INFO - 1500485908368 Marionette DEBUG Register listener.js for window 15
10:38:28 INFO - 1500485908379 Marionette TRACE 357 <- [1,1,null,{"sessionId":"41518355-13c9-4964-851c-ab93efa07d67","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:28 INFO - 1500485908385 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908389 Marionette TRACE 357 <- [1,2,null,{}]
10:38:28 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:28 INFO - 1500485908395 Marionette DEBUG Closed connection 357
10:38:28 INFO - 1500485908396 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
10:38:28 INFO - 1500485908398 Marionette DEBUG Closed connection 358
10:38:28 INFO - 1500485908399 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49787
10:38:28 INFO - 1500485908401 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:28 INFO - 1500485908404 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:28 INFO - 1500485908424 Marionette DEBUG Register listener.js for window 15
10:38:28 INFO - 1500485908435 Marionette TRACE 359 <- [1,1,null,{"sessionId":"52a65231-68b9-4ea6-b390-7e8986ada724","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:28 INFO - 1500485908441 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
10:38:28 INFO - 1500485908445 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
10:38:28 INFO - 1500485908461 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
10:38:28 INFO - 1500485908462 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
10:38:28 INFO - ++DOMWINDOW == 28 (000000E092D2F000) [pid = 3472] [serial = 40] [outer = 000000E08DFC6800]
10:38:28 INFO - 1500485908527 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
10:38:28 INFO - 1500485908531 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
10:38:28 INFO - 1500485908626 Marionette TRACE 359 <- [1,2,null,{}]
10:38:28 INFO - 1500485908629 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:38:28 INFO - 1500485908635 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c976f5ed-8c3b-4284-ba37-e5c3489b5642","ELEMENT":"c976f5ed-8c3b-4284-ba37-e5c3489b5642"}}]
10:38:28 INFO - 1500485908641 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"c976f5ed-8c3b-4284-ba37-e5c3489b5642"}]
10:38:28 INFO - 1500485908860 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
10:38:28 INFO - [Parent 3472] 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
10:38:28 INFO - 1500485908881 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
10:38:28 INFO - 1500485908882 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
10:38:28 INFO - ++DOMWINDOW == 29 (000000E092D42000) [pid = 3472] [serial = 41] [outer = 000000E08DFC6800]
10:38:28 INFO - 1500485908981 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
10:38:28 INFO - 1500485908992 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
10:38:29 INFO - 1500485909008 Marionette TRACE 359 <- [1,4,null,{}]
10:38:29 INFO - 1500485909011 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:38:29 INFO - 1500485909020 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"889807d4-8ede-4393-be82-8c2bd37c9b3b","ELEMENT":"889807d4-8ede-4393-be82-8c2bd37c9b3b"}}]
10:38:29 INFO - 1500485909021 Marionette TRACE 359 -> [0,6,"getTitle",{}]
10:38:29 INFO - 1500485909023 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
10:38:29 INFO - 1500485909025 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
10:38:29 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500485909032 Marionette TRACE 359 <- [1,7,null,{"value":null}]
10:38:29 INFO - 1500485909034 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
10:38:29 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:29 INFO - 1500485909038 Marionette TRACE 359 <- [1,8,null,{}]
10:38:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 781ms
10:38:29 INFO - TEST-START | test_click.py TestClick.test_container_element
10:38:29 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:29 INFO - 1500485909044 Marionette DEBUG Closed connection 359
10:38:29 INFO - 1500485909045 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
10:38:29 INFO - 1500485909047 Marionette DEBUG Closed connection 360
10:38:29 INFO - 1500485909049 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
10:38:29 INFO - 1500485909077 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:29 INFO - 1500485909079 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:29 INFO - 1500485909109 Marionette DEBUG Register listener.js for window 15
10:38:29 INFO - 1500485909120 Marionette TRACE 361 <- [1,1,null,{"sessionId":"7fe9a868-927a-4804-ad60-2b97b311452c","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:29 INFO - 1500485909131 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
10:38:29 INFO - 1500485909133 Marionette TRACE 361 <- [1,2,null,{}]
10:38:29 INFO - 1500485909140 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:29 INFO - 1500485909141 Marionette TRACE 361 <- [1,3,null,{}]
10:38:29 INFO - 1500485909143 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:29 INFO - 1500485909145 Marionette TRACE 361 <- [1,4,null,{}]
10:38:29 INFO - 1500485909970 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
10:38:29 INFO - TEST-START: test_click.py TestClick.test_container_element1500485909979 Marionette TRACE 361 <- [1,5,null,{"value":null}]
10:38:30 INFO - 1500485910025 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:30 INFO - 1500485910029 Marionette TRACE 361 <- [1,6,null,{}]
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:30 INFO - 1500485910245 Marionette DEBUG Closed connection 361
10:38:30 INFO - 1500485910246 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
10:38:30 INFO - 1500485910248 Marionette DEBUG Closed connection 362
10:38:30 INFO - 1500485910250 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
10:38:30 INFO - 1500485910256 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:30 INFO - 1500485910258 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:30 INFO - 1500485910279 Marionette DEBUG Register listener.js for window 15
10:38:30 INFO - 1500485910292 Marionette TRACE 363 <- [1,1,null,{"sessionId":"feddc8ac-8499-420f-9c30-a750010021ef","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:30 INFO - 1500485910302 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:30 INFO - 1500485910306 Marionette TRACE 363 <- [1,2,null,{}]
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:30 INFO - 1500485910351 Marionette DEBUG Closed connection 363
10:38:30 INFO - 1500485910352 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
10:38:30 INFO - 1500485910374 Marionette DEBUG Closed connection 364
10:38:30 INFO - 1500485910375 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
10:38:30 INFO - 1500485910381 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:30 INFO - 1500485910383 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:30 INFO - 1500485910405 Marionette DEBUG Register listener.js for window 15
10:38:30 INFO - 1500485910417 Marionette TRACE 365 <- [1,1,null,{"sessionId":"1a276547-6234-49c3-b7ce-9fae9656e739","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:30 INFO - 1500485910423 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:38:30 INFO - 1500485910427 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
10:38:30 INFO - 1500485910433 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
10:38:30 INFO - 1500485910434 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
10:38:30 INFO - ++DOMWINDOW == 30 (000000E08AA55000) [pid = 3472] [serial = 42] [outer = 000000E08DFC6800]
10:38:30 INFO - 1500485910523 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"
10:38:30 INFO - 1500485910528 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"
10:38:30 INFO - 1500485910549 Marionette TRACE 365 <- [1,2,null,{}]
10:38:30 INFO - 1500485910552 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:38:30 INFO - 1500485910559 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86e0fba2-8ec6-463f-b9b5-44c19762a93d","ELEMENT":"86e0fba2-8ec6-463f-b9b5-44c19762a93d"}}]
10:38:30 INFO - 1500485910561 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"86e0fba2-8ec6-463f-b9b5-44c19762a93d"}]
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
10:38:30 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
10:38:31 INFO - 1500485911082 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:38:31 INFO - 1500485911083 Marionette TRACE 365 <- [1,4,null,{}]
10:38:31 INFO - 1500485911087 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"86e0fba2-8ec6-463f-b9b5-44c19762a93d","name":"selected"}]
10:38:31 INFO - 1500485911117 Marionette TRACE 365 <- [1,5,null,{"value":true}]
10:38:31 INFO - 1500485911125 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
10:38:31 INFO - TEST-END: test_click.py TestClick.test_container_element1500485911133 Marionette TRACE 365 <- [1,6,null,{"value":null}]
10:38:31 INFO - 1500485911178 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911183 Marionette TRACE 365 <- [1,7,null,{}]
10:38:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 2146ms
10:38:31 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911210 Marionette DEBUG Closed connection 365
10:38:31 INFO - 1500485911212 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
10:38:31 INFO - 1500485911218 Marionette DEBUG Closed connection 366
10:38:31 INFO - 1500485911220 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
10:38:31 INFO - 1500485911226 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:31 INFO - 1500485911229 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:31 INFO - 1500485911251 Marionette DEBUG Register listener.js for window 15
10:38:31 INFO - 1500485911263 Marionette TRACE 367 <- [1,1,null,{"sessionId":"aa528cb7-52d3-493e-a118-dea44ef046c4","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:31 INFO - 1500485911269 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
10:38:31 INFO - 1500485911271 Marionette TRACE 367 <- [1,2,null,{}]
10:38:31 INFO - 1500485911273 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:31 INFO - 1500485911274 Marionette TRACE 367 <- [1,3,null,{}]
10:38:31 INFO - 1500485911276 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:31 INFO - 1500485911277 Marionette TRACE 367 <- [1,4,null,{}]
10:38:31 INFO - 1500485911280 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
10:38:31 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500485911287 Marionette TRACE 367 <- [1,5,null,{"value":null}]
10:38:31 INFO - 1500485911289 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911294 Marionette TRACE 367 <- [1,6,null,{}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911323 Marionette DEBUG Closed connection 367
10:38:31 INFO - 1500485911325 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
10:38:31 INFO - 1500485911329 Marionette DEBUG Closed connection 368
10:38:31 INFO - 1500485911330 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
10:38:31 INFO - 1500485911332 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:31 INFO - 1500485911334 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:31 INFO - 1500485911355 Marionette DEBUG Register listener.js for window 15
10:38:31 INFO - 1500485911371 Marionette TRACE 369 <- [1,1,null,{"sessionId":"6af5ec4a-8faf-40ed-a7f3-2c9274fd6580","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:31 INFO - --DOMWINDOW == 29 (000000E096316800) [pid = 3472] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 28 (000000E08DA6F000) [pid = 3472] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 27 (000000E08DFC3800) [pid = 3472] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 26 (000000E094022000) [pid = 3472] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 25 (000000E08CA14000) [pid = 3472] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 24 (000000E0861E0000) [pid = 3472] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 23 (000000E092AC9800) [pid = 3472] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - --DOMWINDOW == 22 (000000E0961D3800) [pid = 3472] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:38:31 INFO - 1500485911390 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911395 Marionette TRACE 369 <- [1,2,null,{}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:31 INFO - 1500485911418 Marionette DEBUG Closed connection 369
10:38:31 INFO - 1500485911420 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
10:38:31 INFO - 1500485911421 Marionette DEBUG Closed connection 370
10:38:31 INFO - 1500485911422 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
10:38:31 INFO - 1500485911424 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:31 INFO - 1500485911427 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:31 INFO - 1500485911448 Marionette DEBUG Register listener.js for window 15
10:38:31 INFO - 1500485911470 Marionette TRACE 371 <- [1,1,null,{"sessionId":"792ab132-1fcd-441e-8afa-eb99ca31b818","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:31 INFO - 1500485911477 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:38:31 INFO - 1500485911482 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"
10:38:31 INFO - 1500485911487 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"
10:38:31 INFO - 1500485911489 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"
10:38:31 INFO - ++DOMWINDOW == 23 (000000E08645B000) [pid = 3472] [serial = 43] [outer = 000000E08DFC6800]
10:38:31 INFO - 1500485911544 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"
10:38:31 INFO - 1500485911548 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"
10:38:31 INFO - 1500485911567 Marionette TRACE 371 <- [1,2,null,{}]
10:38:31 INFO - 1500485911570 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:38:31 INFO - 1500485911577 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a364519f-4e94-4160-9f88-901533fad2a5","ELEMENT":"a364519f-4e94-4160-9f88-901533fad2a5"}}]
10:38:31 INFO - 1500485911579 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"a364519f-4e94-4160-9f88-901533fad2a5"}]
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
10:38:31 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
10:38:32 INFO - 1500485912025 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:38:32 INFO - 1500485912027 Marionette TRACE 371 <- [1,4,null,{}]
10:38:32 INFO - 1500485912029 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"a364519f-4e94-4160-9f88-901533fad2a5","name":"selected"}]
10:38:32 INFO - 1500485912055 Marionette TRACE 371 <- [1,5,null,{"value":true}]
10:38:32 INFO - 1500485912058 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
10:38:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500485912065 Marionette TRACE 371 <- [1,6,null,{"value":null}]
10:38:32 INFO - 1500485912067 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912071 Marionette TRACE 371 <- [1,7,null,{}]
10:38:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 886ms
10:38:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912077 Marionette DEBUG Closed connection 371
10:38:32 INFO - 1500485912078 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
10:38:32 INFO - 1500485912080 Marionette DEBUG Closed connection 372
10:38:32 INFO - 1500485912081 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
10:38:32 INFO - 1500485912083 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:32 INFO - 1500485912085 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912106 Marionette DEBUG Register listener.js for window 15
10:38:32 INFO - 1500485912117 Marionette TRACE 373 <- [1,1,null,{"sessionId":"e6d76206-7a12-4e4f-a3bb-dd6ad6aa3e8a","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:32 INFO - 1500485912123 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
10:38:32 INFO - 1500485912125 Marionette TRACE 373 <- [1,2,null,{}]
10:38:32 INFO - 1500485912126 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:32 INFO - 1500485912128 Marionette TRACE 373 <- [1,3,null,{}]
10:38:32 INFO - 1500485912132 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:32 INFO - 1500485912134 Marionette TRACE 373 <- [1,4,null,{}]
10:38:32 INFO - 1500485912137 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
10:38:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500485912144 Marionette TRACE 373 <- [1,5,null,{"value":null}]
10:38:32 INFO - 1500485912146 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912151 Marionette TRACE 373 <- [1,6,null,{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912157 Marionette DEBUG Closed connection 373
10:38:32 INFO - 1500485912158 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
10:38:32 INFO - 1500485912160 Marionette DEBUG Closed connection 374
10:38:32 INFO - 1500485912161 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
10:38:32 INFO - 1500485912163 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:32 INFO - 1500485912165 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912186 Marionette DEBUG Register listener.js for window 15
10:38:32 INFO - 1500485912197 Marionette TRACE 375 <- [1,1,null,{"sessionId":"47e8ae74-7e3e-49cb-a96a-b14a71c29758","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:32 INFO - 1500485912203 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912207 Marionette TRACE 375 <- [1,2,null,{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912213 Marionette DEBUG Closed connection 375
10:38:32 INFO - 1500485912214 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
10:38:32 INFO - 1500485912216 Marionette DEBUG Closed connection 376
10:38:32 INFO - 1500485912217 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
10:38:32 INFO - 1500485912219 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:32 INFO - 1500485912222 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912243 Marionette DEBUG Register listener.js for window 15
10:38:32 INFO - 1500485912255 Marionette TRACE 377 <- [1,1,null,{"sessionId":"482c6629-aefe-499e-b988-16fc8a260905","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:32 INFO - 1500485912261 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
10:38:32 INFO - 1500485912265 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"
10:38:32 INFO - 1500485912271 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"
10:38:32 INFO - 1500485912272 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"
10:38:32 INFO - ++DOMWINDOW == 24 (000000E091B24000) [pid = 3472] [serial = 44] [outer = 000000E08DFC6800]
10:38:32 INFO - 1500485912325 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"
10:38:32 INFO - 1500485912329 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"
10:38:32 INFO - 1500485912346 Marionette TRACE 377 <- [1,2,null,{}]
10:38:32 INFO - 1500485912350 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:38:32 INFO - 1500485912356 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0621bdd3-48eb-4f77-bef2-0b3eaa97c9ce","ELEMENT":"0621bdd3-48eb-4f77-bef2-0b3eaa97c9ce"}}]
10:38:32 INFO - 1500485912358 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"0621bdd3-48eb-4f77-bef2-0b3eaa97c9ce"}]
10:38:32 INFO - 1500485912808 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:38:32 INFO - 1500485912809 Marionette TRACE 377 <- [1,4,null,{}]
10:38:32 INFO - 1500485912813 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
10:38:32 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500485912820 Marionette TRACE 377 <- [1,5,null,{"value":null}]
10:38:32 INFO - 1500485912822 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912827 Marionette TRACE 377 <- [1,6,null,{}]
10:38:32 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 754ms
10:38:32 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912832 Marionette DEBUG Closed connection 377
10:38:32 INFO - 1500485912834 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
10:38:32 INFO - 1500485912835 Marionette DEBUG Closed connection 378
10:38:32 INFO - 1500485912837 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
10:38:32 INFO - 1500485912839 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:32 INFO - 1500485912841 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912862 Marionette DEBUG Register listener.js for window 15
10:38:32 INFO - 1500485912872 Marionette TRACE 379 <- [1,1,null,{"sessionId":"ee277ba8-f924-44fd-bb36-d9196743e9e3","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:32 INFO - 1500485912878 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
10:38:32 INFO - 1500485912880 Marionette TRACE 379 <- [1,2,null,{}]
10:38:32 INFO - 1500485912881 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:32 INFO - 1500485912883 Marionette TRACE 379 <- [1,3,null,{}]
10:38:32 INFO - 1500485912885 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:32 INFO - 1500485912886 Marionette TRACE 379 <- [1,4,null,{}]
10:38:32 INFO - 1500485912888 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
10:38:32 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500485912895 Marionette TRACE 379 <- [1,5,null,{"value":null}]
10:38:32 INFO - 1500485912897 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912901 Marionette TRACE 379 <- [1,6,null,{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912907 Marionette DEBUG Closed connection 379
10:38:32 INFO - 1500485912909 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
10:38:32 INFO - 1500485912910 Marionette DEBUG Closed connection 380
10:38:32 INFO - 1500485912912 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
10:38:32 INFO - 1500485912916 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:32 INFO - 1500485912918 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912940 Marionette DEBUG Register listener.js for window 15
10:38:32 INFO - 1500485912952 Marionette TRACE 381 <- [1,1,null,{"sessionId":"f6af9c37-6cec-402b-af9e-5111f7fc2fc7","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:32 INFO - 1500485912958 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912962 Marionette TRACE 381 <- [1,2,null,{}]
10:38:32 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:32 INFO - 1500485912968 Marionette DEBUG Closed connection 381
10:38:32 INFO - 1500485912969 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
10:38:32 INFO - 1500485912971 Marionette DEBUG Closed connection 382
10:38:32 INFO - 1500485912972 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
10:38:32 INFO - 1500485912974 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:32 INFO - 1500485912977 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:32 INFO - 1500485912998 Marionette DEBUG Register listener.js for window 15
10:38:33 INFO - 1500485913009 Marionette TRACE 383 <- [1,1,null,{"sessionId":"fc653e52-f018-4c72-91a0-be8fbe67c46b","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:33 INFO - 1500485913015 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:38:33 INFO - 1500485913018 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"
10:38:33 INFO - 1500485913023 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"
10:38:33 INFO - 1500485913024 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"
10:38:33 INFO - ++DOMWINDOW == 25 (000000E092C84800) [pid = 3472] [serial = 45] [outer = 000000E08DFC6800]
10:38:33 INFO - 1500485913075 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"
10:38:33 INFO - 1500485913080 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"
10:38:33 INFO - 1500485913097 Marionette TRACE 383 <- [1,2,null,{}]
10:38:33 INFO - 1500485913101 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:38:33 INFO - 1500485913107 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aed1bd0c-0a97-454b-9abb-44a224fc407b","ELEMENT":"aed1bd0c-0a97-454b-9abb-44a224fc407b"}}]
10:38:33 INFO - 1500485913109 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"aed1bd0c-0a97-454b-9abb-44a224fc407b"}]
10:38:33 INFO - 1500485913536 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:38:33 INFO - 1500485913537 Marionette TRACE 383 <- [1,4,null,{}]
10:38:33 INFO - 1500485913540 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
10:38:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500485913547 Marionette TRACE 383 <- [1,5,null,{"value":null}]
10:38:33 INFO - 1500485913549 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913553 Marionette TRACE 383 <- [1,6,null,{}]
10:38:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 726ms
10:38:33 INFO - TEST-START | test_click.py TestClick.test_input_file
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913559 Marionette DEBUG Closed connection 383
10:38:33 INFO - 1500485913560 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
10:38:33 INFO - 1500485913562 Marionette DEBUG Closed connection 384
10:38:33 INFO - 1500485913564 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
10:38:33 INFO - 1500485913566 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:33 INFO - 1500485913568 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:33 INFO - 1500485913589 Marionette DEBUG Register listener.js for window 15
10:38:33 INFO - 1500485913601 Marionette TRACE 385 <- [1,1,null,{"sessionId":"d65f42b3-b80d-408b-94ae-a3a662daec88","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:33 INFO - 1500485913607 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
10:38:33 INFO - 1500485913608 Marionette TRACE 385 <- [1,2,null,{}]
10:38:33 INFO - 1500485913610 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:33 INFO - 1500485913612 Marionette TRACE 385 <- [1,3,null,{}]
10:38:33 INFO - 1500485913614 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:33 INFO - 1500485913615 Marionette TRACE 385 <- [1,4,null,{}]
10:38:33 INFO - 1500485913617 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
10:38:33 INFO - TEST-START: test_click.py TestClick.test_input_file1500485913624 Marionette TRACE 385 <- [1,5,null,{"value":null}]
10:38:33 INFO - 1500485913626 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913630 Marionette TRACE 385 <- [1,6,null,{}]
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913636 Marionette DEBUG Closed connection 385
10:38:33 INFO - 1500485913637 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
10:38:33 INFO - 1500485913639 Marionette DEBUG Closed connection 386
10:38:33 INFO - 1500485913641 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
10:38:33 INFO - 1500485913644 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:33 INFO - 1500485913646 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:33 INFO - 1500485913667 Marionette DEBUG Register listener.js for window 15
10:38:33 INFO - 1500485913681 Marionette TRACE 387 <- [1,1,null,{"sessionId":"6e440719-0766-456f-8ea5-f326328250a0","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:33 INFO - 1500485913688 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913692 Marionette TRACE 387 <- [1,2,null,{}]
10:38:33 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:33 INFO - 1500485913697 Marionette DEBUG Closed connection 387
10:38:33 INFO - 1500485913699 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
10:38:33 INFO - 1500485913700 Marionette DEBUG Closed connection 388
10:38:33 INFO - 1500485913702 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
10:38:33 INFO - 1500485913704 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:33 INFO - 1500485913707 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:33 INFO - 1500485913729 Marionette DEBUG Register listener.js for window 15
10:38:33 INFO - 1500485913752 Marionette TRACE 389 <- [1,1,null,{"sessionId":"ad8eca30-5807-4a2c-9cef-f2f8696e323f","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:33 INFO - 1500485913759 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:38:33 INFO - 1500485913763 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"
10:38:33 INFO - 1500485913767 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"
10:38:33 INFO - 1500485913769 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"
10:38:33 INFO - ++DOMWINDOW == 26 (000000E08CFC4000) [pid = 3472] [serial = 46] [outer = 000000E08DFC6800]
10:38:33 INFO - 1500485913820 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:38:33 INFO - 1500485913825 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:38:33 INFO - 1500485913833 Marionette TRACE 389 <- [1,2,null,{}]
10:38:33 INFO - 1500485913842 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:38:33 INFO - 1500485913850 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f6515a9-a3f5-4e94-9a55-47dba8f7f70d","ELEMENT":"4f6515a9-a3f5-4e94-9a55-47dba8f7f70d"}}]
10:38:33 INFO - 1500485913852 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"4f6515a9-a3f5-4e94-9a55-47dba8f7f70d"}]
10:38:34 INFO - 1500485913998 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:38:34 INFO - 1500485914000 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
10:38:34 INFO - TEST-END: test_click.py TestClick.test_input_file1500485914007 Marionette TRACE 389 <- [1,5,null,{"value":null}]
10:38:34 INFO - 1500485914009 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914013 Marionette TRACE 389 <- [1,6,null,{}]
10:38:34 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 459ms
10:38:34 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914019 Marionette DEBUG Closed connection 389
10:38:34 INFO - 1500485914020 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
10:38:34 INFO - 1500485914034 Marionette DEBUG Closed connection 390
10:38:34 INFO - 1500485914035 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
10:38:34 INFO - 1500485914038 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:34 INFO - 1500485914040 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:34 INFO - 1500485914062 Marionette DEBUG Register listener.js for window 15
10:38:34 INFO - 1500485914073 Marionette TRACE 391 <- [1,1,null,{"sessionId":"96893b7a-f75b-4a8f-956d-2aad16cea38c","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:34 INFO - 1500485914079 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
10:38:34 INFO - 1500485914080 Marionette TRACE 391 <- [1,2,null,{}]
10:38:34 INFO - 1500485914082 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:34 INFO - 1500485914084 Marionette TRACE 391 <- [1,3,null,{}]
10:38:34 INFO - 1500485914085 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:34 INFO - 1500485914086 Marionette TRACE 391 <- [1,4,null,{}]
10:38:34 INFO - 1500485914089 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
10:38:34 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500485914096 Marionette TRACE 391 <- [1,5,null,{"value":null}]
10:38:34 INFO - 1500485914098 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914102 Marionette TRACE 391 <- [1,6,null,{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914108 Marionette DEBUG Closed connection 391
10:38:34 INFO - 1500485914109 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
10:38:34 INFO - 1500485914111 Marionette DEBUG Closed connection 392
10:38:34 INFO - 1500485914112 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
10:38:34 INFO - 1500485914114 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:34 INFO - 1500485914117 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:34 INFO - 1500485914139 Marionette DEBUG Register listener.js for window 15
10:38:34 INFO - 1500485914150 Marionette TRACE 393 <- [1,1,null,{"sessionId":"0d25d39b-9182-42a9-b015-a570dbb6a130","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:34 INFO - 1500485914156 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914160 Marionette TRACE 393 <- [1,2,null,{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914166 Marionette DEBUG Closed connection 393
10:38:34 INFO - 1500485914167 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
10:38:34 INFO - 1500485914169 Marionette DEBUG Closed connection 394
10:38:34 INFO - 1500485914170 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
10:38:34 INFO - 1500485914172 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:34 INFO - 1500485914175 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:34 INFO - 1500485914197 Marionette DEBUG Register listener.js for window 15
10:38:34 INFO - 1500485914207 Marionette TRACE 395 <- [1,1,null,{"sessionId":"0f558d68-4d67-4f3b-b484-37c8f3d4893c","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:34 INFO - 1500485914214 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
10:38:34 INFO - 1500485914217 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:38:34 INFO - 1500485914222 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:38:34 INFO - 1500485914223 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:38:34 INFO - ++DOMWINDOW == 27 (000000E092DAE000) [pid = 3472] [serial = 47] [outer = 000000E08DFC6800]
10:38:34 INFO - 1500485914277 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"
10:38:34 INFO - 1500485914282 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"
10:38:34 INFO - 1500485914301 Marionette TRACE 395 <- [1,2,null,{}]
10:38:34 INFO - 1500485914304 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:38:34 INFO - 1500485914310 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f46756e-7c34-4966-8076-d40ed4c1bb1d","ELEMENT":"5f46756e-7c34-4966-8076-d40ed4c1bb1d"}}]
10:38:34 INFO - 1500485914313 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:38:34 INFO - 1500485914319 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce107e9d-b1a0-4f29-98dd-8e0ac8afbe31","ELEMENT":"ce107e9d-b1a0-4f29-98dd-8e0ac8afbe31"}}]
10:38:34 INFO - 1500485914321 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"5f46756e-7c34-4966-8076-d40ed4c1bb1d"}]
10:38:34 INFO - 1500485914763 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:38:34 INFO - 1500485914764 Marionette TRACE 395 <- [1,5,null,{}]
10:38:34 INFO - 1500485914766 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"ce107e9d-b1a0-4f29-98dd-8e0ac8afbe31"}]
10:38:34 INFO - 1500485914895 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:38:34 INFO - 1500485914898 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:38:34 INFO - 1500485914905 Marionette TRACE 395 <- [1,7,null,{"value":false}]
10:38:34 INFO - 1500485914907 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
10:38:34 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500485914914 Marionette TRACE 395 <- [1,8,null,{"value":null}]
10:38:34 INFO - 1500485914916 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914920 Marionette TRACE 395 <- [1,9,null,{}]
10:38:34 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 906ms
10:38:34 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914926 Marionette DEBUG Closed connection 395
10:38:34 INFO - 1500485914927 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
10:38:34 INFO - 1500485914929 Marionette DEBUG Closed connection 396
10:38:34 INFO - 1500485914930 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
10:38:34 INFO - 1500485914933 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:34 INFO - 1500485914935 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:34 INFO - 1500485914957 Marionette DEBUG Register listener.js for window 15
10:38:34 INFO - 1500485914970 Marionette TRACE 397 <- [1,1,null,{"sessionId":"974d47f5-a40f-4be0-877e-fe3e1026d1e4","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:34 INFO - 1500485914975 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
10:38:34 INFO - 1500485914977 Marionette TRACE 397 <- [1,2,null,{}]
10:38:34 INFO - 1500485914978 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:38:34 INFO - 1500485914980 Marionette TRACE 397 <- [1,3,null,{}]
10:38:34 INFO - 1500485914981 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
10:38:34 INFO - 1500485914983 Marionette TRACE 397 <- [1,4,null,{}]
10:38:34 INFO - 1500485914985 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
10:38:34 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500485914992 Marionette TRACE 397 <- [1,5,null,{"value":null}]
10:38:34 INFO - 1500485914994 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
10:38:34 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:34 INFO - 1500485914998 Marionette TRACE 397 <- [1,6,null,{}]
10:38:35 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:35 INFO - 1500485915004 Marionette DEBUG Closed connection 397
10:38:35 INFO - 1500485915005 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
10:38:35 INFO - 1500485915006 Marionette DEBUG Closed connection 398
10:38:35 INFO - 1500485915008 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
10:38:35 INFO - 1500485915010 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:38:35 INFO - 1500485915012 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:35 INFO - 1500485915035 Marionette DEBUG Register listener.js for window 15
10:38:35 INFO - 1500485915046 Marionette TRACE 399 <- [1,1,null,{"sessionId":"32e50da0-b713-4b3d-98f6-74d82ae72bbb","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:35 INFO - 1500485915052 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
10:38:35 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:35 INFO - 1500485915056 Marionette TRACE 399 <- [1,2,null,{}]
10:38:35 INFO - [Parent 3472] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:38:35 INFO - 1500485915062 Marionette DEBUG Closed connection 399
10:38:35 INFO - 1500485915063 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
10:38:35 INFO - 1500485915065 Marionette DEBUG Closed connection 400
10:38:35 INFO - 1500485915066 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49831
10:38:35 INFO - 1500485915068 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:38:35 INFO - 1500485915071 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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}
10:38:35 INFO - 1500485915094 Marionette DEBUG Register listener.js for window 15
10:38:35 INFO - 1500485915105 Marionette TRACE 401 <- [1,1,null,{"sessionId":"d837bfe3-202f-4cb4-a764-fa0488189832","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":3472,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpwiqwnn.mozrunner","moz:accessibilityChecks":false}}]
10:38:35 INFO - 1500485915111 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
10:38:35 INFO - 1500485915115 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"
10:38:35 INFO - 1500485915120 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"
10:38:35 INFO - 1500485915121 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"
10:38:35 INFO - ++DOMWINDOW == 28 (000000E081EA6000) [pid = 3472] [serial = 48] [outer = 000000E08DFC6800]
10:38:35 INFO - 1500485915180 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"
10:38:35 INFO - 1500485915184 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"
10:38:35 INFO - 1500485915199 Marionette TRACE 401 <- [1,2,null,{}]
10:38:35 INFO - 1500485915201 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:38:35 INFO - 1500485915207 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ca74efc-5499-4d69-a6af-9fdaaed842cb","ELEMENT":"5ca74efc-5499-4d69-a6af-9fdaaed842cb"}}]
10:38:35 INFO - 1500485915209 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5ca74efc-5499-4d69-a6af-9fdaaed842cb"}]
10:38:35 INFO - 1500485915354 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
10:38:35 INFO - 1500485915358 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"5ca74efc-5499-4d69-a6af-9fdaaed842cb"}]
10:38:35 INFO - 1500485915497 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element