could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:21:33 INFO - 1501039293970 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}]
20:21:33 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501039293978 Marionette TRACE 353 <- [1,5,null,{"value":null}]
20:21:33 INFO - 1501039293981 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
20:21:33 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:33 INFO - 1501039293986 Marionette TRACE 353 <- [1,6,null,{}]
20:21:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1285ms
20:21:33 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:21:33 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:33 INFO - 1501039293992 Marionette DEBUG Closed connection 353
20:21:33 INFO - 1501039293993 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49777
20:21:33 INFO - 1501039293995 Marionette DEBUG Closed connection 354
20:21:33 INFO - 1501039293996 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49778
20:21:34 INFO - 1501039293999 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:34 INFO - 1501039294001 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:34 INFO - 1501039294021 Marionette DEBUG Register listener.js for window 12
20:21:34 INFO - 1501039294032 Marionette TRACE 355 <- [1,1,null,{"sessionId":"c1514fb3-3455-46fb-aca4-2340b14c0fd9","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:34 INFO - 1501039294057 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
20:21:34 INFO - 1501039294059 Marionette TRACE 355 <- [1,2,null,{}]
20:21:34 INFO - 1501039294062 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:34 INFO - 1501039294063 Marionette TRACE 355 <- [1,3,null,{}]
20:21:34 INFO - 1501039294065 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:34 INFO - 1501039294067 Marionette TRACE 355 <- [1,4,null,{}]
20:21:34 INFO - 1501039294069 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}]
20:21:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501039294080 Marionette TRACE 355 <- [1,5,null,{"value":null}]
20:21:34 INFO - 1501039294082 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294086 Marionette TRACE 355 <- [1,6,null,{}]
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294108 Marionette DEBUG Closed connection 355
20:21:34 INFO - 1501039294110 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49779
20:21:34 INFO - 1501039294135 Marionette DEBUG Closed connection 356
20:21:34 INFO - 1501039294137 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49780
20:21:34 INFO - 1501039294139 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:34 INFO - 1501039294141 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:34 INFO - 1501039294161 Marionette DEBUG Register listener.js for window 12
20:21:34 INFO - 1501039294173 Marionette TRACE 357 <- [1,1,null,{"sessionId":"e1a77c47-dd3a-42b8-8edf-45c3e80d129a","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:34 INFO - 1501039294183 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294187 Marionette TRACE 357 <- [1,2,null,{}]
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294206 Marionette DEBUG Closed connection 357
20:21:34 INFO - 1501039294208 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49781
20:21:34 INFO - 1501039294212 Marionette DEBUG Closed connection 358
20:21:34 INFO - 1501039294213 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49782
20:21:34 INFO - 1501039294216 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:34 INFO - 1501039294218 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:34 INFO - 1501039294238 Marionette DEBUG Register listener.js for window 12
20:21:34 INFO - 1501039294249 Marionette TRACE 359 <- [1,1,null,{"sessionId":"050293db-6299-4cee-859a-85711351c936","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:34 INFO - 1501039294258 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
20:21:34 INFO - 1501039294262 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
20:21:34 INFO - 1501039294281 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
20:21:34 INFO - 1501039294282 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
20:21:34 INFO - ++DOMWINDOW == 21 (000000F2F8EAC000) [pid = 1852] [serial = 41] [outer = 000000F284B56800]
20:21:34 INFO - 1501039294339 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
20:21:34 INFO - 1501039294345 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
20:21:34 INFO - 1501039294364 Marionette TRACE 359 <- [1,2,null,{}]
20:21:34 INFO - 1501039294366 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:21:34 INFO - 1501039294391 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3419154b-eda0-4a3b-8080-cb53866fa936","ELEMENT":"3419154b-eda0-4a3b-8080-cb53866fa936"}}]
20:21:34 INFO - 1501039294400 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"3419154b-eda0-4a3b-8080-cb53866fa936"}]
20:21:34 INFO - 1501039294708 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
20:21:34 INFO - [Parent 1852] 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
20:21:34 INFO - 1501039294734 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
20:21:34 INFO - 1501039294736 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
20:21:34 INFO - ++DOMWINDOW == 22 (000000F2827ED000) [pid = 1852] [serial = 42] [outer = 000000F284B56800]
20:21:34 INFO - 1501039294819 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
20:21:34 INFO - 1501039294827 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
20:21:34 INFO - 1501039294866 Marionette TRACE 359 <- [1,4,null,{}]
20:21:34 INFO - 1501039294901 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:21:34 INFO - 1501039294914 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f163dcd0-10b3-47dd-a1e1-5733b44509ad","ELEMENT":"f163dcd0-10b3-47dd-a1e1-5733b44509ad"}}]
20:21:34 INFO - 1501039294918 Marionette TRACE 359 -> [0,6,"getTitle",{}]
20:21:34 INFO - 1501039294924 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
20:21:34 INFO - 1501039294928 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}]
20:21:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501039294941 Marionette TRACE 359 <- [1,7,null,{"value":null}]
20:21:34 INFO - 1501039294946 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294955 Marionette TRACE 359 <- [1,8,null,{}]
20:21:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 981ms
20:21:34 INFO - TEST-START | test_click.py TestClick.test_container_element
20:21:34 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:34 INFO - 1501039294976 Marionette DEBUG Closed connection 359
20:21:34 INFO - 1501039294979 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49785
20:21:34 INFO - 1501039294982 Marionette DEBUG Closed connection 360
20:21:34 INFO - 1501039294984 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49786
20:21:35 INFO - 1501039295007 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:35 INFO - 1501039295017 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:35 INFO - 1501039295055 Marionette DEBUG Register listener.js for window 12
20:21:35 INFO - 1501039295075 Marionette TRACE 361 <- [1,1,null,{"sessionId":"6d81f647-1bb3-4cf6-b9ba-08f960e060f8","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:35 INFO - 1501039295099 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
20:21:35 INFO - 1501039295102 Marionette TRACE 361 <- [1,2,null,{}]
20:21:35 INFO - 1501039295108 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:35 INFO - 1501039295111 Marionette TRACE 361 <- [1,3,null,{}]
20:21:35 INFO - 1501039295115 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:35 INFO - 1501039295118 Marionette TRACE 361 <- [1,4,null,{}]
20:21:35 INFO - 1501039295171 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}]
20:21:35 INFO - TEST-START: test_click.py TestClick.test_container_element1501039295183 Marionette TRACE 361 <- [1,5,null,{"value":null}]
20:21:35 INFO - 1501039295192 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:35 INFO - 1501039295200 Marionette TRACE 361 <- [1,6,null,{}]
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:35 INFO - 1501039295223 Marionette DEBUG Closed connection 361
20:21:35 INFO - 1501039295227 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49787
20:21:35 INFO - 1501039295279 Marionette DEBUG Closed connection 362
20:21:35 INFO - 1501039295281 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49788
20:21:35 INFO - 1501039295286 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:35 INFO - 1501039295290 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:35 INFO - 1501039295328 Marionette DEBUG Register listener.js for window 12
20:21:35 INFO - 1501039295344 Marionette TRACE 363 <- [1,1,null,{"sessionId":"00e0a578-6f67-4b29-805b-8172d8754113","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:35 INFO - 1501039295353 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:35 INFO - 1501039295360 Marionette TRACE 363 <- [1,2,null,{}]
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:35 INFO - 1501039295367 Marionette DEBUG Closed connection 363
20:21:35 INFO - 1501039295369 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49789
20:21:35 INFO - 1501039295371 Marionette DEBUG Closed connection 364
20:21:35 INFO - 1501039295374 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49790
20:21:35 INFO - 1501039295376 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:35 INFO - 1501039295380 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:35 INFO - 1501039295419 Marionette DEBUG Register listener.js for window 12
20:21:35 INFO - 1501039295436 Marionette TRACE 365 <- [1,1,null,{"sessionId":"d3ed8c2c-3aea-4493-82c2-86a8ff12dd77","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:35 INFO - 1501039295455 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"}]
20:21:35 INFO - 1501039295462 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
20:21:35 INFO - 1501039295471 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
20:21:35 INFO - 1501039295472 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
20:21:35 INFO - ++DOMWINDOW == 23 (000000F28DB3C800) [pid = 1852] [serial = 43] [outer = 000000F284B56800]
20:21:35 INFO - 1501039295545 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"
20:21:35 INFO - 1501039295550 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"
20:21:35 INFO - 1501039295571 Marionette TRACE 365 <- [1,2,null,{}]
20:21:35 INFO - 1501039295575 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:21:35 INFO - 1501039295585 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79a3962d-dffc-4ec5-8698-52c665456ea5","ELEMENT":"79a3962d-dffc-4ec5-8698-52c665456ea5"}}]
20:21:35 INFO - 1501039295629 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"79a3962d-dffc-4ec5-8698-52c665456ea5"}]
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
20:21:35 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
20:21:36 INFO - 1501039296132 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:36 INFO - 1501039296135 Marionette TRACE 365 <- [1,4,null,{}]
20:21:36 INFO - 1501039296137 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"79a3962d-dffc-4ec5-8698-52c665456ea5","name":"selected"}]
20:21:36 INFO - 1501039296177 Marionette TRACE 365 <- [1,5,null,{"value":true}]
20:21:36 INFO - 1501039296320 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}]
20:21:36 INFO - TEST-END: test_click.py TestClick.test_container_element1501039296332 Marionette TRACE 365 <- [1,6,null,{"value":null}]
20:21:36 INFO - 1501039296343 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1388ms
20:21:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:21:36 INFO - 1501039296354 Marionette TRACE 365 <- [1,7,null,{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - 1501039296377 Marionette DEBUG Closed connection 365
20:21:36 INFO - 1501039296380 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49791
20:21:36 INFO - 1501039296435 Marionette DEBUG Closed connection 366
20:21:36 INFO - 1501039296437 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49792
20:21:36 INFO - 1501039296446 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:36 INFO - 1501039296455 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:36 INFO - 1501039296480 Marionette DEBUG Register listener.js for window 12
20:21:36 INFO - 1501039296501 Marionette TRACE 367 <- [1,1,null,{"sessionId":"80bb18dc-f7ad-41fb-b5c6-849326d53035","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:36 INFO - 1501039296517 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
20:21:36 INFO - 1501039296520 Marionette TRACE 367 <- [1,2,null,{}]
20:21:36 INFO - 1501039296540 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:36 INFO - 1501039296543 Marionette TRACE 367 <- [1,3,null,{}]
20:21:36 INFO - 1501039296548 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:36 INFO - 1501039296551 Marionette TRACE 367 <- [1,4,null,{}]
20:21:36 INFO - 1501039296555 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}]
20:21:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501039296569 Marionette TRACE 367 <- [1,5,null,{"value":null}]
20:21:36 INFO - 1501039296573 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - 1501039296579 Marionette TRACE 367 <- [1,6,null,{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - 1501039296603 Marionette DEBUG Closed connection 367
20:21:36 INFO - 1501039296606 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49793
20:21:36 INFO - 1501039296610 Marionette DEBUG Closed connection 368
20:21:36 INFO - 1501039296612 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49794
20:21:36 INFO - 1501039296617 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:36 INFO - 1501039296621 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:36 INFO - 1501039296643 Marionette DEBUG Register listener.js for window 12
20:21:36 INFO - 1501039296659 Marionette TRACE 369 <- [1,1,null,{"sessionId":"144397be-90be-40c3-8994-709bebc04442","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:36 INFO - 1501039296668 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - 1501039296676 Marionette TRACE 369 <- [1,2,null,{}]
20:21:36 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:36 INFO - 1501039296700 Marionette DEBUG Closed connection 369
20:21:36 INFO - 1501039296702 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49795
20:21:36 INFO - 1501039296707 Marionette DEBUG Closed connection 370
20:21:36 INFO - 1501039296709 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49796
20:21:36 INFO - 1501039296721 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:36 INFO - 1501039296727 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:36 INFO - 1501039296749 Marionette DEBUG Register listener.js for window 12
20:21:36 INFO - 1501039296766 Marionette TRACE 371 <- [1,1,null,{"sessionId":"bd8ff813-2ed7-4a75-9317-07046a8070c3","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:36 INFO - 1501039296781 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"}]
20:21:36 INFO - 1501039296786 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"
20:21:36 INFO - 1501039296795 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"
20:21:36 INFO - 1501039296796 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"
20:21:36 INFO - ++DOMWINDOW == 24 (000000F284307000) [pid = 1852] [serial = 44] [outer = 000000F284B56800]
20:21:36 INFO - 1501039296859 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"
20:21:36 INFO - 1501039296865 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"
20:21:36 INFO - 1501039296887 Marionette TRACE 371 <- [1,2,null,{}]
20:21:36 INFO - 1501039296890 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:21:36 INFO - 1501039296901 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2dc334c-1bc2-486b-8ff0-5bb69229bf4b","ELEMENT":"f2dc334c-1bc2-486b-8ff0-5bb69229bf4b"}}]
20:21:36 INFO - 1501039296903 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"f2dc334c-1bc2-486b-8ff0-5bb69229bf4b"}]
20:21:37 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
20:21:37 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
20:21:37 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
20:21:37 INFO - --DOMWINDOW == 23 (000000F2FAF13800) [pid = 1852] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
20:21:37 INFO - --DOMWINDOW == 22 (000000F2F7E2A800) [pid = 1852] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
20:21:37 INFO - --DOMWINDOW == 21 (000000F286086800) [pid = 1852] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
20:21:37 INFO - --DOMWINDOW == 20 (000000F28D629000) [pid = 1852] [serial = 33] [outer = 0000000000000000] [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%20position%3A%20absolute%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%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%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]
20:21:37 INFO - 1501039297889 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:37 INFO - 1501039297924 Marionette TRACE 371 <- [1,4,null,{}]
20:21:37 INFO - 1501039297930 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"f2dc334c-1bc2-486b-8ff0-5bb69229bf4b","name":"selected"}]
20:21:37 INFO - 1501039297952 Marionette TRACE 371 <- [1,5,null,{"value":true}]
20:21:37 INFO - 1501039297954 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}]
20:21:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501039298000 Marionette TRACE 371 <- [1,6,null,{"value":null}]
20:21:38 INFO - 1501039298045 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298050 Marionette TRACE 371 <- [1,7,null,{}]
20:21:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1695ms
20:21:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298096 Marionette DEBUG Closed connection 371
20:21:38 INFO - 1501039298098 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49797
20:21:38 INFO - 1501039298144 Marionette DEBUG Closed connection 372
20:21:38 INFO - 1501039298147 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49798
20:21:38 INFO - 1501039298167 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:38 INFO - 1501039298170 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:38 INFO - 1501039298194 Marionette DEBUG Register listener.js for window 12
20:21:38 INFO - 1501039298210 Marionette TRACE 373 <- [1,1,null,{"sessionId":"5259f42c-5a5b-4ba4-a86f-cdf5a318600a","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:38 INFO - 1501039298231 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
20:21:38 INFO - 1501039298233 Marionette TRACE 373 <- [1,2,null,{}]
20:21:38 INFO - 1501039298236 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:38 INFO - 1501039298238 Marionette TRACE 373 <- [1,3,null,{}]
20:21:38 INFO - 1501039298240 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:38 INFO - 1501039298242 Marionette TRACE 373 <- [1,4,null,{}]
20:21:38 INFO - 1501039298245 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}]
20:21:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501039298254 Marionette TRACE 373 <- [1,5,null,{"value":null}]
20:21:38 INFO - 1501039298258 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298262 Marionette TRACE 373 <- [1,6,null,{}]
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298283 Marionette DEBUG Closed connection 373
20:21:38 INFO - 1501039298285 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49799
20:21:38 INFO - 1501039298289 Marionette DEBUG Closed connection 374
20:21:38 INFO - 1501039298291 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49800
20:21:38 INFO - 1501039298293 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:38 INFO - 1501039298299 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:38 INFO - 1501039298320 Marionette DEBUG Register listener.js for window 12
20:21:38 INFO - 1501039298341 Marionette TRACE 375 <- [1,1,null,{"sessionId":"71369e19-71a1-4cbc-91fa-e04168dcda9e","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:38 INFO - 1501039298351 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298357 Marionette TRACE 375 <- [1,2,null,{}]
20:21:38 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:38 INFO - 1501039298379 Marionette DEBUG Closed connection 375
20:21:38 INFO - 1501039298385 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49801
20:21:38 INFO - 1501039298389 Marionette DEBUG Closed connection 376
20:21:38 INFO - 1501039298391 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49802
20:21:38 INFO - 1501039298394 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:38 INFO - 1501039298396 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:38 INFO - 1501039298419 Marionette DEBUG Register listener.js for window 12
20:21:38 INFO - 1501039298434 Marionette TRACE 377 <- [1,1,null,{"sessionId":"9aa06fd7-5dd0-4dbf-9372-32fbbbc9bc0f","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:38 INFO - 1501039298444 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"}]
20:21:38 INFO - 1501039298448 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"
20:21:38 INFO - 1501039298457 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"
20:21:38 INFO - 1501039298458 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"
20:21:38 INFO - ++DOMWINDOW == 21 (000000F2F828F800) [pid = 1852] [serial = 45] [outer = 000000F284B56800]
20:21:38 INFO - 1501039298522 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"
20:21:38 INFO - 1501039298527 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"
20:21:38 INFO - 1501039298548 Marionette TRACE 377 <- [1,2,null,{}]
20:21:38 INFO - 1501039298555 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:21:38 INFO - 1501039298564 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27b428f6-5948-4084-ae44-c590b36fad25","ELEMENT":"27b428f6-5948-4084-ae44-c590b36fad25"}}]
20:21:38 INFO - 1501039298566 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"27b428f6-5948-4084-ae44-c590b36fad25"}]
20:21:38 INFO - 1501039298988 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:39 INFO - 1501039298992 Marionette TRACE 377 <- [1,4,null,{}]
20:21:39 INFO - 1501039298995 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}]
20:21:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501039299003 Marionette TRACE 377 <- [1,5,null,{"value":null}]
20:21:39 INFO - 1501039299005 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299010 Marionette TRACE 377 <- [1,6,null,{}]
20:21:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 960ms
20:21:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299016 Marionette DEBUG Closed connection 377
20:21:39 INFO - 1501039299018 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49803
20:21:39 INFO - 1501039299019 Marionette DEBUG Closed connection 378
20:21:39 INFO - 1501039299021 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49804
20:21:39 INFO - 1501039299023 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:39 INFO - 1501039299025 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:39 INFO - 1501039299046 Marionette DEBUG Register listener.js for window 12
20:21:39 INFO - 1501039299058 Marionette TRACE 379 <- [1,1,null,{"sessionId":"0d31c62e-46f2-44d6-be3c-6af812191916","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:39 INFO - 1501039299089 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
20:21:39 INFO - 1501039299090 Marionette TRACE 379 <- [1,2,null,{}]
20:21:39 INFO - 1501039299093 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:39 INFO - 1501039299094 Marionette TRACE 379 <- [1,3,null,{}]
20:21:39 INFO - 1501039299096 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:39 INFO - 1501039299098 Marionette TRACE 379 <- [1,4,null,{}]
20:21:39 INFO - 1501039299101 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}]
20:21:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501039299110 Marionette TRACE 379 <- [1,5,null,{"value":null}]
20:21:39 INFO - 1501039299112 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299116 Marionette TRACE 379 <- [1,6,null,{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299122 Marionette DEBUG Closed connection 379
20:21:39 INFO - 1501039299124 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49805
20:21:39 INFO - 1501039299125 Marionette DEBUG Closed connection 380
20:21:39 INFO - 1501039299128 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49806
20:21:39 INFO - 1501039299130 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:39 INFO - 1501039299132 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:39 INFO - 1501039299154 Marionette DEBUG Register listener.js for window 12
20:21:39 INFO - 1501039299166 Marionette TRACE 381 <- [1,1,null,{"sessionId":"d7e6a005-659c-4b36-9a51-170c5d571866","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:39 INFO - 1501039299172 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299177 Marionette TRACE 381 <- [1,2,null,{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299184 Marionette DEBUG Closed connection 381
20:21:39 INFO - 1501039299185 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49807
20:21:39 INFO - 1501039299187 Marionette DEBUG Closed connection 382
20:21:39 INFO - 1501039299188 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49808
20:21:39 INFO - 1501039299191 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:39 INFO - 1501039299194 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:39 INFO - 1501039299215 Marionette DEBUG Register listener.js for window 12
20:21:39 INFO - 1501039299227 Marionette TRACE 383 <- [1,1,null,{"sessionId":"eaf5a683-b3f6-4c84-a39e-68fcc2b99f89","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:39 INFO - 1501039299234 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"}]
20:21:39 INFO - 1501039299238 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"
20:21:39 INFO - 1501039299244 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"
20:21:39 INFO - 1501039299245 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"
20:21:39 INFO - ++DOMWINDOW == 22 (000000F2F99B3800) [pid = 1852] [serial = 46] [outer = 000000F284B56800]
20:21:39 INFO - 1501039299304 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"
20:21:39 INFO - 1501039299309 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"
20:21:39 INFO - 1501039299328 Marionette TRACE 383 <- [1,2,null,{}]
20:21:39 INFO - 1501039299376 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:21:39 INFO - 1501039299385 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d4bb20a-fdf8-4906-b36a-dff3f61d95f4","ELEMENT":"8d4bb20a-fdf8-4906-b36a-dff3f61d95f4"}}]
20:21:39 INFO - 1501039299387 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"8d4bb20a-fdf8-4906-b36a-dff3f61d95f4"}]
20:21:39 INFO - 1501039299824 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:39 INFO - 1501039299826 Marionette TRACE 383 <- [1,4,null,{}]
20:21:39 INFO - 1501039299831 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}]
20:21:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501039299840 Marionette TRACE 383 <- [1,5,null,{"value":null}]
20:21:39 INFO - 1501039299842 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299846 Marionette TRACE 383 <- [1,6,null,{}]
20:21:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 835ms
20:21:39 INFO - TEST-START | test_click.py TestClick.test_input_file
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299870 Marionette DEBUG Closed connection 383
20:21:39 INFO - 1501039299872 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49809
20:21:39 INFO - 1501039299874 Marionette DEBUG Closed connection 384
20:21:39 INFO - 1501039299875 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49810
20:21:39 INFO - 1501039299877 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:39 INFO - 1501039299880 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:39 INFO - 1501039299901 Marionette DEBUG Register listener.js for window 12
20:21:39 INFO - 1501039299913 Marionette TRACE 385 <- [1,1,null,{"sessionId":"23fcbf6d-708c-445a-9548-dcbeff6aaed1","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:39 INFO - 1501039299926 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
20:21:39 INFO - 1501039299928 Marionette TRACE 385 <- [1,2,null,{}]
20:21:39 INFO - 1501039299930 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:39 INFO - 1501039299932 Marionette TRACE 385 <- [1,3,null,{}]
20:21:39 INFO - 1501039299934 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:39 INFO - 1501039299935 Marionette TRACE 385 <- [1,4,null,{}]
20:21:39 INFO - 1501039299938 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}]
20:21:39 INFO - TEST-START: test_click.py TestClick.test_input_file1501039299946 Marionette TRACE 385 <- [1,5,null,{"value":null}]
20:21:39 INFO - 1501039299948 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299953 Marionette TRACE 385 <- [1,6,null,{}]
20:21:39 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:39 INFO - 1501039299959 Marionette DEBUG Closed connection 385
20:21:39 INFO - 1501039299960 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49811
20:21:39 INFO - 1501039299962 Marionette DEBUG Closed connection 386
20:21:39 INFO - 1501039299963 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49812
20:21:39 INFO - 1501039299966 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:39 INFO - 1501039299969 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:39 INFO - 1501039299995 Marionette DEBUG Register listener.js for window 12
20:21:40 INFO - 1501039300010 Marionette TRACE 387 <- [1,1,null,{"sessionId":"907bdcdd-1569-4242-80fb-79e4ac37f4b5","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:40 INFO - 1501039300016 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
20:21:40 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:40 INFO - 1501039300020 Marionette TRACE 387 <- [1,2,null,{}]
20:21:40 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:40 INFO - 1501039300026 Marionette DEBUG Closed connection 387
20:21:40 INFO - 1501039300028 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49813
20:21:40 INFO - 1501039300029 Marionette DEBUG Closed connection 388
20:21:40 INFO - 1501039300031 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49814
20:21:40 INFO - 1501039300033 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:40 INFO - 1501039300036 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:40 INFO - 1501039300058 Marionette DEBUG Register listener.js for window 12
20:21:40 INFO - 1501039300070 Marionette TRACE 389 <- [1,1,null,{"sessionId":"1f62aa1a-c040-4ac7-845f-60d1c923e12e","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:40 INFO - 1501039300076 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:21:40 INFO - 1501039300081 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"
20:21:40 INFO - 1501039300090 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"
20:21:40 INFO - 1501039300092 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"
20:21:40 INFO - ++DOMWINDOW == 23 (000000F2FA144800) [pid = 1852] [serial = 47] [outer = 000000F284B56800]
20:21:40 INFO - 1501039300190 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:40 INFO - 1501039300200 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:40 INFO - 1501039300217 Marionette TRACE 389 <- [1,2,null,{}]
20:21:40 INFO - 1501039300809 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:21:40 INFO - 1501039300817 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e46f6a9e-f9cd-46f0-80cd-3751d926065b","ELEMENT":"e46f6a9e-f9cd-46f0-80cd-3751d926065b"}}]
20:21:40 INFO - 1501039300819 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"e46f6a9e-f9cd-46f0-80cd-3751d926065b"}]
20:21:40 INFO - 1501039300981 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:21:40 INFO - 1501039300985 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}]
20:21:40 INFO - TEST-END: test_click.py TestClick.test_input_file1501039300993 Marionette TRACE 389 <- [1,5,null,{"value":null}]
20:21:40 INFO - 1501039300997 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301002 Marionette TRACE 389 <- [1,6,null,{}]
20:21:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1156ms
20:21:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301024 Marionette DEBUG Closed connection 389
20:21:41 INFO - 1501039301026 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49815
20:21:41 INFO - 1501039301030 Marionette DEBUG Closed connection 390
20:21:41 INFO - 1501039301031 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49816
20:21:41 INFO - 1501039301034 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:41 INFO - 1501039301037 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:41 INFO - 1501039301059 Marionette DEBUG Register listener.js for window 12
20:21:41 INFO - 1501039301071 Marionette TRACE 391 <- [1,1,null,{"sessionId":"ea2b7b4d-ee1d-4082-b64f-340bad8938bd","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:41 INFO - 1501039301080 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
20:21:41 INFO - 1501039301082 Marionette TRACE 391 <- [1,2,null,{}]
20:21:41 INFO - 1501039301101 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:41 INFO - 1501039301103 Marionette TRACE 391 <- [1,3,null,{}]
20:21:41 INFO - 1501039301106 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:41 INFO - 1501039301108 Marionette TRACE 391 <- [1,4,null,{}]
20:21:41 INFO - 1501039301111 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}]
20:21:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501039301119 Marionette TRACE 391 <- [1,5,null,{"value":null}]
20:21:41 INFO - 1501039301121 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301125 Marionette TRACE 391 <- [1,6,null,{}]
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301132 Marionette DEBUG Closed connection 391
20:21:41 INFO - 1501039301133 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49817
20:21:41 INFO - 1501039301135 Marionette DEBUG Closed connection 392
20:21:41 INFO - 1501039301136 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49818
20:21:41 INFO - 1501039301138 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:41 INFO - 1501039301140 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:41 INFO - 1501039301163 Marionette DEBUG Register listener.js for window 12
20:21:41 INFO - 1501039301174 Marionette TRACE 393 <- [1,1,null,{"sessionId":"16def319-bf1c-48fb-a52b-893a6a35bfb6","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:41 INFO - 1501039301212 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301217 Marionette TRACE 393 <- [1,2,null,{}]
20:21:41 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:41 INFO - 1501039301239 Marionette DEBUG Closed connection 393
20:21:41 INFO - 1501039301241 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49819
20:21:41 INFO - 1501039301245 Marionette DEBUG Closed connection 394
20:21:41 INFO - 1501039301246 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49820
20:21:41 INFO - 1501039301249 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:41 INFO - 1501039301251 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:41 INFO - 1501039301273 Marionette DEBUG Register listener.js for window 12
20:21:41 INFO - 1501039301284 Marionette TRACE 395 <- [1,1,null,{"sessionId":"64fda70c-2191-46ef-90db-c5dbe78e2643","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:41 INFO - 1501039301294 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"}]
20:21:41 INFO - 1501039301298 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:41 INFO - 1501039301305 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:41 INFO - 1501039301305 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:41 INFO - ++DOMWINDOW == 24 (000000F2FA14A000) [pid = 1852] [serial = 48] [outer = 000000F284B56800]
20:21:41 INFO - 1501039301362 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"
20:21:41 INFO - 1501039301368 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"
20:21:41 INFO - 1501039301383 Marionette TRACE 395 <- [1,2,null,{}]
20:21:41 INFO - 1501039301389 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:21:41 INFO - 1501039301397 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b21320df-d099-44da-ab41-e23d7d0726f5","ELEMENT":"b21320df-d099-44da-ab41-e23d7d0726f5"}}]
20:21:41 INFO - 1501039301399 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:21:41 INFO - 1501039301420 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88d7464e-2be7-40bf-88ea-c417e6d59b82","ELEMENT":"88d7464e-2be7-40bf-88ea-c417e6d59b82"}}]
20:21:41 INFO - 1501039301424 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"b21320df-d099-44da-ab41-e23d7d0726f5"}]
20:21:41 INFO - 1501039301947 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:41 INFO - 1501039301952 Marionette TRACE 395 <- [1,5,null,{}]
20:21:41 INFO - 1501039301958 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"88d7464e-2be7-40bf-88ea-c417e6d59b82"}]
20:21:42 INFO - 1501039302142 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:21:42 INFO - 1501039302148 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:21:42 INFO - 1501039302162 Marionette TRACE 395 <- [1,7,null,{"value":false}]
20:21:42 INFO - 1501039302190 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}]
20:21:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501039302203 Marionette TRACE 395 <- [1,8,null,{"value":null}]
20:21:42 INFO - 1501039302207 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302217 Marionette TRACE 395 <- [1,9,null,{}]
20:21:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1217ms
20:21:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302229 Marionette DEBUG Closed connection 395
20:21:42 INFO - 1501039302232 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49821
20:21:42 INFO - 1501039302240 Marionette DEBUG Closed connection 396
20:21:42 INFO - 1501039302242 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49822
20:21:42 INFO - 1501039302265 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:42 INFO - 1501039302275 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:42 INFO - 1501039302317 Marionette DEBUG Register listener.js for window 12
20:21:42 INFO - 1501039302359 Marionette TRACE 397 <- [1,1,null,{"sessionId":"ed97c34f-dc20-4555-b211-0ae314cf24a7","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:42 INFO - 1501039302386 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
20:21:42 INFO - 1501039302389 Marionette TRACE 397 <- [1,2,null,{}]
20:21:42 INFO - 1501039302396 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:42 INFO - 1501039302398 Marionette TRACE 397 <- [1,3,null,{}]
20:21:42 INFO - 1501039302403 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:42 INFO - 1501039302405 Marionette TRACE 397 <- [1,4,null,{}]
20:21:42 INFO - 1501039302408 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}]
20:21:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501039302420 Marionette TRACE 397 <- [1,5,null,{"value":null}]
20:21:42 INFO - 1501039302477 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302486 Marionette TRACE 397 <- [1,6,null,{}]
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302512 Marionette DEBUG Closed connection 397
20:21:42 INFO - 1501039302515 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49823
20:21:42 INFO - 1501039302567 Marionette DEBUG Closed connection 398
20:21:42 INFO - 1501039302569 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49824
20:21:42 INFO - 1501039302575 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:42 INFO - 1501039302578 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:42 INFO - 1501039302620 Marionette DEBUG Register listener.js for window 12
20:21:42 INFO - 1501039302637 Marionette TRACE 399 <- [1,1,null,{"sessionId":"2f0e20cf-bb05-4ab1-9b8e-f0cb1612c21b","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:42 INFO - 1501039302648 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302655 Marionette TRACE 399 <- [1,2,null,{}]
20:21:42 INFO - [Parent 1852] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:42 INFO - 1501039302700 Marionette DEBUG Closed connection 399
20:21:42 INFO - 1501039302703 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49825
20:21:42 INFO - 1501039302723 Marionette DEBUG Closed connection 400
20:21:42 INFO - 1501039302725 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49826
20:21:42 INFO - 1501039302735 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:42 INFO - 1501039302739 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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}
20:21:42 INFO - 1501039302781 Marionette DEBUG Register listener.js for window 12
20:21:42 INFO - 1501039302806 Marionette TRACE 401 <- [1,1,null,{"sessionId":"ecd52f35-8bb3-4449-934c-2295c74e303a","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":1852,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-056.000\\AppData\\Local\\Temp\\tmpfqdlwm.mozrunner","moz:accessibilityChecks":false}}]
20:21:42 INFO - 1501039302820 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"}]
20:21:42 INFO - 1501039302832 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"
20:21:43 INFO - 1501039303008 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"
20:21:43 INFO - 1501039303010 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"
20:21:43 INFO - ++DOMWINDOW == 25 (000000F2851E7800) [pid = 1852] [serial = 49] [outer = 000000F284B56800]
20:21:43 INFO - 1501039303095 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"
20:21:43 INFO - 1501039303102 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"
20:21:43 INFO - 1501039303123 Marionette TRACE 401 <- [1,2,null,{}]
20:21:43 INFO - 1501039303139 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:21:43 INFO - 1501039303154 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d2eb02c-4604-440b-b4e4-d26dc1387d0e","ELEMENT":"2d2eb02c-4604-440b-b4e4-d26dc1387d0e"}}]
20:21:43 INFO - 1501039303181 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2d2eb02c-4604-440b-b4e4-d26dc1387d0e"}]
20:21:43 INFO - 1501039303310 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
20:21:43 INFO - 1501039303329 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"2d2eb02c-4604-440b-b4e4-d26dc1387d0e"}]
20:21:43 INFO - 1501039303459 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element