could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:29 INFO - 1501021289115 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}]
15:21:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501021289123 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:21:29 INFO - 1501021289125 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289129 Marionette TRACE 352 <- [1,6,null,{}]
15:21:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1625ms
15:21:29 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289135 Marionette DEBUG Closed connection 352
15:21:29 INFO - 1501021289136 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49789
15:21:29 INFO - 1501021289138 Marionette DEBUG Closed connection 353
15:21:29 INFO - 1501021289139 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49790
15:21:29 INFO - 1501021289161 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:29 INFO - 1501021289163 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:29 INFO - 1501021289185 Marionette DEBUG Register listener.js for window 12
15:21:29 INFO - 1501021289196 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9cb24337-63ab-48f2-b53f-7eeefb7ed030","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:29 INFO - 1501021289202 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:21:29 INFO - 1501021289203 Marionette TRACE 354 <- [1,2,null,{}]
15:21:29 INFO - 1501021289205 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:29 INFO - 1501021289206 Marionette TRACE 354 <- [1,3,null,{}]
15:21:29 INFO - 1501021289208 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:29 INFO - 1501021289210 Marionette TRACE 354 <- [1,4,null,{}]
15:21:29 INFO - 1501021289212 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}]
15:21:29 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501021289219 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:21:29 INFO - 1501021289221 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289225 Marionette TRACE 354 <- [1,6,null,{}]
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289231 Marionette DEBUG Closed connection 354
15:21:29 INFO - 1501021289232 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49791
15:21:29 INFO - 1501021289234 Marionette DEBUG Closed connection 355
15:21:29 INFO - 1501021289235 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49792
15:21:29 INFO - 1501021289238 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:29 INFO - 1501021289240 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:29 INFO - 1501021289260 Marionette DEBUG Register listener.js for window 12
15:21:29 INFO - 1501021289271 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e4ab9465-3c5b-4977-a936-5ad12348fd81","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:29 INFO - 1501021289277 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289281 Marionette TRACE 356 <- [1,2,null,{}]
15:21:29 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:29 INFO - 1501021289287 Marionette DEBUG Closed connection 356
15:21:29 INFO - 1501021289288 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49793
15:21:29 INFO - 1501021289340 Marionette DEBUG Closed connection 357
15:21:29 INFO - 1501021289343 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49794
15:21:29 INFO - 1501021289395 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:29 INFO - 1501021289406 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:29 INFO - 1501021289446 Marionette DEBUG Register listener.js for window 12
15:21:29 INFO - 1501021289468 Marionette TRACE 358 <- [1,1,null,{"sessionId":"89110d66-f292-45e3-8ffe-65358188e414","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:29 INFO - 1501021289484 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
15:21:29 INFO - 1501021289490 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
15:21:29 INFO - 1501021289522 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
15:21:29 INFO - 1501021289524 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
15:21:29 INFO - ++DOMWINDOW == 28 (0000005A11CB0800) [pid = 4064] [serial = 41] [outer = 0000005A1D459000]
15:21:29 INFO - 1501021289610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
15:21:29 INFO - 1501021289617 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
15:21:29 INFO - 1501021289649 Marionette TRACE 358 <- [1,2,null,{}]
15:21:29 INFO - 1501021289659 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:21:29 INFO - 1501021289671 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"16cca924-e1e4-4662-a72a-ac9298f870df","ELEMENT":"16cca924-e1e4-4662-a72a-ac9298f870df"}}]
15:21:29 INFO - 1501021289674 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"16cca924-e1e4-4662-a72a-ac9298f870df"}]
15:21:30 INFO - 1501021290059 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
15:21:30 INFO - [Parent 4064] 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
15:21:30 INFO - 1501021290080 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
15:21:30 INFO - 1501021290082 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
15:21:30 INFO - ++DOMWINDOW == 29 (0000005A1C450800) [pid = 4064] [serial = 42] [outer = 0000005A1D459000]
15:21:30 INFO - 1501021290157 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
15:21:30 INFO - 1501021290163 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
15:21:30 INFO - 1501021290185 Marionette TRACE 358 <- [1,4,null,{}]
15:21:30 INFO - 1501021290189 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:21:30 INFO - 1501021290203 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"330bd573-cd34-47a3-ad98-bb015ce712af","ELEMENT":"330bd573-cd34-47a3-ad98-bb015ce712af"}}]
15:21:30 INFO - 1501021290207 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:21:30 INFO - 1501021290210 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:21:30 INFO - 1501021290213 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}]
15:21:30 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501021290237 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:21:30 INFO - 1501021290281 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290288 Marionette TRACE 358 <- [1,8,null,{}]
15:21:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1159ms
15:21:30 INFO - TEST-START | test_click.py TestClick.test_container_element
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290297 Marionette DEBUG Closed connection 358
15:21:30 INFO - 1501021290299 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49797
15:21:30 INFO - 1501021290302 Marionette DEBUG Closed connection 359
15:21:30 INFO - 1501021290306 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49798
15:21:30 INFO - 1501021290311 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:30 INFO - 1501021290315 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:30 INFO - 1501021290354 Marionette DEBUG Register listener.js for window 12
15:21:30 INFO - 1501021290371 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0051fa93-1087-4d4f-9789-67e487cde620","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:30 INFO - 1501021290380 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:21:30 INFO - 1501021290383 Marionette TRACE 360 <- [1,2,null,{}]
15:21:30 INFO - 1501021290423 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:30 INFO - 1501021290429 Marionette TRACE 360 <- [1,3,null,{}]
15:21:30 INFO - 1501021290432 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:30 INFO - 1501021290435 Marionette TRACE 360 <- [1,4,null,{}]
15:21:30 INFO - 1501021290486 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}]
15:21:30 INFO - TEST-START: test_click.py TestClick.test_container_element1501021290497 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:21:30 INFO - 1501021290501 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290507 Marionette TRACE 360 <- [1,6,null,{}]
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290531 Marionette DEBUG Closed connection 360
15:21:30 INFO - 1501021290533 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49799
15:21:30 INFO - 1501021290585 Marionette DEBUG Closed connection 361
15:21:30 INFO - 1501021290588 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49800
15:21:30 INFO - 1501021290594 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:30 INFO - 1501021290597 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:30 INFO - 1501021290636 Marionette DEBUG Register listener.js for window 12
15:21:30 INFO - 1501021290652 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cc4dff43-fd35-4990-bc7c-424e21e98c99","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:30 INFO - 1501021290663 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290668 Marionette TRACE 362 <- [1,2,null,{}]
15:21:30 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:30 INFO - 1501021290675 Marionette DEBUG Closed connection 362
15:21:30 INFO - 1501021290677 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49801
15:21:30 INFO - 1501021290701 Marionette DEBUG Closed connection 363
15:21:30 INFO - 1501021290703 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49802
15:21:30 INFO - 1501021290706 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:30 INFO - 1501021290709 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:30 INFO - 1501021290749 Marionette DEBUG Register listener.js for window 12
15:21:30 INFO - 1501021290775 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2393821c-cfd2-48d5-b0b1-51e32699a9b3","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:30 INFO - 1501021290783 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"}]
15:21:30 INFO - 1501021290790 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
15:21:30 INFO - 1501021290797 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
15:21:30 INFO - 1501021290798 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
15:21:30 INFO - ++DOMWINDOW == 30 (0000005A235D2800) [pid = 4064] [serial = 43] [outer = 0000005A1D459000]
15:21:30 INFO - 1501021290867 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"
15:21:30 INFO - 1501021290874 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"
15:21:30 INFO - 1501021290892 Marionette TRACE 364 <- [1,2,null,{}]
15:21:30 INFO - 1501021290906 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:21:30 INFO - 1501021290916 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c01990f-31b1-4c9a-a3e9-265b6e9f404d","ELEMENT":"7c01990f-31b1-4c9a-a3e9-265b6e9f404d"}}]
15:21:30 INFO - 1501021290918 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7c01990f-31b1-4c9a-a3e9-265b6e9f404d"}]
15:21:31 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:21:31 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:21:31 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:21:31 INFO - 1501021291632 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:31 INFO - 1501021291637 Marionette TRACE 364 <- [1,4,null,{}]
15:21:31 INFO - 1501021291643 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7c01990f-31b1-4c9a-a3e9-265b6e9f404d","name":"selected"}]
15:21:31 INFO - 1501021291683 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:21:31 INFO - 1501021291693 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}]
15:21:31 INFO - TEST-END: test_click.py TestClick.test_container_element1501021291707 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:21:31 INFO - 1501021291761 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:21:31 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:31 INFO - 1501021291769 Marionette TRACE 364 <- [1,7,null,{}]
15:21:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1481ms
15:21:31 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:21:31 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:31 INFO - 1501021291778 Marionette DEBUG Closed connection 364
15:21:31 INFO - 1501021291783 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49803
15:21:31 INFO - 1501021291811 Marionette DEBUG Closed connection 365
15:21:31 INFO - 1501021291815 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49804
15:21:31 INFO - 1501021291820 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:31 INFO - 1501021291830 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:31 INFO - 1501021291855 Marionette DEBUG Register listener.js for window 12
15:21:31 INFO - 1501021291876 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0fdcf49b-af27-4e0a-a43c-2fffc2339c45","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:31 INFO - 1501021291900 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:21:31 INFO - 1501021291905 Marionette TRACE 366 <- [1,2,null,{}]
15:21:31 INFO - 1501021291912 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:31 INFO - 1501021291914 Marionette TRACE 366 <- [1,3,null,{}]
15:21:31 INFO - 1501021291918 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:31 INFO - 1501021291920 Marionette TRACE 366 <- [1,4,null,{}]
15:21:31 INFO - 1501021291985 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}]
15:21:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501021291997 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:21:32 INFO - 1501021292001 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:32 INFO - 1501021292007 Marionette TRACE 366 <- [1,6,null,{}]
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:32 INFO - 1501021292036 Marionette DEBUG Closed connection 366
15:21:32 INFO - 1501021292039 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49805
15:21:32 INFO - 1501021292043 Marionette DEBUG Closed connection 367
15:21:32 INFO - 1501021292046 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49806
15:21:32 INFO - 1501021292052 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:32 INFO - 1501021292056 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:32 INFO - 1501021292079 Marionette DEBUG Register listener.js for window 12
15:21:32 INFO - 1501021292096 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f40dacc5-0da8-44d5-a012-d40c7370d3f7","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:32 INFO - 1501021292113 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:32 INFO - 1501021292121 Marionette TRACE 368 <- [1,2,null,{}]
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:32 INFO - 1501021292130 Marionette DEBUG Closed connection 368
15:21:32 INFO - 1501021292132 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49807
15:21:32 INFO - --DOMWINDOW == 29 (0000005A1CDC5800) [pid = 4064] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 28 (0000005A1CDA8800) [pid = 4064] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 27 (0000005A2367E000) [pid = 4064] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 26 (0000005A17D87800) [pid = 4064] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 25 (0000005A237C5800) [pid = 4064] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 24 (0000005A252E3000) [pid = 4064] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - --DOMWINDOW == 23 (0000005A1DAC3000) [pid = 4064] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:32 INFO - 1501021292165 Marionette DEBUG Closed connection 369
15:21:32 INFO - 1501021292167 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49808
15:21:32 INFO - 1501021292171 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:32 INFO - 1501021292175 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:32 INFO - 1501021292198 Marionette DEBUG Register listener.js for window 12
15:21:32 INFO - 1501021292213 Marionette TRACE 370 <- [1,1,null,{"sessionId":"07cc85e7-affe-400d-8d0d-ea76f596647a","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:32 INFO - 1501021292228 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"}]
15:21:32 INFO - 1501021292234 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"
15:21:32 INFO - 1501021292243 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"
15:21:32 INFO - 1501021292244 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"
15:21:32 INFO - ++DOMWINDOW == 24 (0000005A1315D000) [pid = 4064] [serial = 44] [outer = 0000005A1D459000]
15:21:32 INFO - 1501021292306 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"
15:21:32 INFO - 1501021292312 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"
15:21:32 INFO - 1501021292334 Marionette TRACE 370 <- [1,2,null,{}]
15:21:32 INFO - 1501021292337 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:21:32 INFO - 1501021292348 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50777f89-4aa9-44b5-bb1b-b0ecab112c29","ELEMENT":"50777f89-4aa9-44b5-bb1b-b0ecab112c29"}}]
15:21:32 INFO - 1501021292349 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"50777f89-4aa9-44b5-bb1b-b0ecab112c29"}]
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:21:32 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:21:33 INFO - 1501021293491 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:33 INFO - 1501021293513 Marionette TRACE 370 <- [1,4,null,{}]
15:21:33 INFO - 1501021293561 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"50777f89-4aa9-44b5-bb1b-b0ecab112c29","name":"selected"}]
15:21:33 INFO - 1501021293590 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:21:33 INFO - 1501021293637 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}]
15:21:33 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501021293649 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:21:33 INFO - 1501021293697 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:21:33 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:33 INFO - 1501021293701 Marionette TRACE 370 <- [1,7,null,{}]
15:21:33 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1930ms
15:21:33 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:21:33 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:33 INFO - 1501021293709 Marionette DEBUG Closed connection 370
15:21:33 INFO - 1501021293711 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49809
15:21:33 INFO - 1501021293713 Marionette DEBUG Closed connection 371
15:21:33 INFO - 1501021293715 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49810
15:21:33 INFO - 1501021293717 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:33 INFO - 1501021293720 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:33 INFO - 1501021293742 Marionette DEBUG Register listener.js for window 12
15:21:33 INFO - 1501021293756 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b254b645-fecd-495b-b009-9a6afba94732","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:33 INFO - 1501021293814 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:21:33 INFO - 1501021293816 Marionette TRACE 372 <- [1,2,null,{}]
15:21:33 INFO - 1501021293869 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:33 INFO - 1501021293871 Marionette TRACE 372 <- [1,3,null,{}]
15:21:33 INFO - 1501021293919 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:33 INFO - 1501021293921 Marionette TRACE 372 <- [1,4,null,{}]
15:21:33 INFO - 1501021293942 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}]
15:21:33 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501021293951 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:21:33 INFO - 1501021293953 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:21:33 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:33 INFO - 1501021293957 Marionette TRACE 372 <- [1,6,null,{}]
15:21:33 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:33 INFO - 1501021293982 Marionette DEBUG Closed connection 372
15:21:33 INFO - 1501021293984 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49811
15:21:33 INFO - 1501021293989 Marionette DEBUG Closed connection 373
15:21:33 INFO - 1501021293991 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49812
15:21:33 INFO - 1501021293993 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:34 INFO - 1501021293998 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:34 INFO - 1501021294021 Marionette DEBUG Register listener.js for window 12
15:21:34 INFO - 1501021294035 Marionette TRACE 374 <- [1,1,null,{"sessionId":"41c538a3-a081-4d8b-b5b3-51248416f694","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:34 INFO - 1501021294045 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294051 Marionette TRACE 374 <- [1,2,null,{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294074 Marionette DEBUG Closed connection 374
15:21:34 INFO - 1501021294079 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49813
15:21:34 INFO - 1501021294084 Marionette DEBUG Closed connection 375
15:21:34 INFO - 1501021294086 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49814
15:21:34 INFO - 1501021294089 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:34 INFO - 1501021294091 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:34 INFO - 1501021294114 Marionette DEBUG Register listener.js for window 12
15:21:34 INFO - 1501021294128 Marionette TRACE 376 <- [1,1,null,{"sessionId":"30f1ff5d-da38-401f-b2c1-3afcfa27b9d3","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:34 INFO - 1501021294138 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"}]
15:21:34 INFO - 1501021294143 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"
15:21:34 INFO - 1501021294151 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"
15:21:34 INFO - 1501021294152 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"
15:21:34 INFO - ++DOMWINDOW == 25 (0000005A1C9A8800) [pid = 4064] [serial = 45] [outer = 0000005A1D459000]
15:21:34 INFO - 1501021294215 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"
15:21:34 INFO - 1501021294221 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"
15:21:34 INFO - 1501021294234 Marionette TRACE 376 <- [1,2,null,{}]
15:21:34 INFO - 1501021294246 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:21:34 INFO - 1501021294255 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80a879cb-0405-4a83-af5c-ce32e2528fee","ELEMENT":"80a879cb-0405-4a83-af5c-ce32e2528fee"}}]
15:21:34 INFO - 1501021294258 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"80a879cb-0405-4a83-af5c-ce32e2528fee"}]
15:21:34 INFO - 1501021294726 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:34 INFO - 1501021294728 Marionette TRACE 376 <- [1,4,null,{}]
15:21:34 INFO - 1501021294730 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}]
15:21:34 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501021294739 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:21:34 INFO - 1501021294741 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294745 Marionette TRACE 376 <- [1,6,null,{}]
15:21:34 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1043ms
15:21:34 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294751 Marionette DEBUG Closed connection 376
15:21:34 INFO - 1501021294753 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49815
15:21:34 INFO - 1501021294754 Marionette DEBUG Closed connection 377
15:21:34 INFO - 1501021294756 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49816
15:21:34 INFO - 1501021294758 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:34 INFO - 1501021294761 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:34 INFO - 1501021294784 Marionette DEBUG Register listener.js for window 12
15:21:34 INFO - 1501021294796 Marionette TRACE 378 <- [1,1,null,{"sessionId":"14c3bd50-c711-4cd9-bab4-d01e36028c7e","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:34 INFO - 1501021294829 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:21:34 INFO - 1501021294831 Marionette TRACE 378 <- [1,2,null,{}]
15:21:34 INFO - 1501021294833 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:34 INFO - 1501021294834 Marionette TRACE 378 <- [1,3,null,{}]
15:21:34 INFO - 1501021294836 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:34 INFO - 1501021294838 Marionette TRACE 378 <- [1,4,null,{}]
15:21:34 INFO - 1501021294842 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}]
15:21:34 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501021294852 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:21:34 INFO - 1501021294854 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294858 Marionette TRACE 378 <- [1,6,null,{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294883 Marionette DEBUG Closed connection 378
15:21:34 INFO - 1501021294885 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49817
15:21:34 INFO - 1501021294886 Marionette DEBUG Closed connection 379
15:21:34 INFO - 1501021294889 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49818
15:21:34 INFO - 1501021294891 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:34 INFO - 1501021294894 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:34 INFO - 1501021294917 Marionette DEBUG Register listener.js for window 12
15:21:34 INFO - 1501021294943 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9e24e2f6-7487-4e4b-b176-23d4c4f6f56c","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:34 INFO - 1501021294951 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294956 Marionette TRACE 380 <- [1,2,null,{}]
15:21:34 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:34 INFO - 1501021294985 Marionette DEBUG Closed connection 380
15:21:34 INFO - 1501021294987 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49819
15:21:34 INFO - 1501021294989 Marionette DEBUG Closed connection 381
15:21:34 INFO - 1501021294990 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49820
15:21:34 INFO - 1501021294992 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:34 INFO - 1501021294996 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:35 INFO - 1501021295018 Marionette DEBUG Register listener.js for window 12
15:21:35 INFO - 1501021295029 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7bfbc477-c156-4ecd-8b81-260cdb43fa4c","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:35 INFO - 1501021295036 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"}]
15:21:35 INFO - 1501021295040 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"
15:21:35 INFO - 1501021295046 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"
15:21:35 INFO - 1501021295047 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"
15:21:35 INFO - ++DOMWINDOW == 26 (0000005A1CDB5000) [pid = 4064] [serial = 46] [outer = 0000005A1D459000]
15:21:35 INFO - 1501021295105 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"
15:21:35 INFO - 1501021295110 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"
15:21:35 INFO - 1501021295128 Marionette TRACE 382 <- [1,2,null,{}]
15:21:35 INFO - 1501021295131 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:21:35 INFO - 1501021295169 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f4f2238-66dc-41d2-b579-f180f7fc7490","ELEMENT":"6f4f2238-66dc-41d2-b579-f180f7fc7490"}}]
15:21:35 INFO - 1501021295174 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6f4f2238-66dc-41d2-b579-f180f7fc7490"}]
15:21:35 INFO - 1501021295610 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:35 INFO - 1501021295611 Marionette TRACE 382 <- [1,4,null,{}]
15:21:35 INFO - 1501021295614 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}]
15:21:35 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501021295622 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:21:35 INFO - 1501021295624 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295628 Marionette TRACE 382 <- [1,6,null,{}]
15:21:35 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 882ms
15:21:35 INFO - TEST-START | test_click.py TestClick.test_input_file
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295634 Marionette DEBUG Closed connection 382
15:21:35 INFO - 1501021295636 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49821
15:21:35 INFO - 1501021295637 Marionette DEBUG Closed connection 383
15:21:35 INFO - 1501021295639 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49822
15:21:35 INFO - 1501021295641 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:35 INFO - 1501021295643 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:35 INFO - 1501021295665 Marionette DEBUG Register listener.js for window 12
15:21:35 INFO - 1501021295677 Marionette TRACE 384 <- [1,1,null,{"sessionId":"505f6270-43bb-49cc-a4d3-2d14b9641a7c","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:35 INFO - 1501021295683 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:21:35 INFO - 1501021295685 Marionette TRACE 384 <- [1,2,null,{}]
15:21:35 INFO - 1501021295687 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:35 INFO - 1501021295689 Marionette TRACE 384 <- [1,3,null,{}]
15:21:35 INFO - 1501021295691 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:35 INFO - 1501021295693 Marionette TRACE 384 <- [1,4,null,{}]
15:21:35 INFO - 1501021295695 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}]
15:21:35 INFO - TEST-START: test_click.py TestClick.test_input_file1501021295703 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:21:35 INFO - 1501021295705 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295709 Marionette TRACE 384 <- [1,6,null,{}]
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295762 Marionette DEBUG Closed connection 384
15:21:35 INFO - 1501021295763 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49823
15:21:35 INFO - 1501021295767 Marionette DEBUG Closed connection 385
15:21:35 INFO - 1501021295768 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49824
15:21:35 INFO - 1501021295771 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:35 INFO - 1501021295774 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:35 INFO - 1501021295801 Marionette DEBUG Register listener.js for window 12
15:21:35 INFO - 1501021295815 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6e76efc4-3fa8-4b59-b644-5d4b09adc589","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:35 INFO - 1501021295826 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295830 Marionette TRACE 386 <- [1,2,null,{}]
15:21:35 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:35 INFO - 1501021295853 Marionette DEBUG Closed connection 386
15:21:35 INFO - 1501021295855 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49825
15:21:35 INFO - 1501021295859 Marionette DEBUG Closed connection 387
15:21:35 INFO - 1501021295860 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49826
15:21:35 INFO - 1501021295863 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:35 INFO - 1501021295865 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:35 INFO - 1501021295888 Marionette DEBUG Register listener.js for window 12
15:21:35 INFO - 1501021295908 Marionette TRACE 388 <- [1,1,null,{"sessionId":"85504f77-37d7-4b51-8508-1aa704d517f9","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:35 INFO - 1501021295920 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:21:35 INFO - 1501021295924 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"
15:21:35 INFO - 1501021295933 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"
15:21:35 INFO - 1501021295935 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"
15:21:35 INFO - ++DOMWINDOW == 27 (0000005A1CDAF800) [pid = 4064] [serial = 47] [outer = 0000005A1D459000]
15:21:35 INFO - 1501021295986 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:35 INFO - 1501021295995 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:36 INFO - 1501021296011 Marionette TRACE 388 <- [1,2,null,{}]
15:21:36 INFO - 1501021296017 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:21:36 INFO - 1501021296028 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe301c38-7444-413f-a224-fd6b528f687b","ELEMENT":"fe301c38-7444-413f-a224-fd6b528f687b"}}]
15:21:36 INFO - 1501021296033 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fe301c38-7444-413f-a224-fd6b528f687b"}]
15:21:36 INFO - 1501021296188 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:36 INFO - 1501021296203 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}]
15:21:36 INFO - TEST-END: test_click.py TestClick.test_input_file1501021296211 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:21:36 INFO - 1501021296216 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:21:36 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:36 INFO - 1501021296221 Marionette TRACE 388 <- [1,6,null,{}]
15:21:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 592ms
15:21:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:21:36 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:36 INFO - 1501021296244 Marionette DEBUG Closed connection 388
15:21:36 INFO - 1501021296245 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49827
15:21:36 INFO - 1501021296873 Marionette DEBUG Closed connection 389
15:21:36 INFO - 1501021296874 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49828
15:21:36 INFO - 1501021296892 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:36 INFO - 1501021296894 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:36 INFO - 1501021296918 Marionette DEBUG Register listener.js for window 12
15:21:36 INFO - 1501021296929 Marionette TRACE 390 <- [1,1,null,{"sessionId":"dc11e744-9e2c-4710-96e8-8b94d0e8fcee","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:36 INFO - 1501021296975 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:21:36 INFO - 1501021296977 Marionette TRACE 390 <- [1,2,null,{}]
15:21:37 INFO - 1501021297059 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:37 INFO - 1501021297065 Marionette TRACE 390 <- [1,3,null,{}]
15:21:37 INFO - 1501021297069 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:37 INFO - 1501021297074 Marionette TRACE 390 <- [1,4,null,{}]
15:21:37 INFO - 1501021297130 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}]
15:21:37 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501021297146 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:21:37 INFO - 1501021297152 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:21:37 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:37 INFO - 1501021297161 Marionette TRACE 390 <- [1,6,null,{}]
15:21:37 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:37 INFO - 1501021297177 Marionette DEBUG Closed connection 390
15:21:37 INFO - 1501021297180 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49829
15:21:37 INFO - 1501021297186 Marionette DEBUG Closed connection 391
15:21:37 INFO - 1501021297190 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49830
15:21:37 INFO - 1501021297194 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:37 INFO - 1501021297203 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:37 INFO - 1501021297246 Marionette DEBUG Register listener.js for window 12
15:21:37 INFO - 1501021297267 Marionette TRACE 392 <- [1,1,null,{"sessionId":"03c7377f-6562-4072-a7e7-5cfc082f580e","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:37 INFO - 1501021297313 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:21:37 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:37 INFO - 1501021297320 Marionette TRACE 392 <- [1,2,null,{}]
15:21:37 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:37 INFO - 1501021297346 Marionette DEBUG Closed connection 392
15:21:37 INFO - 1501021297349 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49831
15:21:37 INFO - 1501021297356 Marionette DEBUG Closed connection 393
15:21:37 INFO - 1501021297359 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49832
15:21:37 INFO - 1501021297412 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:37 INFO - 1501021297417 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:37 INFO - 1501021297459 Marionette DEBUG Register listener.js for window 12
15:21:37 INFO - 1501021297477 Marionette TRACE 394 <- [1,1,null,{"sessionId":"dc764b6c-eb21-45eb-8523-8e7452ca3fa7","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:37 INFO - 1501021297488 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"}]
15:21:37 INFO - 1501021297494 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:37 INFO - 1501021297504 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:37 INFO - 1501021297505 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:37 INFO - ++DOMWINDOW == 28 (0000005A11F3A800) [pid = 4064] [serial = 48] [outer = 0000005A1D459000]
15:21:37 INFO - 1501021297577 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"
15:21:37 INFO - 1501021297584 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"
15:21:37 INFO - 1501021297616 Marionette TRACE 394 <- [1,2,null,{}]
15:21:37 INFO - 1501021297628 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:21:37 INFO - 1501021297639 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bc889f5c-9c3e-45a2-9c4e-e1d20f964972","ELEMENT":"bc889f5c-9c3e-45a2-9c4e-e1d20f964972"}}]
15:21:37 INFO - 1501021297642 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:21:37 INFO - 1501021297652 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80714a1e-78b3-4c16-9b6e-2c278e390941","ELEMENT":"80714a1e-78b3-4c16-9b6e-2c278e390941"}}]
15:21:37 INFO - 1501021297660 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"bc889f5c-9c3e-45a2-9c4e-e1d20f964972"}]
15:21:38 INFO - 1501021298255 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:38 INFO - 1501021298262 Marionette TRACE 394 <- [1,5,null,{}]
15:21:38 INFO - 1501021298266 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"80714a1e-78b3-4c16-9b6e-2c278e390941"}]
15:21:38 INFO - 1501021298541 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:38 INFO - 1501021298573 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:21:38 INFO - 1501021298586 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:21:38 INFO - 1501021298588 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}]
15:21:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501021298599 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:21:38 INFO - 1501021298618 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:21:38 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:38 INFO - 1501021298625 Marionette TRACE 394 <- [1,9,null,{}]
15:21:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2403ms
15:21:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:21:38 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:38 INFO - 1501021298652 Marionette DEBUG Closed connection 394
15:21:38 INFO - 1501021298654 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49833
15:21:38 INFO - 1501021298659 Marionette DEBUG Closed connection 395
15:21:38 INFO - 1501021298661 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49834
15:21:38 INFO - 1501021298693 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:38 INFO - 1501021298696 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:38 INFO - 1501021298739 Marionette DEBUG Register listener.js for window 12
15:21:38 INFO - 1501021298756 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ffaed00c-afed-449e-858d-ea776bd19ff6","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:38 INFO - 1501021298768 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:21:38 INFO - 1501021298771 Marionette TRACE 396 <- [1,2,null,{}]
15:21:38 INFO - 1501021298935 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:38 INFO - 1501021298940 Marionette TRACE 396 <- [1,3,null,{}]
15:21:38 INFO - 1501021298945 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:38 INFO - 1501021298949 Marionette TRACE 396 <- [1,4,null,{}]
15:21:39 INFO - 1501021299004 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}]
15:21:39 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501021299016 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:21:39 INFO - 1501021299023 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:21:39 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:39 INFO - 1501021299031 Marionette TRACE 396 <- [1,6,null,{}]
15:21:39 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:39 INFO - 1501021299069 Marionette DEBUG Closed connection 396
15:21:39 INFO - 1501021299073 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49835
15:21:39 INFO - 1501021299099 Marionette DEBUG Closed connection 397
15:21:39 INFO - 1501021299103 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49836
15:21:39 INFO - 1501021299108 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:39 INFO - 1501021299117 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:39 INFO - 1501021299142 Marionette DEBUG Register listener.js for window 12
15:21:39 INFO - 1501021299162 Marionette TRACE 398 <- [1,1,null,{"sessionId":"838e9338-7ff7-4df1-9dc5-101b9128c78d","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:39 INFO - 1501021299176 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:21:39 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:39 INFO - 1501021299184 Marionette TRACE 398 <- [1,2,null,{}]
15:21:39 INFO - [Parent 4064] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:39 INFO - 1501021299214 Marionette DEBUG Closed connection 398
15:21:39 INFO - 1501021299217 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49837
15:21:39 INFO - 1501021299222 Marionette DEBUG Closed connection 399
15:21:39 INFO - 1501021299224 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49838
15:21:39 INFO - 1501021299232 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:39 INFO - 1501021299237 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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}
15:21:39 INFO - 1501021299261 Marionette DEBUG Register listener.js for window 12
15:21:39 INFO - 1501021299278 Marionette TRACE 400 <- [1,1,null,{"sessionId":"89faedb0-16b1-45fe-a437-c067b4851c47","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":4064,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp08xa1m.mozrunner","moz:accessibilityChecks":false}}]
15:21:39 INFO - 1501021299288 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"}]
15:21:39 INFO - 1501021299294 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"
15:21:39 INFO - 1501021299303 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"
15:21:39 INFO - 1501021299304 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"
15:21:39 INFO - ++DOMWINDOW == 29 (0000005A17034800) [pid = 4064] [serial = 49] [outer = 0000005A1D459000]
15:21:39 INFO - 1501021299367 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"
15:21:39 INFO - 1501021299373 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"
15:21:39 INFO - 1501021299393 Marionette TRACE 400 <- [1,2,null,{}]
15:21:39 INFO - --DOMWINDOW == 28 (0000005A252D7800) [pid = 4064] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:39 INFO - --DOMWINDOW == 27 (0000005A1702E800) [pid = 4064] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:39 INFO - --DOMWINDOW == 26 (0000005A1AF8E800) [pid = 4064] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:21:39 INFO - --DOMWINDOW == 25 (0000005A18F08800) [pid = 4064] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
15:21:39 INFO - --DOMWINDOW == 24 (0000005A25C3F800) [pid = 4064] [serial = 39] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test.html]
15:21:39 INFO - --DOMWINDOW == 23 (0000005A25C25800) [pid = 4064] [serial = 38] [outer = 0000000000000000] [url = http://127.0.0.1:49333/clicks.html]
15:21:39 INFO - --DOMWINDOW == 22 (0000005A2654B800) [pid = 4064] [serial = 37] [outer = 0000000000000000] [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]
15:21:39 INFO - --DOMWINDOW == 21 (0000005A28149800) [pid = 4064] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%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.clicks%20%3D%200%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.clicks%2B%2B%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]
15:21:39 INFO - --DOMWINDOW == 20 (0000005A25903800) [pid = 4064] [serial = 35] [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%20vertically%20%2A/%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]
15:21:39 INFO - --DOMWINDOW == 19 (0000005A2471D800) [pid = 4064] [serial = 34] [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]
15:21:39 INFO - 1501021299410 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:21:39 INFO - 1501021299419 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b25c181-6823-4b40-8a8d-6ad72e04289d","ELEMENT":"5b25c181-6823-4b40-8a8d-6ad72e04289d"}}]
15:21:39 INFO - 1501021299421 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5b25c181-6823-4b40-8a8d-6ad72e04289d"}]
15:21:39 INFO - 1501021299552 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:21:39 INFO - 1501021299559 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5b25c181-6823-4b40-8a8d-6ad72e04289d"}]
15:21:39 INFO - 1501021299703 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element