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]
20:21:08 INFO - 1500607268827 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
20:21:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500607268835 Marionette TRACE 352 <- [1,5,null,{"value":null}]
20:21:08 INFO - 1500607268837 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
20:21:08 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:08 INFO - 1500607268841 Marionette TRACE 352 <- [1,6,null,{}]
20:21:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 685ms
20:21:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:21:08 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:08 INFO - 1500607268848 Marionette DEBUG Closed connection 352
20:21:08 INFO - 1500607268849 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49781
20:21:08 INFO - 1500607268851 Marionette DEBUG Closed connection 353
20:21:08 INFO - 1500607268852 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
20:21:08 INFO - 1500607268854 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:08 INFO - 1500607268857 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:08 INFO - 1500607268877 Marionette DEBUG Register listener.js for window 15
20:21:08 INFO - 1500607268889 Marionette TRACE 354 <- [1,1,null,{"sessionId":"65b0eb26-ed78-4f9c-b6cf-dfa93df0b3b0","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:08 INFO - 1500607268917 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
20:21:08 INFO - 1500607268919 Marionette TRACE 354 <- [1,2,null,{}]
20:21:08 INFO - 1500607268922 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:08 INFO - 1500607268924 Marionette TRACE 354 <- [1,3,null,{}]
20:21:08 INFO - 1500607268926 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:08 INFO - 1500607268928 Marionette TRACE 354 <- [1,4,null,{}]
20:21:08 INFO - 1500607268932 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
20:21:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500607268940 Marionette TRACE 354 <- [1,5,null,{"value":null}]
20:21:09 INFO - 1500607269510 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
20:21:09 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:09 INFO - 1500607269515 Marionette TRACE 354 <- [1,6,null,{}]
20:21:09 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:09 INFO - 1500607269551 Marionette DEBUG Closed connection 354
20:21:09 INFO - 1500607269553 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
20:21:09 INFO - 1500607269597 Marionette DEBUG Closed connection 355
20:21:09 INFO - 1500607269599 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
20:21:09 INFO - 1500607269607 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:09 INFO - 1500607269610 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:09 INFO - 1500607269635 Marionette DEBUG Register listener.js for window 15
20:21:09 INFO - 1500607269650 Marionette TRACE 356 <- [1,1,null,{"sessionId":"49ecde4f-1f7c-407d-a522-41656db0bac8","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:09 INFO - 1500607269662 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
20:21:09 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:09 INFO - 1500607269666 Marionette TRACE 356 <- [1,2,null,{}]
20:21:09 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:09 INFO - 1500607269688 Marionette DEBUG Closed connection 356
20:21:09 INFO - 1500607269690 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
20:21:09 INFO - 1500607269692 Marionette DEBUG Closed connection 357
20:21:09 INFO - 1500607269693 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
20:21:09 INFO - 1500607269695 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:09 INFO - 1500607269698 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:09 INFO - 1500607269718 Marionette DEBUG Register listener.js for window 15
20:21:09 INFO - 1500607269730 Marionette TRACE 358 <- [1,1,null,{"sessionId":"00f0525f-8ec1-42b5-bfaf-27ef44fbb357","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:09 INFO - 1500607269742 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
20:21:09 INFO - 1500607269746 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
20:21:09 INFO - 1500607269758 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
20:21:09 INFO - 1500607269759 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
20:21:09 INFO - ++DOMWINDOW == 20 (0000004819B3B800) [pid = 2516] [serial = 40] [outer = 00000048128B7000]
20:21:09 INFO - 1500607269824 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
20:21:09 INFO - 1500607269829 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
20:21:09 INFO - 1500607269848 Marionette TRACE 358 <- [1,2,null,{}]
20:21:09 INFO - 1500607269855 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:21:09 INFO - 1500607269866 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79f3c5c4-5c0f-40a5-8c72-483c6fd529a4","ELEMENT":"79f3c5c4-5c0f-40a5-8c72-483c6fd529a4"}}]
20:21:09 INFO - 1500607269868 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"79f3c5c4-5c0f-40a5-8c72-483c6fd529a4"}]
20:21:10 INFO - 1500607270091 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
20:21:10 INFO - [Parent 2516] 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
20:21:10 INFO - 1500607270115 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
20:21:10 INFO - 1500607270117 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
20:21:10 INFO - ++DOMWINDOW == 21 (000000481A156800) [pid = 2516] [serial = 41] [outer = 00000048128B7000]
20:21:10 INFO - 1500607270220 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
20:21:10 INFO - 1500607270227 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
20:21:10 INFO - 1500607270243 Marionette TRACE 358 <- [1,4,null,{}]
20:21:10 INFO - 1500607270246 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:21:10 INFO - 1500607270253 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e7d939a-0c58-4c8f-9c5e-56681eadd555","ELEMENT":"9e7d939a-0c58-4c8f-9c5e-56681eadd555"}}]
20:21:10 INFO - 1500607270255 Marionette TRACE 358 -> [0,6,"getTitle",{}]
20:21:10 INFO - 1500607270256 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
20:21:10 INFO - 1500607270259 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
20:21:10 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500607270267 Marionette TRACE 358 <- [1,7,null,{"value":null}]
20:21:10 INFO - 1500607270275 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270280 Marionette TRACE 358 <- [1,8,null,{}]
20:21:10 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1438ms
20:21:10 INFO - TEST-START | test_click.py TestClick.test_container_element
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270286 Marionette DEBUG Closed connection 358
20:21:10 INFO - 1500607270287 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49789
20:21:10 INFO - 1500607270289 Marionette DEBUG Closed connection 359
20:21:10 INFO - 1500607270290 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
20:21:10 INFO - 1500607270293 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:10 INFO - 1500607270295 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:10 INFO - 1500607270316 Marionette DEBUG Register listener.js for window 15
20:21:10 INFO - 1500607270328 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d4dedcc9-9014-4409-824e-2391dc0bbea2","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:10 INFO - 1500607270356 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
20:21:10 INFO - 1500607270358 Marionette TRACE 360 <- [1,2,null,{}]
20:21:10 INFO - 1500607270360 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:10 INFO - 1500607270362 Marionette TRACE 360 <- [1,3,null,{}]
20:21:10 INFO - 1500607270364 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:10 INFO - 1500607270366 Marionette TRACE 360 <- [1,4,null,{}]
20:21:10 INFO - 1500607270368 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
20:21:10 INFO - TEST-START: test_click.py TestClick.test_container_element1500607270379 Marionette TRACE 360 <- [1,5,null,{"value":null}]
20:21:10 INFO - 1500607270381 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270385 Marionette TRACE 360 <- [1,6,null,{}]
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270408 Marionette DEBUG Closed connection 360
20:21:10 INFO - 1500607270410 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
20:21:10 INFO - 1500607270414 Marionette DEBUG Closed connection 361
20:21:10 INFO - 1500607270415 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
20:21:10 INFO - 1500607270469 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:10 INFO - 1500607270480 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:10 INFO - 1500607270521 Marionette DEBUG Register listener.js for window 15
20:21:10 INFO - 1500607270544 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9ab31878-b952-48e9-8ba3-3dcffd64d832","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:10 INFO - 1500607270562 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270569 Marionette TRACE 362 <- [1,2,null,{}]
20:21:10 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:10 INFO - 1500607270629 Marionette DEBUG Closed connection 362
20:21:10 INFO - 1500607270632 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
20:21:10 INFO - 1500607270652 Marionette DEBUG Closed connection 363
20:21:10 INFO - 1500607270655 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
20:21:10 INFO - 1500607270661 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:10 INFO - 1500607270668 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:10 INFO - 1500607270708 Marionette DEBUG Register listener.js for window 15
20:21:10 INFO - 1500607270729 Marionette TRACE 364 <- [1,1,null,{"sessionId":"92fd2212-9031-4cb0-bbe8-54844f3db02d","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:10 INFO - 1500607270742 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
20:21:10 INFO - 1500607270750 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
20:21:10 INFO - 1500607270773 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
20:21:10 INFO - 1500607270774 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
20:21:10 INFO - ++DOMWINDOW == 22 (00000048068D6000) [pid = 2516] [serial = 42] [outer = 00000048128B7000]
20:21:10 INFO - 1500607270858 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:10 INFO - 1500607270873 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:10 INFO - 1500607270886 Marionette TRACE 364 <- [1,2,null,{}]
20:21:10 INFO - 1500607270898 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:21:10 INFO - 1500607270913 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d4bbe6c7-1fda-4cee-9176-8117e96bd0da","ELEMENT":"d4bbe6c7-1fda-4cee-9176-8117e96bd0da"}}]
20:21:10 INFO - 1500607270916 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d4bbe6c7-1fda-4cee-9176-8117e96bd0da"}]
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
20:21:11 INFO - 1500607271513 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:11 INFO - 1500607271517 Marionette TRACE 364 <- [1,4,null,{}]
20:21:11 INFO - 1500607271521 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d4bbe6c7-1fda-4cee-9176-8117e96bd0da","name":"selected"}]
20:21:11 INFO - 1500607271576 Marionette TRACE 364 <- [1,5,null,{"value":true}]
20:21:11 INFO - 1500607271584 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
20:21:11 INFO - TEST-END: test_click.py TestClick.test_container_element1500607271597 Marionette TRACE 364 <- [1,6,null,{"value":null}]
20:21:11 INFO - 1500607271650 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:11 INFO - 1500607271656 Marionette TRACE 364 <- [1,7,null,{}]
20:21:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1378ms
20:21:11 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:11 INFO - 1500607271668 Marionette DEBUG Closed connection 364
20:21:11 INFO - 1500607271670 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
20:21:11 INFO - 1500607271673 Marionette DEBUG Closed connection 365
20:21:11 INFO - 1500607271675 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
20:21:11 INFO - 1500607271698 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:11 INFO - 1500607271701 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:11 INFO - 1500607271742 Marionette DEBUG Register listener.js for window 15
20:21:11 INFO - 1500607271760 Marionette TRACE 366 <- [1,1,null,{"sessionId":"345a53fe-4587-457e-b83f-15027b74f1e6","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:11 INFO - 1500607271788 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
20:21:11 INFO - 1500607271794 Marionette TRACE 366 <- [1,2,null,{}]
20:21:11 INFO - 1500607271820 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:11 INFO - 1500607271823 Marionette TRACE 366 <- [1,3,null,{}]
20:21:11 INFO - 1500607271826 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:11 INFO - 1500607271828 Marionette TRACE 366 <- [1,4,null,{}]
20:21:11 INFO - 1500607271845 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
20:21:11 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500607271856 Marionette TRACE 366 <- [1,5,null,{"value":null}]
20:21:11 INFO - 1500607271860 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:11 INFO - 1500607271866 Marionette TRACE 366 <- [1,6,null,{}]
20:21:11 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:11 INFO - 1500607271888 Marionette DEBUG Closed connection 366
20:21:11 INFO - 1500607271891 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
20:21:12 INFO - 1500607272047 Marionette DEBUG Closed connection 367
20:21:12 INFO - 1500607272050 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
20:21:12 INFO - 1500607272058 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:12 INFO - 1500607272068 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:12 INFO - 1500607272094 Marionette DEBUG Register listener.js for window 15
20:21:12 INFO - 1500607272116 Marionette TRACE 368 <- [1,1,null,{"sessionId":"74de4771-cec6-4abe-8c79-0fa54e9daade","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:12 INFO - 1500607272133 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:12 INFO - 1500607272139 Marionette TRACE 368 <- [1,2,null,{}]
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:12 INFO - 1500607272156 Marionette DEBUG Closed connection 368
20:21:12 INFO - 1500607272159 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
20:21:12 INFO - 1500607272183 Marionette DEBUG Closed connection 369
20:21:12 INFO - 1500607272188 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
20:21:12 INFO - 1500607272194 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:12 INFO - 1500607272202 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:12 INFO - 1500607272227 Marionette DEBUG Register listener.js for window 15
20:21:12 INFO - 1500607272245 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cdc9efc9-a143-4a3b-90c0-9448377065ea","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:12 INFO - 1500607272291 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
20:21:12 INFO - 1500607272298 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:12 INFO - 1500607272305 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:12 INFO - 1500607272306 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:12 INFO - ++DOMWINDOW == 23 (0000004806DA0800) [pid = 2516] [serial = 43] [outer = 00000048128B7000]
20:21:12 INFO - 1500607272371 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:12 INFO - 1500607272377 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:12 INFO - 1500607272399 Marionette TRACE 370 <- [1,2,null,{}]
20:21:12 INFO - 1500607272413 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:21:12 INFO - 1500607272425 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23f23eee-6494-4a26-b9b4-13bcdc0b17ab","ELEMENT":"23f23eee-6494-4a26-b9b4-13bcdc0b17ab"}}]
20:21:12 INFO - 1500607272428 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"23f23eee-6494-4a26-b9b4-13bcdc0b17ab"}]
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
20:21:12 INFO - 1500607272912 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:12 INFO - 1500607272915 Marionette TRACE 370 <- [1,4,null,{}]
20:21:12 INFO - 1500607272922 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"23f23eee-6494-4a26-b9b4-13bcdc0b17ab","name":"selected"}]
20:21:12 INFO - 1500607272954 Marionette TRACE 370 <- [1,5,null,{"value":true}]
20:21:12 INFO - --DOMWINDOW == 22 (0000004806946800) [pid = 2516] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
20:21:12 INFO - --DOMWINDOW == 21 (00000048068D9800) [pid = 2516] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
20:21:12 INFO - --DOMWINDOW == 20 (000000481244A800) [pid = 2516] [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]
20:21:12 INFO - --DOMWINDOW == 19 (000000481120E800) [pid = 2516] [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]
20:21:12 INFO - 1500607272968 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
20:21:12 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500607272979 Marionette TRACE 370 <- [1,6,null,{"value":null}]
20:21:12 INFO - 1500607272983 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
20:21:12 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:12 INFO - 1500607272988 Marionette TRACE 370 <- [1,7,null,{}]
20:21:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1331ms
20:21:12 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:21:13 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:13 INFO - 1500607273016 Marionette DEBUG Closed connection 370
20:21:13 INFO - 1500607273018 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
20:21:13 INFO - 1500607273029 Marionette DEBUG Closed connection 371
20:21:13 INFO - 1500607273032 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
20:21:13 INFO - 1500607273038 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:13 INFO - 1500607273041 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:13 INFO - 1500607273063 Marionette DEBUG Register listener.js for window 15
20:21:13 INFO - 1500607273080 Marionette TRACE 372 <- [1,1,null,{"sessionId":"5d2be057-8bdb-4f9e-bb83-2af11a76d87a","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:13 INFO - 1500607273201 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
20:21:13 INFO - 1500607273207 Marionette TRACE 372 <- [1,2,null,{}]
20:21:13 INFO - 1500607273213 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:13 INFO - 1500607273215 Marionette TRACE 372 <- [1,3,null,{}]
20:21:13 INFO - 1500607273218 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:13 INFO - 1500607273219 Marionette TRACE 372 <- [1,4,null,{}]
20:21:13 INFO - 1500607273222 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
20:21:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500607273232 Marionette TRACE 372 <- [1,5,null,{"value":null}]
20:21:13 INFO - 1500607273235 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
20:21:13 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:13 INFO - 1500607273240 Marionette TRACE 372 <- [1,6,null,{}]
20:21:13 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:13 INFO - 1500607273264 Marionette DEBUG Closed connection 372
20:21:13 INFO - 1500607273266 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
20:21:13 INFO - 1500607273270 Marionette DEBUG Closed connection 373
20:21:13 INFO - 1500607273272 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
20:21:13 INFO - 1500607273274 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:13 INFO - 1500607273277 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:13 INFO - 1500607273299 Marionette DEBUG Register listener.js for window 15
20:21:13 INFO - 1500607273331 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2c403526-4418-4340-a3e6-3982d751eac7","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:13 INFO - 1500607273347 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
20:21:13 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:13 INFO - 1500607273352 Marionette TRACE 374 <- [1,2,null,{}]
20:21:13 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:13 INFO - 1500607273374 Marionette DEBUG Closed connection 374
20:21:13 INFO - 1500607273376 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
20:21:14 INFO - 1500607274010 Marionette DEBUG Closed connection 375
20:21:14 INFO - 1500607274012 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
20:21:14 INFO - 1500607274071 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:14 INFO - 1500607274077 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:14 INFO - 1500607274100 Marionette DEBUG Register listener.js for window 15
20:21:14 INFO - 1500607274116 Marionette TRACE 376 <- [1,1,null,{"sessionId":"12fbd05c-146f-4fed-9bfe-33407895144b","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:14 INFO - 1500607274161 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
20:21:14 INFO - 1500607274166 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:14 INFO - 1500607274172 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:14 INFO - 1500607274173 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:14 INFO - ++DOMWINDOW == 20 (000000480731A800) [pid = 2516] [serial = 44] [outer = 00000048128B7000]
20:21:14 INFO - 1500607274284 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:21:14 INFO - 1500607274290 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:21:14 INFO - 1500607274302 Marionette TRACE 376 <- [1,2,null,{}]
20:21:14 INFO - 1500607274313 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:21:14 INFO - 1500607274320 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3cfd548a-fca6-453b-9e87-68ee050b0b3b","ELEMENT":"3cfd548a-fca6-453b-9e87-68ee050b0b3b"}}]
20:21:14 INFO - 1500607274322 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3cfd548a-fca6-453b-9e87-68ee050b0b3b"}]
20:21:14 INFO - 1500607274812 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:14 INFO - 1500607274814 Marionette TRACE 376 <- [1,4,null,{}]
20:21:14 INFO - 1500607274817 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
20:21:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500607274825 Marionette TRACE 376 <- [1,5,null,{"value":null}]
20:21:14 INFO - 1500607274827 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274831 Marionette TRACE 376 <- [1,6,null,{}]
20:21:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1841ms
20:21:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274838 Marionette DEBUG Closed connection 376
20:21:14 INFO - 1500607274839 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
20:21:14 INFO - 1500607274843 Marionette DEBUG Closed connection 377
20:21:14 INFO - 1500607274848 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
20:21:14 INFO - 1500607274851 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:14 INFO - 1500607274853 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:14 INFO - 1500607274875 Marionette DEBUG Register listener.js for window 15
20:21:14 INFO - 1500607274889 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4f70db9b-7436-4ee9-809d-0d03988e7546","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:14 INFO - 1500607274895 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
20:21:14 INFO - 1500607274897 Marionette TRACE 378 <- [1,2,null,{}]
20:21:14 INFO - 1500607274899 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:14 INFO - 1500607274901 Marionette TRACE 378 <- [1,3,null,{}]
20:21:14 INFO - 1500607274902 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:14 INFO - 1500607274904 Marionette TRACE 378 <- [1,4,null,{}]
20:21:14 INFO - 1500607274907 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
20:21:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500607274919 Marionette TRACE 378 <- [1,5,null,{"value":null}]
20:21:14 INFO - 1500607274921 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274927 Marionette TRACE 378 <- [1,6,null,{}]
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274933 Marionette DEBUG Closed connection 378
20:21:14 INFO - 1500607274935 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
20:21:14 INFO - 1500607274937 Marionette DEBUG Closed connection 379
20:21:14 INFO - 1500607274938 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
20:21:14 INFO - 1500607274940 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:14 INFO - 1500607274943 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:14 INFO - 1500607274965 Marionette DEBUG Register listener.js for window 15
20:21:14 INFO - 1500607274976 Marionette TRACE 380 <- [1,1,null,{"sessionId":"e5da236e-2b38-42e6-8711-aed50e71f9b3","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:14 INFO - 1500607274983 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274987 Marionette TRACE 380 <- [1,2,null,{}]
20:21:14 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:14 INFO - 1500607274993 Marionette DEBUG Closed connection 380
20:21:14 INFO - 1500607274994 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
20:21:14 INFO - 1500607274996 Marionette DEBUG Closed connection 381
20:21:14 INFO - 1500607274997 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
20:21:15 INFO - 1500607275000 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:15 INFO - 1500607275002 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:15 INFO - 1500607275024 Marionette DEBUG Register listener.js for window 15
20:21:15 INFO - 1500607275036 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8c1bbfb8-34d4-4c47-8368-c5eaae2c8d38","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:15 INFO - 1500607275043 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
20:21:15 INFO - 1500607275048 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:21:15 INFO - 1500607275053 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:21:15 INFO - 1500607275054 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:21:15 INFO - ++DOMWINDOW == 21 (00000048118C4000) [pid = 2516] [serial = 45] [outer = 00000048128B7000]
20:21:15 INFO - 1500607275201 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:15 INFO - 1500607275210 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:15 INFO - 1500607275227 Marionette TRACE 382 <- [1,2,null,{}]
20:21:15 INFO - 1500607275234 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:21:15 INFO - 1500607275243 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e39ce0d4-0b98-401b-8b05-692bbb0dc160","ELEMENT":"e39ce0d4-0b98-401b-8b05-692bbb0dc160"}}]
20:21:15 INFO - 1500607275245 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e39ce0d4-0b98-401b-8b05-692bbb0dc160"}]
20:21:15 INFO - 1500607275682 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:15 INFO - 1500607275684 Marionette TRACE 382 <- [1,4,null,{}]
20:21:15 INFO - 1500607275686 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
20:21:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500607275694 Marionette TRACE 382 <- [1,5,null,{"value":null}]
20:21:15 INFO - 1500607275696 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275701 Marionette TRACE 382 <- [1,6,null,{}]
20:21:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 870ms
20:21:15 INFO - TEST-START | test_click.py TestClick.test_input_file
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275707 Marionette DEBUG Closed connection 382
20:21:15 INFO - 1500607275709 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
20:21:15 INFO - 1500607275711 Marionette DEBUG Closed connection 383
20:21:15 INFO - 1500607275712 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
20:21:15 INFO - 1500607275714 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:15 INFO - 1500607275717 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:15 INFO - 1500607275739 Marionette DEBUG Register listener.js for window 15
20:21:15 INFO - 1500607275751 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ca228edb-c7d5-4614-bf1f-2f17328de66a","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:15 INFO - 1500607275757 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
20:21:15 INFO - 1500607275759 Marionette TRACE 384 <- [1,2,null,{}]
20:21:15 INFO - 1500607275761 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:15 INFO - 1500607275762 Marionette TRACE 384 <- [1,3,null,{}]
20:21:15 INFO - 1500607275764 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:15 INFO - 1500607275766 Marionette TRACE 384 <- [1,4,null,{}]
20:21:15 INFO - 1500607275768 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
20:21:15 INFO - TEST-START: test_click.py TestClick.test_input_file1500607275778 Marionette TRACE 384 <- [1,5,null,{"value":null}]
20:21:15 INFO - 1500607275780 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275784 Marionette TRACE 384 <- [1,6,null,{}]
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275834 Marionette DEBUG Closed connection 384
20:21:15 INFO - 1500607275836 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
20:21:15 INFO - 1500607275837 Marionette DEBUG Closed connection 385
20:21:15 INFO - 1500607275839 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
20:21:15 INFO - 1500607275841 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:15 INFO - 1500607275843 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:15 INFO - 1500607275865 Marionette DEBUG Register listener.js for window 15
20:21:15 INFO - 1500607275877 Marionette TRACE 386 <- [1,1,null,{"sessionId":"be2b9ba4-b253-4c82-ac3d-8a151c1a9457","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:15 INFO - 1500607275883 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275888 Marionette TRACE 386 <- [1,2,null,{}]
20:21:15 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:15 INFO - 1500607275894 Marionette DEBUG Closed connection 386
20:21:15 INFO - 1500607275895 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
20:21:15 INFO - 1500607275897 Marionette DEBUG Closed connection 387
20:21:15 INFO - 1500607275898 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
20:21:15 INFO - 1500607275901 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:15 INFO - 1500607275904 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:15 INFO - 1500607275938 Marionette DEBUG Register listener.js for window 15
20:21:15 INFO - 1500607275953 Marionette TRACE 388 <- [1,1,null,{"sessionId":"dad5dafe-8adb-4d9e-863a-616807a33a67","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:15 INFO - 1500607275986 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:21:15 INFO - 1500607275991 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:15 INFO - 1500607275996 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:15 INFO - 1500607275997 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:21:15 INFO - ++DOMWINDOW == 22 (000000481213E000) [pid = 2516] [serial = 46] [outer = 00000048128B7000]
20:21:16 INFO - 1500607276057 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:16 INFO - 1500607276063 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:16 INFO - 1500607276080 Marionette TRACE 388 <- [1,2,null,{}]
20:21:16 INFO - 1500607276088 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:21:16 INFO - 1500607276097 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d217f6f-573e-4718-add9-546bba628990","ELEMENT":"6d217f6f-573e-4718-add9-546bba628990"}}]
20:21:16 INFO - 1500607276099 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6d217f6f-573e-4718-add9-546bba628990"}]
20:21:16 INFO - 1500607276263 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
20:21:16 INFO - 1500607276281 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
20:21:16 INFO - TEST-END: test_click.py TestClick.test_input_file1500607276290 Marionette TRACE 388 <- [1,5,null,{"value":null}]
20:21:16 INFO - 1500607276292 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
20:21:16 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:16 INFO - 1500607276296 Marionette TRACE 388 <- [1,6,null,{}]
20:21:16 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 595ms
20:21:16 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:21:16 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:16 INFO - 1500607276319 Marionette DEBUG Closed connection 388
20:21:16 INFO - 1500607276320 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
20:21:16 INFO - 1500607276325 Marionette DEBUG Closed connection 389
20:21:16 INFO - 1500607276326 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
20:21:16 INFO - 1500607276329 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:16 INFO - 1500607276331 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:16 INFO - 1500607276353 Marionette DEBUG Register listener.js for window 15
20:21:16 INFO - 1500607276365 Marionette TRACE 390 <- [1,1,null,{"sessionId":"4106b981-7aea-4ca9-8c71-78920e127ab4","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:16 INFO - 1500607276375 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
20:21:16 INFO - 1500607276376 Marionette TRACE 390 <- [1,2,null,{}]
20:21:16 INFO - 1500607276379 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:16 INFO - 1500607276381 Marionette TRACE 390 <- [1,3,null,{}]
20:21:16 INFO - 1500607276383 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:16 INFO - 1500607276385 Marionette TRACE 390 <- [1,4,null,{}]
20:21:16 INFO - 1500607276388 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
20:21:16 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500607276395 Marionette TRACE 390 <- [1,5,null,{"value":null}]
20:21:16 INFO - 1500607276397 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
20:21:16 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:16 INFO - 1500607276401 Marionette TRACE 390 <- [1,6,null,{}]
20:21:16 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:16 INFO - 1500607276427 Marionette DEBUG Closed connection 390
20:21:16 INFO - 1500607276428 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
20:21:16 INFO - 1500607276432 Marionette DEBUG Closed connection 391
20:21:16 INFO - 1500607276433 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
20:21:17 INFO - 1500607277019 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:17 INFO - 1500607277021 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:17 INFO - 1500607277043 Marionette DEBUG Register listener.js for window 15
20:21:17 INFO - 1500607277054 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a8045bfb-50f4-4c2a-bfaa-70a82146c4b3","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:17 INFO - 1500607277074 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
20:21:17 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:17 INFO - 1500607277079 Marionette TRACE 392 <- [1,2,null,{}]
20:21:17 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:17 INFO - 1500607277101 Marionette DEBUG Closed connection 392
20:21:17 INFO - 1500607277102 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
20:21:17 INFO - 1500607277106 Marionette DEBUG Closed connection 393
20:21:17 INFO - 1500607277107 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
20:21:17 INFO - 1500607277110 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:17 INFO - 1500607277113 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:17 INFO - 1500607277135 Marionette DEBUG Register listener.js for window 15
20:21:17 INFO - 1500607277146 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8e49a1cb-1570-4858-bebf-4fb882507df3","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:17 INFO - 1500607277155 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
20:21:17 INFO - 1500607277161 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:17 INFO - 1500607277167 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:17 INFO - 1500607277168 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:21:17 INFO - ++DOMWINDOW == 23 (0000004819B41800) [pid = 2516] [serial = 47] [outer = 00000048128B7000]
20:21:17 INFO - 1500607277226 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:21:17 INFO - 1500607277232 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:21:17 INFO - 1500607277248 Marionette TRACE 394 <- [1,2,null,{}]
20:21:17 INFO - 1500607277255 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:21:17 INFO - 1500607277263 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0a8ac7e3-5198-4e25-9b70-08a87e4a1e65","ELEMENT":"0a8ac7e3-5198-4e25-9b70-08a87e4a1e65"}}]
20:21:17 INFO - 1500607277265 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:21:17 INFO - 1500607277272 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f20c13ba-d658-4963-80e2-3c94f5944937","ELEMENT":"f20c13ba-d658-4963-80e2-3c94f5944937"}}]
20:21:17 INFO - 1500607277274 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"0a8ac7e3-5198-4e25-9b70-08a87e4a1e65"}]
20:21:17 INFO - 1500607277724 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:21:17 INFO - 1500607277727 Marionette TRACE 394 <- [1,5,null,{}]
20:21:17 INFO - 1500607277733 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f20c13ba-d658-4963-80e2-3c94f5944937"}]
20:21:18 INFO - 1500607278010 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
20:21:18 INFO - 1500607278039 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
20:21:18 INFO - 1500607278055 Marionette TRACE 394 <- [1,7,null,{"value":false}]
20:21:18 INFO - 1500607278106 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
20:21:18 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500607278121 Marionette TRACE 394 <- [1,8,null,{"value":null}]
20:21:18 INFO - 1500607278128 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278137 Marionette TRACE 394 <- [1,9,null,{}]
20:21:18 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1841ms
20:21:18 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278169 Marionette DEBUG Closed connection 394
20:21:18 INFO - 1500607278173 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
20:21:18 INFO - 1500607278204 Marionette DEBUG Closed connection 395
20:21:18 INFO - 1500607278207 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
20:21:18 INFO - 1500607278230 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:18 INFO - 1500607278240 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:18 INFO - 1500607278285 Marionette DEBUG Register listener.js for window 15
20:21:18 INFO - 1500607278307 Marionette TRACE 396 <- [1,1,null,{"sessionId":"52a1109d-574a-4424-b015-1717eb8306c7","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:18 INFO - 1500607278335 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
20:21:18 INFO - 1500607278338 Marionette TRACE 396 <- [1,2,null,{}]
20:21:18 INFO - 1500607278342 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:21:18 INFO - 1500607278344 Marionette TRACE 396 <- [1,3,null,{}]
20:21:18 INFO - 1500607278397 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
20:21:18 INFO - 1500607278400 Marionette TRACE 396 <- [1,4,null,{}]
20:21:18 INFO - 1500607278406 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
20:21:18 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500607278418 Marionette TRACE 396 <- [1,5,null,{"value":null}]
20:21:18 INFO - 1500607278422 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278429 Marionette TRACE 396 <- [1,6,null,{}]
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278488 Marionette DEBUG Closed connection 396
20:21:18 INFO - 1500607278491 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
20:21:18 INFO - 1500607278512 Marionette DEBUG Closed connection 397
20:21:18 INFO - 1500607278515 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
20:21:18 INFO - 1500607278523 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:21:18 INFO - 1500607278529 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:18 INFO - 1500607278575 Marionette DEBUG Register listener.js for window 15
20:21:18 INFO - 1500607278593 Marionette TRACE 398 <- [1,1,null,{"sessionId":"ff7c138e-208f-44d9-8556-e085387ee23d","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:18 INFO - 1500607278617 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278624 Marionette TRACE 398 <- [1,2,null,{}]
20:21:18 INFO - [Parent 2516] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
20:21:18 INFO - 1500607278632 Marionette DEBUG Closed connection 398
20:21:18 INFO - 1500607278634 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
20:21:18 INFO - 1500607278659 Marionette DEBUG Closed connection 399
20:21:18 INFO - 1500607278661 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
20:21:18 INFO - 1500607278680 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:21:18 INFO - 1500607278685 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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}
20:21:18 INFO - 1500607278730 Marionette DEBUG Register listener.js for window 15
20:21:18 INFO - 1500607278747 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ac759394-f491-48c6-a9b2-3d93fdd66aeb","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":2516,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-288.000\\AppData\\Local\\Temp\\tmp9rxx8n.mozrunner","moz:accessibilityChecks":false}}]
20:21:18 INFO - 1500607278759 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
20:21:18 INFO - 1500607278766 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:21:18 INFO - 1500607278775 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:21:18 INFO - 1500607278776 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:21:18 INFO - ++DOMWINDOW == 24 (00000048138A5800) [pid = 2516] [serial = 48] [outer = 00000048128B7000]
20:21:19 INFO - 1500607279011 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
20:21:19 INFO - 1500607279018 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
20:21:19 INFO - 1500607279038 Marionette TRACE 400 <- [1,2,null,{}]
20:21:19 INFO - 1500607279048 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:21:19 INFO - 1500607279061 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"856c08c2-a60e-4dcf-b5e5-3c023c31b0ff","ELEMENT":"856c08c2-a60e-4dcf-b5e5-3c023c31b0ff"}}]
20:21:19 INFO - 1500607279066 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"856c08c2-a60e-4dcf-b5e5-3c023c31b0ff"}]
20:21:19 INFO - 1500607279223 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
20:21:19 INFO - 1500607279227 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"856c08c2-a60e-4dcf-b5e5-3c023c31b0ff"}]
20:21:19 INFO - 1500607279397 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element