could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:02:16 INFO - 1500109336494 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}]
02:02:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500109336503 Marionette TRACE 353 <- [1,5,null,{"value":null}]
02:02:16 INFO - 1500109336505 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336509 Marionette TRACE 353 <- [1,6,null,{}]
02:02:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 689ms
02:02:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336515 Marionette DEBUG Closed connection 353
02:02:16 INFO - 1500109336517 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49877
02:02:16 INFO - 1500109336518 Marionette DEBUG Closed connection 354
02:02:16 INFO - 1500109336520 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49878
02:02:16 INFO - 1500109336522 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:16 INFO - 1500109336524 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:16 INFO - 1500109336546 Marionette DEBUG Register listener.js for window 15
02:02:16 INFO - 1500109336558 Marionette TRACE 355 <- [1,1,null,{"sessionId":"fce25bd3-4eec-4f38-b047-2ed3446726b9","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:16 INFO - 1500109336564 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
02:02:16 INFO - 1500109336566 Marionette TRACE 355 <- [1,2,null,{}]
02:02:16 INFO - 1500109336568 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:16 INFO - 1500109336569 Marionette TRACE 355 <- [1,3,null,{}]
02:02:16 INFO - 1500109336571 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:16 INFO - 1500109336573 Marionette TRACE 355 <- [1,4,null,{}]
02:02:16 INFO - 1500109336576 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}]
02:02:16 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500109336585 Marionette TRACE 355 <- [1,5,null,{"value":null}]
02:02:16 INFO - 1500109336587 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336591 Marionette TRACE 355 <- [1,6,null,{}]
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336597 Marionette DEBUG Closed connection 355
02:02:16 INFO - 1500109336599 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49879
02:02:16 INFO - 1500109336601 Marionette DEBUG Closed connection 356
02:02:16 INFO - 1500109336602 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49880
02:02:16 INFO - 1500109336604 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:16 INFO - 1500109336607 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:16 INFO - 1500109336628 Marionette DEBUG Register listener.js for window 15
02:02:16 INFO - 1500109336639 Marionette TRACE 357 <- [1,1,null,{"sessionId":"7d66305d-9036-482d-9968-411c97e9166e","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:16 INFO - 1500109336646 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336650 Marionette TRACE 357 <- [1,2,null,{}]
02:02:16 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:16 INFO - 1500109336656 Marionette DEBUG Closed connection 357
02:02:16 INFO - 1500109336657 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49881
02:02:16 INFO - 1500109336659 Marionette DEBUG Closed connection 358
02:02:16 INFO - 1500109336660 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49882
02:02:16 INFO - 1500109336662 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:16 INFO - 1500109336666 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:16 INFO - 1500109336691 Marionette DEBUG Register listener.js for window 15
02:02:16 INFO - 1500109336705 Marionette TRACE 359 <- [1,1,null,{"sessionId":"378ebc25-55b3-4748-abda-ba23d72e9748","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:16 INFO - 1500109336711 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49412/clicks.html"}]
02:02:16 INFO - 1500109336715 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/hidden.html"
02:02:16 INFO - 1500109336734 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/hidden.html"
02:02:16 INFO - 1500109336735 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/hidden.html"
02:02:16 INFO - ++DOMWINDOW == 21 (000000DA98B33800) [pid = 2696] [serial = 40] [outer = 000000DA9C913000]
02:02:16 INFO - 1500109336865 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49412/clicks.html"
02:02:16 INFO - 1500109336870 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49412/clicks.html"
02:02:16 INFO - 1500109336890 Marionette TRACE 359 <- [1,2,null,{}]
02:02:16 INFO - 1500109336899 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:02:16 INFO - 1500109336906 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4cdcc612-7785-48a0-a9fd-3a84f4a182db","ELEMENT":"4cdcc612-7785-48a0-a9fd-3a84f4a182db"}}]
02:02:16 INFO - 1500109336908 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"4cdcc612-7785-48a0-a9fd-3a84f4a182db"}]
02:02:17 INFO - 1500109337132 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/clicks.html"
02:02:17 INFO - [Parent 2696] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
02:02:17 INFO - 1500109337153 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/clicks.html"
02:02:17 INFO - 1500109337155 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/clicks.html"
02:02:17 INFO - ++DOMWINDOW == 22 (000000DA9BF35000) [pid = 2696] [serial = 41] [outer = 000000DA9C913000]
02:02:17 INFO - 1500109337259 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49412/test.html"
02:02:17 INFO - 1500109337266 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49412/test.html"
02:02:17 INFO - 1500109337291 Marionette TRACE 359 <- [1,4,null,{}]
02:02:17 INFO - 1500109337297 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:02:17 INFO - 1500109337304 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fcb2fa4-9eac-41f1-a9dd-8d3d48a0cb1d","ELEMENT":"4fcb2fa4-9eac-41f1-a9dd-8d3d48a0cb1d"}}]
02:02:17 INFO - 1500109337312 Marionette TRACE 359 -> [0,6,"getTitle",{}]
02:02:17 INFO - 1500109337314 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
02:02:17 INFO - 1500109337317 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}]
02:02:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500109337326 Marionette TRACE 359 <- [1,7,null,{"value":null}]
02:02:17 INFO - 1500109337328 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337333 Marionette TRACE 359 <- [1,8,null,{}]
02:02:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 823ms
02:02:17 INFO - TEST-START | test_click.py TestClick.test_container_element
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337339 Marionette DEBUG Closed connection 359
02:02:17 INFO - 1500109337341 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49885
02:02:17 INFO - 1500109337342 Marionette DEBUG Closed connection 360
02:02:17 INFO - 1500109337344 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49886
02:02:17 INFO - 1500109337346 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:17 INFO - 1500109337348 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:17 INFO - 1500109337370 Marionette DEBUG Register listener.js for window 15
02:02:17 INFO - 1500109337381 Marionette TRACE 361 <- [1,1,null,{"sessionId":"357aa5e5-2f3c-4dfd-b5f5-d468cb692940","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:17 INFO - 1500109337411 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
02:02:17 INFO - 1500109337412 Marionette TRACE 361 <- [1,2,null,{}]
02:02:17 INFO - 1500109337414 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:17 INFO - 1500109337416 Marionette TRACE 361 <- [1,3,null,{}]
02:02:17 INFO - 1500109337419 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:17 INFO - 1500109337420 Marionette TRACE 361 <- [1,4,null,{}]
02:02:17 INFO - 1500109337423 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}]
02:02:17 INFO - TEST-START: test_click.py TestClick.test_container_element1500109337431 Marionette TRACE 361 <- [1,5,null,{"value":null}]
02:02:17 INFO - 1500109337433 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337437 Marionette TRACE 361 <- [1,6,null,{}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337461 Marionette DEBUG Closed connection 361
02:02:17 INFO - 1500109337462 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49887
02:02:17 INFO - 1500109337467 Marionette DEBUG Closed connection 362
02:02:17 INFO - 1500109337468 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49888
02:02:17 INFO - 1500109337473 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:17 INFO - 1500109337476 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:17 INFO - 1500109337498 Marionette DEBUG Register listener.js for window 15
02:02:17 INFO - 1500109337509 Marionette TRACE 363 <- [1,1,null,{"sessionId":"add6fe7d-9c03-4257-af58-32fd8cc6b90c","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:17 INFO - 1500109337519 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337523 Marionette TRACE 363 <- [1,2,null,{}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:17 INFO - 1500109337544 Marionette DEBUG Closed connection 363
02:02:17 INFO - 1500109337546 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49889
02:02:17 INFO - 1500109337550 Marionette DEBUG Closed connection 364
02:02:17 INFO - 1500109337551 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49890
02:02:17 INFO - 1500109337554 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:17 INFO - 1500109337557 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:17 INFO - 1500109337578 Marionette DEBUG Register listener.js for window 15
02:02:17 INFO - 1500109337589 Marionette TRACE 365 <- [1,1,null,{"sessionId":"9ea4751c-bcf4-44b2-b7fb-89cc9726935e","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:17 INFO - 1500109337599 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"}]
02:02:17 INFO - 1500109337603 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49412/test.html"
02:02:17 INFO - 1500109337608 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49412/test.html"
02:02:17 INFO - 1500109337609 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49412/test.html"
02:02:17 INFO - ++DOMWINDOW == 23 (000000DA9BF43000) [pid = 2696] [serial = 42] [outer = 000000DA9C913000]
02:02:17 INFO - 1500109337655 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"
02:02:17 INFO - 1500109337669 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"
02:02:17 INFO - 1500109337677 Marionette TRACE 365 <- [1,2,null,{}]
02:02:17 INFO - 1500109337687 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:02:17 INFO - 1500109337695 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"710852a6-2f3a-4f0f-9ef3-a5da9c78fa64","ELEMENT":"710852a6-2f3a-4f0f-9ef3-a5da9c78fa64"}}]
02:02:17 INFO - 1500109337702 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"710852a6-2f3a-4f0f-9ef3-a5da9c78fa64"}]
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
02:02:17 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
02:02:18 INFO - 1500109338161 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:02:18 INFO - 1500109338163 Marionette TRACE 365 <- [1,4,null,{}]
02:02:18 INFO - 1500109338165 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"710852a6-2f3a-4f0f-9ef3-a5da9c78fa64","name":"selected"}]
02:02:18 INFO - 1500109338193 Marionette TRACE 365 <- [1,5,null,{"value":true}]
02:02:18 INFO - 1500109338196 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}]
02:02:18 INFO - TEST-END: test_click.py TestClick.test_container_element1500109338203 Marionette TRACE 365 <- [1,6,null,{"value":null}]
02:02:18 INFO - 1500109338205 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
02:02:18 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:18 INFO - 1500109338210 Marionette TRACE 365 <- [1,7,null,{}]
02:02:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 876ms
02:02:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:02:18 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:18 INFO - 1500109338216 Marionette DEBUG Closed connection 365
02:02:18 INFO - 1500109338217 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49891
02:02:18 INFO - 1500109338219 Marionette DEBUG Closed connection 366
02:02:18 INFO - 1500109338221 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49892
02:02:18 INFO - 1500109338223 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:18 INFO - 1500109338225 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:18 INFO - 1500109338246 Marionette DEBUG Register listener.js for window 15
02:02:18 INFO - 1500109338298 Marionette TRACE 367 <- [1,1,null,{"sessionId":"58d70a6c-9229-4c2c-9e70-5c4303c7648d","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:18 INFO - 1500109338308 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
02:02:18 INFO - 1500109338309 Marionette TRACE 367 <- [1,2,null,{}]
02:02:18 INFO - 1500109338314 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:18 INFO - 1500109338315 Marionette TRACE 367 <- [1,3,null,{}]
02:02:18 INFO - 1500109338318 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:18 INFO - 1500109338319 Marionette TRACE 367 <- [1,4,null,{}]
02:02:18 INFO - 1500109338322 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}]
02:02:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500109338329 Marionette TRACE 367 <- [1,5,null,{"value":null}]
02:02:18 INFO - 1500109338331 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
02:02:18 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:18 INFO - 1500109338335 Marionette TRACE 367 <- [1,6,null,{}]
02:02:18 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:18 INFO - 1500109338341 Marionette DEBUG Closed connection 367
02:02:18 INFO - 1500109338343 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49893
02:02:18 INFO - 1500109338344 Marionette DEBUG Closed connection 368
02:02:18 INFO - 1500109338345 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49894
02:02:18 INFO - 1500109338347 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:18 INFO - 1500109338349 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:18 INFO - 1500109338371 Marionette DEBUG Register listener.js for window 15
02:02:18 INFO - 1500109338382 Marionette TRACE 369 <- [1,1,null,{"sessionId":"69f608bf-c091-44f9-a529-276ca927003f","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:18 INFO - 1500109338410 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
02:02:18 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:18 INFO - 1500109338415 Marionette TRACE 369 <- [1,2,null,{}]
02:02:19 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:19 INFO - 1500109339044 Marionette DEBUG Closed connection 369
02:02:19 INFO - 1500109339045 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49895
02:02:19 INFO - 1500109339048 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49896
02:02:19 INFO - 1500109339049 Marionette DEBUG Closed connection 370
02:02:19 INFO - 1500109339100 Marionette DEBUG Closed connection 371
02:02:19 INFO - 1500109339102 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49897
02:02:19 INFO - 1500109339144 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:19 INFO - 1500109339147 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:19 INFO - 1500109339168 Marionette DEBUG Register listener.js for window 15
02:02:19 INFO - 1500109339180 Marionette TRACE 372 <- [1,1,null,{"sessionId":"db29d4fa-5c77-4b19-a5b0-da67241ebc2c","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:19 INFO - 1500109339213 Marionette TRACE 372 -> [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"}]
02:02:19 INFO - 1500109339220 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"
02:02:19 INFO - 1500109339231 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"
02:02:19 INFO - 1500109339233 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"
02:02:19 INFO - ++DOMWINDOW == 24 (000000DA8FCB1800) [pid = 2696] [serial = 43] [outer = 000000DA9C913000]
02:02:19 INFO - 1500109339302 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"
02:02:19 INFO - 1500109339310 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"
02:02:19 INFO - 1500109339335 Marionette TRACE 372 <- [1,2,null,{}]
02:02:19 INFO - 1500109339340 Marionette TRACE 372 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:02:19 INFO - 1500109339356 Marionette TRACE 372 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d52de9bd-2ee1-44ce-9c3e-aee7f0149535","ELEMENT":"d52de9bd-2ee1-44ce-9c3e-aee7f0149535"}}]
02:02:19 INFO - 1500109339361 Marionette TRACE 372 -> [0,4,"clickElement",{"id":"d52de9bd-2ee1-44ce-9c3e-aee7f0149535"}]
02:02:19 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
02:02:19 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
02:02:19 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
02:02:20 INFO - 1500109340010 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:02:20 INFO - 1500109340014 Marionette TRACE 372 <- [1,4,null,{}]
02:02:20 INFO - 1500109340018 Marionette TRACE 372 -> [0,5,"getElementProperty",{"id":"d52de9bd-2ee1-44ce-9c3e-aee7f0149535","name":"selected"}]
02:02:20 INFO - 1500109340079 Marionette TRACE 372 <- [1,5,null,{"value":true}]
02:02:20 INFO - 1500109340106 Marionette TRACE 372 -> [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}]
02:02:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500109340119 Marionette TRACE 372 <- [1,6,null,{"value":null}]
02:02:20 INFO - 1500109340135 Marionette TRACE 372 -> [0,7,"deleteSession",{}]
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340145 Marionette TRACE 372 <- [1,7,null,{}]
02:02:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1935ms
02:02:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340169 Marionette DEBUG Closed connection 372
02:02:20 INFO - 1500109340172 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49898
02:02:20 INFO - 1500109340174 Marionette DEBUG Closed connection 373
02:02:20 INFO - 1500109340177 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49899
02:02:20 INFO - 1500109340180 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:20 INFO - 1500109340190 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:20 INFO - 1500109340234 Marionette DEBUG Register listener.js for window 15
02:02:20 INFO - 1500109340285 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f4ead9be-f8f7-4f8d-8481-1715d9310cd1","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:20 INFO - 1500109340308 Marionette TRACE 374 -> [0,2,"setTimeouts",{"script":30000}]
02:02:20 INFO - 1500109340310 Marionette TRACE 374 <- [1,2,null,{}]
02:02:20 INFO - 1500109340352 Marionette TRACE 374 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:20 INFO - 1500109340355 Marionette TRACE 374 <- [1,3,null,{}]
02:02:20 INFO - 1500109340359 Marionette TRACE 374 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:20 INFO - 1500109340362 Marionette TRACE 374 <- [1,4,null,{}]
02:02:20 INFO - 1500109340371 Marionette TRACE 374 -> [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}]
02:02:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500109340385 Marionette TRACE 374 <- [1,5,null,{"value":null}]
02:02:20 INFO - 1500109340389 Marionette TRACE 374 -> [0,6,"deleteSession",{}]
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340395 Marionette TRACE 374 <- [1,6,null,{}]
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340454 Marionette DEBUG Closed connection 374
02:02:20 INFO - 1500109340458 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49900
02:02:20 INFO - 1500109340477 Marionette DEBUG Closed connection 375
02:02:20 INFO - 1500109340480 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49901
02:02:20 INFO - 1500109340483 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:20 INFO - 1500109340486 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:20 INFO - 1500109340530 Marionette DEBUG Register listener.js for window 15
02:02:20 INFO - 1500109340547 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c208eb0d-e0ac-4d07-91a6-85684c8a2cae","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:20 INFO - 1500109340582 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340588 Marionette TRACE 376 <- [1,2,null,{}]
02:02:20 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:20 INFO - 1500109340614 Marionette DEBUG Closed connection 376
02:02:20 INFO - 1500109340616 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49902
02:02:20 INFO - 1500109340621 Marionette DEBUG Closed connection 377
02:02:20 INFO - 1500109340623 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49903
02:02:20 INFO - 1500109340649 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:20 INFO - 1500109340654 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:20 INFO - 1500109340698 Marionette DEBUG Register listener.js for window 15
02:02:20 INFO - 1500109340715 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fd7acc5a-7917-42f4-9301-f0c861f5935c","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:20 INFO - 1500109340727 Marionette TRACE 378 -> [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"}]
02:02:20 INFO - 1500109340733 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"
02:02:20 INFO - 1500109340742 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"
02:02:20 INFO - 1500109340743 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"
02:02:20 INFO - ++DOMWINDOW == 25 (000000DA9BF3C000) [pid = 2696] [serial = 44] [outer = 000000DA9C913000]
02:02:20 INFO - 1500109340809 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"
02:02:20 INFO - 1500109340818 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"
02:02:20 INFO - 1500109340849 Marionette TRACE 378 <- [1,2,null,{}]
02:02:20 INFO - 1500109340853 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:02:20 INFO - 1500109340863 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f69b6e9-c475-4666-a5cc-985231cc48fc","ELEMENT":"1f69b6e9-c475-4666-a5cc-985231cc48fc"}}]
02:02:20 INFO - 1500109340871 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"1f69b6e9-c475-4666-a5cc-985231cc48fc"}]
02:02:21 INFO - 1500109341629 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:02:21 INFO - 1500109341631 Marionette TRACE 378 <- [1,4,null,{}]
02:02:21 INFO - 1500109341638 Marionette TRACE 378 -> [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}]
02:02:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500109341653 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:02:21 INFO - 1500109341658 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341667 Marionette TRACE 378 <- [1,6,null,{}]
02:02:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1521ms
02:02:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341676 Marionette DEBUG Closed connection 378
02:02:21 INFO - 1500109341678 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49904
02:02:21 INFO - 1500109341680 Marionette DEBUG Closed connection 379
02:02:21 INFO - 1500109341683 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49905
02:02:21 INFO - 1500109341688 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:21 INFO - 1500109341698 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:21 INFO - 1500109341723 Marionette DEBUG Register listener.js for window 15
02:02:21 INFO - 1500109341744 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9f24f559-9c97-47ec-ad9c-4ae99050533f","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:21 INFO - 1500109341790 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
02:02:21 INFO - 1500109341793 Marionette TRACE 380 <- [1,2,null,{}]
02:02:21 INFO - 1500109341820 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:21 INFO - 1500109341823 Marionette TRACE 380 <- [1,3,null,{}]
02:02:21 INFO - 1500109341827 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:21 INFO - 1500109341830 Marionette TRACE 380 <- [1,4,null,{}]
02:02:21 INFO - 1500109341844 Marionette TRACE 380 -> [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}]
02:02:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500109341856 Marionette TRACE 380 <- [1,5,null,{"value":null}]
02:02:21 INFO - 1500109341862 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341868 Marionette TRACE 380 <- [1,6,null,{}]
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341879 Marionette DEBUG Closed connection 380
02:02:21 INFO - 1500109341882 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49906
02:02:21 INFO - 1500109341903 Marionette DEBUG Closed connection 381
02:02:21 INFO - 1500109341905 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49907
02:02:21 INFO - 1500109341910 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:21 INFO - 1500109341914 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:21 INFO - 1500109341937 Marionette DEBUG Register listener.js for window 15
02:02:21 INFO - 1500109341953 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b5c9b660-dc0d-4f52-b686-1017a5758bee","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:21 INFO - 1500109341964 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341971 Marionette TRACE 382 <- [1,2,null,{}]
02:02:21 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:21 INFO - 1500109341980 Marionette DEBUG Closed connection 382
02:02:21 INFO - 1500109341982 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49908
02:02:21 INFO - --DOMWINDOW == 24 (000000DA8FCBA800) [pid = 2696] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49412/test_accessibility.html]
02:02:21 INFO - --DOMWINDOW == 23 (000000DA91A21000) [pid = 2696] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49412/test_accessibility.html]
02:02:21 INFO - --DOMWINDOW == 22 (000000DAA5AF6800) [pid = 2696] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49412/test_accessibility.html]
02:02:22 INFO - --DOMWINDOW == 21 (000000DA97B8C800) [pid = 2696] [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%20horizontally%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%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]
02:02:22 INFO - --DOMWINDOW == 20 (000000DA94E9D000) [pid = 2696] [serial = 32] [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]
02:02:22 INFO - 1500109342010 Marionette DEBUG Closed connection 383
02:02:22 INFO - 1500109342012 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49909
02:02:22 INFO - 1500109342020 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:22 INFO - 1500109342025 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:22 INFO - 1500109342048 Marionette DEBUG Register listener.js for window 15
02:02:22 INFO - 1500109342062 Marionette TRACE 384 <- [1,1,null,{"sessionId":"69431925-c1b0-4d04-92c8-7439e082a568","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:22 INFO - 1500109342070 Marionette TRACE 384 -> [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"}]
02:02:22 INFO - 1500109342075 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"
02:02:22 INFO - 1500109342084 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"
02:02:22 INFO - 1500109342085 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"
02:02:22 INFO - ++DOMWINDOW == 21 (000000DA8FCC8800) [pid = 2696] [serial = 45] [outer = 000000DA9C913000]
02:02:22 INFO - 1500109342138 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"
02:02:22 INFO - 1500109342154 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"
02:02:22 INFO - 1500109342174 Marionette TRACE 384 <- [1,2,null,{}]
02:02:22 INFO - 1500109342177 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:02:22 INFO - 1500109342186 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7380a336-30ba-4402-b6df-ced9e3b6aaff","ELEMENT":"7380a336-30ba-4402-b6df-ced9e3b6aaff"}}]
02:02:22 INFO - 1500109342188 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"7380a336-30ba-4402-b6df-ced9e3b6aaff"}]
02:02:22 INFO - 1500109342627 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:02:22 INFO - 1500109342629 Marionette TRACE 384 <- [1,4,null,{}]
02:02:22 INFO - 1500109342632 Marionette TRACE 384 -> [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}]
02:02:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500109342640 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:02:22 INFO - 1500109342643 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342647 Marionette TRACE 384 <- [1,6,null,{}]
02:02:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 979ms
02:02:22 INFO - TEST-START | test_click.py TestClick.test_input_file
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342653 Marionette DEBUG Closed connection 384
02:02:22 INFO - 1500109342655 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49910
02:02:22 INFO - 1500109342657 Marionette DEBUG Closed connection 385
02:02:22 INFO - 1500109342660 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49911
02:02:22 INFO - 1500109342662 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:22 INFO - 1500109342665 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:22 INFO - 1500109342688 Marionette DEBUG Register listener.js for window 15
02:02:22 INFO - 1500109342701 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d8173e64-adfc-4407-a8d1-9ddbc19e9acb","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:22 INFO - 1500109342709 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
02:02:22 INFO - 1500109342711 Marionette TRACE 386 <- [1,2,null,{}]
02:02:22 INFO - 1500109342712 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:22 INFO - 1500109342714 Marionette TRACE 386 <- [1,3,null,{}]
02:02:22 INFO - 1500109342716 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:22 INFO - 1500109342718 Marionette TRACE 386 <- [1,4,null,{}]
02:02:22 INFO - 1500109342720 Marionette TRACE 386 -> [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}]
02:02:22 INFO - TEST-START: test_click.py TestClick.test_input_file1500109342730 Marionette TRACE 386 <- [1,5,null,{"value":null}]
02:02:22 INFO - 1500109342732 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342737 Marionette TRACE 386 <- [1,6,null,{}]
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342745 Marionette DEBUG Closed connection 386
02:02:22 INFO - 1500109342747 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49912
02:02:22 INFO - 1500109342749 Marionette DEBUG Closed connection 387
02:02:22 INFO - 1500109342750 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49913
02:02:22 INFO - 1500109342753 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:22 INFO - 1500109342758 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:22 INFO - 1500109342781 Marionette DEBUG Register listener.js for window 15
02:02:22 INFO - 1500109342802 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c9443405-8987-4ce5-b51a-58647492d0f8","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:22 INFO - 1500109342809 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342814 Marionette TRACE 388 <- [1,2,null,{}]
02:02:22 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:22 INFO - 1500109342822 Marionette DEBUG Closed connection 388
02:02:22 INFO - 1500109342827 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49914
02:02:22 INFO - 1500109342829 Marionette DEBUG Closed connection 389
02:02:22 INFO - 1500109342831 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49915
02:02:22 INFO - 1500109342833 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:22 INFO - 1500109342836 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:22 INFO - 1500109342859 Marionette DEBUG Register listener.js for window 15
02:02:22 INFO - 1500109342873 Marionette TRACE 390 <- [1,1,null,{"sessionId":"16710cf6-f9fc-4e46-9b3e-a157756468c2","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:22 INFO - 1500109342879 Marionette TRACE 390 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:02:22 INFO - 1500109342883 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"
02:02:22 INFO - 1500109342892 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"
02:02:22 INFO - 1500109342893 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"
02:02:22 INFO - ++DOMWINDOW == 22 (000000DA91E4B000) [pid = 2696] [serial = 46] [outer = 000000DA9C913000]
02:02:22 INFO - 1500109342954 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:02:22 INFO - 1500109342960 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:02:22 INFO - 1500109342979 Marionette TRACE 390 <- [1,2,null,{}]
02:02:22 INFO - 1500109342982 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:02:22 INFO - 1500109342991 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24ceb133-9326-49fa-b03f-b2469d4048f5","ELEMENT":"24ceb133-9326-49fa-b03f-b2469d4048f5"}}]
02:02:22 INFO - 1500109342993 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"24ceb133-9326-49fa-b03f-b2469d4048f5"}]
02:02:23 INFO - 1500109343167 Marionette TRACE 390 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:02:23 INFO - 1500109343173 Marionette TRACE 390 -> [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}]
02:02:23 INFO - TEST-END: test_click.py TestClick.test_input_file1500109343183 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:02:23 INFO - 1500109343185 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343189 Marionette TRACE 390 <- [1,6,null,{}]
02:02:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 542ms
02:02:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343195 Marionette DEBUG Closed connection 390
02:02:23 INFO - 1500109343197 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49916
02:02:23 INFO - 1500109343198 Marionette DEBUG Closed connection 391
02:02:23 INFO - 1500109343200 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49917
02:02:23 INFO - 1500109343202 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:23 INFO - 1500109343204 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:23 INFO - 1500109343227 Marionette DEBUG Register listener.js for window 15
02:02:23 INFO - 1500109343238 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1ccde61f-7a8f-410a-8a74-ec079ed2e22e","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:23 INFO - 1500109343245 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
02:02:23 INFO - 1500109343246 Marionette TRACE 392 <- [1,2,null,{}]
02:02:23 INFO - 1500109343248 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:23 INFO - 1500109343250 Marionette TRACE 392 <- [1,3,null,{}]
02:02:23 INFO - 1500109343252 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:23 INFO - 1500109343253 Marionette TRACE 392 <- [1,4,null,{}]
02:02:23 INFO - 1500109343256 Marionette TRACE 392 -> [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}]
02:02:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500109343265 Marionette TRACE 392 <- [1,5,null,{"value":null}]
02:02:23 INFO - 1500109343267 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343271 Marionette TRACE 392 <- [1,6,null,{}]
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343277 Marionette DEBUG Closed connection 392
02:02:23 INFO - 1500109343279 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49918
02:02:23 INFO - 1500109343280 Marionette DEBUG Closed connection 393
02:02:23 INFO - 1500109343283 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49919
02:02:23 INFO - 1500109343285 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:23 INFO - 1500109343288 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:23 INFO - 1500109343311 Marionette DEBUG Register listener.js for window 15
02:02:23 INFO - 1500109343323 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1b3de15d-a799-43ce-bd23-ee74e325496b","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:23 INFO - 1500109343329 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343333 Marionette TRACE 394 <- [1,2,null,{}]
02:02:23 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:23 INFO - 1500109343339 Marionette DEBUG Closed connection 394
02:02:23 INFO - 1500109343341 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49920
02:02:23 INFO - 1500109343342 Marionette DEBUG Closed connection 395
02:02:23 INFO - 1500109343344 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49921
02:02:23 INFO - 1500109343346 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:23 INFO - 1500109343349 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:23 INFO - 1500109343372 Marionette DEBUG Register listener.js for window 15
02:02:23 INFO - 1500109343384 Marionette TRACE 396 <- [1,1,null,{"sessionId":"51021d7c-f78a-47da-b5f1-61d920134c99","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:23 INFO - 1500109343390 Marionette TRACE 396 -> [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"}]
02:02:23 INFO - 1500109343394 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:02:23 INFO - 1500109343401 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:02:23 INFO - 1500109343402 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:02:23 INFO - ++DOMWINDOW == 23 (000000DA95B3A800) [pid = 2696] [serial = 47] [outer = 000000DA9C913000]
02:02:23 INFO - 1500109343461 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"
02:02:23 INFO - 1500109343467 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"
02:02:23 INFO - 1500109343475 Marionette TRACE 396 <- [1,2,null,{}]
02:02:23 INFO - 1500109343486 Marionette TRACE 396 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:02:23 INFO - 1500109343494 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33814682-c452-4656-83f0-2f7035f4ec06","ELEMENT":"33814682-c452-4656-83f0-2f7035f4ec06"}}]
02:02:23 INFO - 1500109343496 Marionette TRACE 396 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:02:23 INFO - 1500109343503 Marionette TRACE 396 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e527117-6725-461a-9844-09df59106f4d","ELEMENT":"1e527117-6725-461a-9844-09df59106f4d"}}]
02:02:23 INFO - 1500109343505 Marionette TRACE 396 -> [0,5,"clickElement",{"id":"33814682-c452-4656-83f0-2f7035f4ec06"}]
02:02:23 INFO - 1500109343953 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:02:23 INFO - 1500109343955 Marionette TRACE 396 <- [1,5,null,{}]
02:02:23 INFO - 1500109343959 Marionette TRACE 396 -> [0,6,"clickElement",{"id":"1e527117-6725-461a-9844-09df59106f4d"}]
02:02:24 INFO - 1500109344094 Marionette TRACE 396 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:02:24 INFO - 1500109344097 Marionette TRACE 396 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:02:24 INFO - 1500109344105 Marionette TRACE 396 <- [1,7,null,{"value":false}]
02:02:24 INFO - 1500109344108 Marionette TRACE 396 -> [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}]
02:02:24 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500109344116 Marionette TRACE 396 <- [1,8,null,{"value":null}]
02:02:24 INFO - 1500109344118 Marionette TRACE 396 -> [0,9,"deleteSession",{}]
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344122 Marionette TRACE 396 <- [1,9,null,{}]
02:02:24 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 934ms
02:02:24 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344128 Marionette DEBUG Closed connection 396
02:02:24 INFO - 1500109344130 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49922
02:02:24 INFO - 1500109344131 Marionette DEBUG Closed connection 397
02:02:24 INFO - 1500109344133 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49923
02:02:24 INFO - 1500109344135 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:24 INFO - 1500109344137 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:24 INFO - 1500109344160 Marionette DEBUG Register listener.js for window 15
02:02:24 INFO - 1500109344172 Marionette TRACE 398 <- [1,1,null,{"sessionId":"13e02786-d294-4ce1-8876-fa1fc1a19934","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:24 INFO - 1500109344178 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
02:02:24 INFO - 1500109344180 Marionette TRACE 398 <- [1,2,null,{}]
02:02:24 INFO - 1500109344182 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:02:24 INFO - 1500109344184 Marionette TRACE 398 <- [1,3,null,{}]
02:02:24 INFO - 1500109344186 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
02:02:24 INFO - 1500109344187 Marionette TRACE 398 <- [1,4,null,{}]
02:02:24 INFO - 1500109344190 Marionette TRACE 398 -> [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}]
02:02:24 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500109344198 Marionette TRACE 398 <- [1,5,null,{"value":null}]
02:02:24 INFO - 1500109344199 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344204 Marionette TRACE 398 <- [1,6,null,{}]
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344210 Marionette DEBUG Closed connection 398
02:02:24 INFO - 1500109344211 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49924
02:02:24 INFO - 1500109344213 Marionette DEBUG Closed connection 399
02:02:24 INFO - 1500109344214 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49925
02:02:24 INFO - 1500109344216 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:02:24 INFO - 1500109344220 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:24 INFO - 1500109344247 Marionette DEBUG Register listener.js for window 15
02:02:24 INFO - 1500109344261 Marionette TRACE 400 <- [1,1,null,{"sessionId":"62c8d9e2-338e-4f20-b9aa-6c8c6eefd222","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:24 INFO - 1500109344267 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344271 Marionette TRACE 400 <- [1,2,null,{}]
02:02:24 INFO - [Parent 2696] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:02:24 INFO - 1500109344277 Marionette DEBUG Closed connection 400
02:02:24 INFO - 1500109344279 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49926
02:02:24 INFO - 1500109344280 Marionette DEBUG Closed connection 401
02:02:24 INFO - 1500109344282 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49927
02:02:24 INFO - 1500109344284 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:02:24 INFO - 1500109344287 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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}
02:02:24 INFO - 1500109344310 Marionette DEBUG Register listener.js for window 15
02:02:24 INFO - 1500109344321 Marionette TRACE 402 <- [1,1,null,{"sessionId":"2e646d27-5da7-4448-b438-098bacb80de6","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":2696,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-200.001\\AppData\\Local\\Temp\\tmp64gfmc.mozrunner","moz:accessibilityChecks":false}}]
02:02:24 INFO - 1500109344327 Marionette TRACE 402 -> [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"}]
02:02:24 INFO - 1500109344331 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"
02:02:24 INFO - 1500109344341 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"
02:02:24 INFO - 1500109344342 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"
02:02:24 INFO - ++DOMWINDOW == 24 (000000DA9AA58000) [pid = 2696] [serial = 48] [outer = 000000DA9C913000]
02:02:24 INFO - 1500109344399 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"
02:02:24 INFO - 1500109344405 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"
02:02:24 INFO - 1500109344435 Marionette TRACE 402 <- [1,2,null,{}]
02:02:24 INFO - 1500109344441 Marionette TRACE 402 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:02:24 INFO - 1500109344448 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"51a8597c-9124-467a-a192-03281c9e1ca4","ELEMENT":"51a8597c-9124-467a-a192-03281c9e1ca4"}}]
02:02:24 INFO - 1500109344450 Marionette TRACE 402 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"51a8597c-9124-467a-a192-03281c9e1ca4"}]
02:02:24 INFO - 1500109344575 Marionette TRACE 402 <- [1,4,null,{"value":"none"}]
02:02:24 INFO - 1500109344577 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"51a8597c-9124-467a-a192-03281c9e1ca4"}]
02:02:24 INFO - 1500109344721 Marionette TRACE 402 <- [1,5,{"error":"element click intercepted","message":"Element