could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:32 INFO - 1500408572187 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}]
13:09:32 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500408572197 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:09:32 INFO - 1500408572199 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572203 Marionette TRACE 352 <- [1,6,null,{}]
13:09:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 791ms
13:09:32 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572215 Marionette DEBUG Closed connection 352
13:09:32 INFO - 1500408572217 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49785
13:09:32 INFO - 1500408572233 Marionette DEBUG Closed connection 353
13:09:32 INFO - 1500408572235 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49786
13:09:32 INFO - 1500408572237 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:32 INFO - 1500408572239 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:32 INFO - 1500408572260 Marionette DEBUG Register listener.js for window 15
13:09:32 INFO - 1500408572271 Marionette TRACE 354 <- [1,1,null,{"sessionId":"d5641c35-7fd8-49be-992a-0e68267f842c","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:32 INFO - 1500408572277 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:09:32 INFO - 1500408572278 Marionette TRACE 354 <- [1,2,null,{}]
13:09:32 INFO - 1500408572280 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:32 INFO - 1500408572281 Marionette TRACE 354 <- [1,3,null,{}]
13:09:32 INFO - 1500408572283 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:32 INFO - 1500408572284 Marionette TRACE 354 <- [1,4,null,{}]
13:09:32 INFO - 1500408572287 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}]
13:09:32 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500408572294 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:09:32 INFO - 1500408572322 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572331 Marionette TRACE 354 <- [1,6,null,{}]
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572357 Marionette DEBUG Closed connection 354
13:09:32 INFO - 1500408572360 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49787
13:09:32 INFO - 1500408572413 Marionette DEBUG Closed connection 355
13:09:32 INFO - 1500408572417 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49788
13:09:32 INFO - 1500408572424 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:32 INFO - 1500408572435 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:32 INFO - 1500408572477 Marionette DEBUG Register listener.js for window 15
13:09:32 INFO - 1500408572500 Marionette TRACE 356 <- [1,1,null,{"sessionId":"0c5c9039-6148-4c7e-85d6-ad597cf62463","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:32 INFO - 1500408572517 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572525 Marionette TRACE 356 <- [1,2,null,{}]
13:09:32 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1500408572536 Marionette DEBUG Closed connection 356
13:09:32 INFO - 1500408572540 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49789
13:09:32 INFO - 1500408572543 Marionette DEBUG Closed connection 357
13:09:32 INFO - 1500408572545 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49790
13:09:32 INFO - 1500408572549 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:32 INFO - 1500408572554 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:32 INFO - 1500408572596 Marionette DEBUG Register listener.js for window 15
13:09:32 INFO - 1500408572625 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b9f4509e-77a4-4ce8-a0ef-a2472c89b629","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:32 INFO - 1500408572660 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
13:09:32 INFO - 1500408572667 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
13:09:32 INFO - 1500408572682 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
13:09:32 INFO - 1500408572683 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
13:09:32 INFO - ++DOMWINDOW == 28 (000000820F22E800) [pid = 320] [serial = 40] [outer = 0000008219FA7000]
13:09:32 INFO - 1500408572771 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
13:09:32 INFO - 1500408572779 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
13:09:32 INFO - 1500408572811 Marionette TRACE 358 <- [1,2,null,{}]
13:09:32 INFO - 1500408572824 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:09:32 INFO - 1500408572838 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cfceb33f-4d6c-4b92-b60a-42ce534b6807","ELEMENT":"cfceb33f-4d6c-4b92-b60a-42ce534b6807"}}]
13:09:32 INFO - 1500408572861 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"cfceb33f-4d6c-4b92-b60a-42ce534b6807"}]
13:09:33 INFO - 1500408573270 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
13:09:33 INFO - [Parent 320] 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
13:09:33 INFO - 1500408573283 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
13:09:33 INFO - 1500408573284 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
13:09:33 INFO - ++DOMWINDOW == 29 (0000008217E5E800) [pid = 320] [serial = 41] [outer = 0000008219FA7000]
13:09:33 INFO - 1500408573362 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
13:09:33 INFO - 1500408573368 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
13:09:33 INFO - 1500408573388 Marionette TRACE 358 <- [1,4,null,{}]
13:09:33 INFO - 1500408573405 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:09:33 INFO - 1500408573416 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5c4d610-c76f-4b1c-8bc2-f1685086db02","ELEMENT":"c5c4d610-c76f-4b1c-8bc2-f1685086db02"}}]
13:09:33 INFO - 1500408573419 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:09:33 INFO - 1500408573421 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:09:33 INFO - 1500408573425 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}]
13:09:33 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500408573437 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:09:33 INFO - 1500408573444 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573455 Marionette TRACE 358 <- [1,8,null,{}]
13:09:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1247ms
13:09:33 INFO - TEST-START | test_click.py TestClick.test_container_element
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573463 Marionette DEBUG Closed connection 358
13:09:33 INFO - 1500408573465 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49793
13:09:33 INFO - 1500408573467 Marionette DEBUG Closed connection 359
13:09:33 INFO - 1500408573470 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49794
13:09:33 INFO - 1500408573473 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:33 INFO - 1500408573476 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573519 Marionette DEBUG Register listener.js for window 15
13:09:33 INFO - 1500408573550 Marionette TRACE 360 <- [1,1,null,{"sessionId":"90fcf074-c7c9-4f4c-9f8b-97b40223285f","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1500408573613 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:09:33 INFO - 1500408573615 Marionette TRACE 360 <- [1,2,null,{}]
13:09:33 INFO - 1500408573617 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:33 INFO - 1500408573620 Marionette TRACE 360 <- [1,3,null,{}]
13:09:33 INFO - 1500408573623 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:33 INFO - 1500408573625 Marionette TRACE 360 <- [1,4,null,{}]
13:09:33 INFO - 1500408573628 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}]
13:09:33 INFO - TEST-START: test_click.py TestClick.test_container_element1500408573638 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:09:33 INFO - 1500408573641 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573646 Marionette TRACE 360 <- [1,6,null,{}]
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573682 Marionette DEBUG Closed connection 360
13:09:33 INFO - 1500408573684 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49795
13:09:33 INFO - 1500408573688 Marionette DEBUG Closed connection 361
13:09:33 INFO - 1500408573691 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49796
13:09:33 INFO - 1500408573743 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:33 INFO - 1500408573746 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573790 Marionette DEBUG Register listener.js for window 15
13:09:33 INFO - 1500408573806 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b79129cb-52dc-411b-92da-9e3a3471b3ab","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1500408573818 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573823 Marionette TRACE 362 <- [1,2,null,{}]
13:09:33 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573880 Marionette DEBUG Closed connection 362
13:09:33 INFO - 1500408573882 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49797
13:09:33 INFO - 1500408573902 Marionette DEBUG Closed connection 363
13:09:33 INFO - 1500408573904 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49798
13:09:33 INFO - 1500408573910 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:33 INFO - 1500408573917 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573959 Marionette DEBUG Register listener.js for window 15
13:09:34 INFO - 1500408574000 Marionette TRACE 364 <- [1,1,null,{"sessionId":"de5e5aa8-fa32-4a3a-ab22-e4b22234b51a","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1500408574018 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"}]
13:09:34 INFO - 1500408574024 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
13:09:34 INFO - 1500408574031 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
13:09:34 INFO - 1500408574032 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
13:09:34 INFO - ++DOMWINDOW == 30 (00000082211D4000) [pid = 320] [serial = 42] [outer = 0000008219FA7000]
13:09:34 INFO - 1500408574098 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"
13:09:34 INFO - 1500408574118 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"
13:09:34 INFO - 1500408574135 Marionette TRACE 364 <- [1,2,null,{}]
13:09:34 INFO - 1500408574146 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:34 INFO - 1500408574157 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cea94229-e1db-4133-8ddf-35a475b6f3bb","ELEMENT":"cea94229-e1db-4133-8ddf-35a475b6f3bb"}}]
13:09:34 INFO - 1500408574159 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cea94229-e1db-4133-8ddf-35a475b6f3bb"}]
13:09:34 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:34 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:34 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:34 INFO - 1500408574763 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:34 INFO - 1500408574765 Marionette TRACE 364 <- [1,4,null,{}]
13:09:34 INFO - 1500408574768 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cea94229-e1db-4133-8ddf-35a475b6f3bb","name":"selected"}]
13:09:34 INFO - 1500408574827 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:09:34 INFO - 1500408574834 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}]
13:09:34 INFO - TEST-END: test_click.py TestClick.test_container_element1500408574848 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:09:34 INFO - 1500408574859 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:09:34 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574865 Marionette TRACE 364 <- [1,7,null,{}]
13:09:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1410ms
13:09:34 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:09:34 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574874 Marionette DEBUG Closed connection 364
13:09:34 INFO - 1500408574880 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49799
13:09:34 INFO - 1500408574884 Marionette DEBUG Closed connection 365
13:09:34 INFO - 1500408574886 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49800
13:09:34 INFO - 1500408574889 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1500408574891 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1500408574935 Marionette DEBUG Register listener.js for window 15
13:09:34 INFO - 1500408574951 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c6bdce44-d120-40b5-84c4-e0202b16a728","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1500408574958 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:09:34 INFO - 1500408574960 Marionette TRACE 366 <- [1,2,null,{}]
13:09:35 INFO - 1500408575146 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:35 INFO - 1500408575153 Marionette TRACE 366 <- [1,3,null,{}]
13:09:35 INFO - 1500408575180 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:35 INFO - 1500408575185 Marionette TRACE 366 <- [1,4,null,{}]
13:09:35 INFO - 1500408575239 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}]
13:09:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500408575252 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:09:35 INFO - 1500408575261 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575270 Marionette TRACE 366 <- [1,6,null,{}]
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575331 Marionette DEBUG Closed connection 366
13:09:35 INFO - 1500408575334 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49801
13:09:35 INFO - 1500408575340 Marionette DEBUG Closed connection 367
13:09:35 INFO - 1500408575345 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49802
13:09:35 INFO - 1500408575349 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:35 INFO - 1500408575358 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575383 Marionette DEBUG Register listener.js for window 15
13:09:35 INFO - 1500408575404 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ee00070e-1c47-4cf1-a19c-9e4bcf543bb7","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575439 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575446 Marionette TRACE 368 <- [1,2,null,{}]
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575476 Marionette DEBUG Closed connection 368
13:09:35 INFO - 1500408575479 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49803
13:09:35 INFO - 1500408575484 Marionette DEBUG Closed connection 369
13:09:35 INFO - 1500408575486 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49804
13:09:35 INFO - 1500408575490 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:35 INFO - 1500408575495 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575518 Marionette DEBUG Register listener.js for window 15
13:09:35 INFO - 1500408575536 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ea38460e-6fa5-40fd-b407-f91a3e51a022","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575551 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"}]
13:09:35 INFO - 1500408575557 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"
13:09:35 INFO - 1500408575565 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"
13:09:35 INFO - 1500408575566 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"
13:09:35 INFO - ++DOMWINDOW == 31 (000000820DCC5000) [pid = 320] [serial = 43] [outer = 0000008219FA7000]
13:09:35 INFO - 1500408575626 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"
13:09:35 INFO - 1500408575633 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"
13:09:35 INFO - 1500408575655 Marionette TRACE 370 <- [1,2,null,{}]
13:09:35 INFO - 1500408575665 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:35 INFO - 1500408575674 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac0bfdb9-c9da-41a6-b80a-17b9bdb9e7fe","ELEMENT":"ac0bfdb9-c9da-41a6-b80a-17b9bdb9e7fe"}}]
13:09:35 INFO - 1500408575677 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ac0bfdb9-c9da-41a6-b80a-17b9bdb9e7fe"}]
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:35 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:35 INFO - --DOMWINDOW == 30 (0000008211CEB000) [pid = 320] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 29 (000000821DE0D800) [pid = 320] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 28 (0000008213896000) [pid = 320] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 27 (0000008219307000) [pid = 320] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 26 (000000821FFBF800) [pid = 320] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 25 (0000008219FAD000) [pid = 320] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:35 INFO - --DOMWINDOW == 24 (0000008219542800) [pid = 320] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:09:36 INFO - 1500408576814 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:36 INFO - 1500408576835 Marionette TRACE 370 <- [1,4,null,{}]
13:09:36 INFO - 1500408576884 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ac0bfdb9-c9da-41a6-b80a-17b9bdb9e7fe","name":"selected"}]
13:09:36 INFO - 1500408576918 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:09:36 INFO - 1500408576966 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}]
13:09:36 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500408576975 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:09:36 INFO - 1500408576978 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:09:36 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1500408576984 Marionette TRACE 370 <- [1,7,null,{}]
13:09:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 2118ms
13:09:36 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:09:37 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577039 Marionette DEBUG Closed connection 370
13:09:37 INFO - 1500408577041 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49805
13:09:37 INFO - 1500408577094 Marionette DEBUG Closed connection 371
13:09:37 INFO - 1500408577096 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49806
13:09:37 INFO - 1500408577149 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1500408577152 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577174 Marionette DEBUG Register listener.js for window 15
13:09:37 INFO - 1500408577189 Marionette TRACE 372 <- [1,1,null,{"sessionId":"1d0ecb16-25b3-4aa2-8862-908e58d821ef","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1500408577242 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:09:37 INFO - 1500408577244 Marionette TRACE 372 <- [1,2,null,{}]
13:09:37 INFO - 1500408577265 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:37 INFO - 1500408577266 Marionette TRACE 372 <- [1,3,null,{}]
13:09:37 INFO - 1500408577268 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:37 INFO - 1500408577270 Marionette TRACE 372 <- [1,4,null,{}]
13:09:37 INFO - 1500408577274 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}]
13:09:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500408577283 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:09:37 INFO - 1500408577287 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:09:37 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577292 Marionette TRACE 372 <- [1,6,null,{}]
13:09:37 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577314 Marionette DEBUG Closed connection 372
13:09:37 INFO - 1500408577316 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49807
13:09:37 INFO - 1500408577320 Marionette DEBUG Closed connection 373
13:09:37 INFO - 1500408577322 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49808
13:09:37 INFO - 1500408577325 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1500408577327 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577351 Marionette DEBUG Register listener.js for window 15
13:09:37 INFO - 1500408577365 Marionette TRACE 374 <- [1,1,null,{"sessionId":"1c2f284b-c853-437a-80e8-b5a45ada2b46","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1500408577375 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:09:37 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577380 Marionette TRACE 374 <- [1,2,null,{}]
13:09:37 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577402 Marionette DEBUG Closed connection 374
13:09:37 INFO - 1500408577404 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49809
13:09:37 INFO - 1500408577408 Marionette DEBUG Closed connection 375
13:09:37 INFO - 1500408577410 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49810
13:09:37 INFO - 1500408577413 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:37 INFO - 1500408577418 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577441 Marionette DEBUG Register listener.js for window 15
13:09:37 INFO - 1500408577457 Marionette TRACE 376 <- [1,1,null,{"sessionId":"79cf89a5-21b2-41f6-9029-1ae690d221eb","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1500408577467 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"}]
13:09:37 INFO - 1500408577471 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"
13:09:37 INFO - 1500408577478 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"
13:09:37 INFO - 1500408577479 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"
13:09:37 INFO - ++DOMWINDOW == 25 (0000008214148000) [pid = 320] [serial = 44] [outer = 0000008219FA7000]
13:09:37 INFO - 1500408577542 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"
13:09:37 INFO - 1500408577549 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"
13:09:37 INFO - 1500408577569 Marionette TRACE 376 <- [1,2,null,{}]
13:09:37 INFO - 1500408577577 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:09:37 INFO - 1500408577585 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1ddafe1b-7435-4b5d-9c21-db615161d19f","ELEMENT":"1ddafe1b-7435-4b5d-9c21-db615161d19f"}}]
13:09:37 INFO - 1500408577587 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1ddafe1b-7435-4b5d-9c21-db615161d19f"}]
13:09:38 INFO - 1500408578058 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:38 INFO - 1500408578060 Marionette TRACE 376 <- [1,4,null,{}]
13:09:38 INFO - 1500408578063 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}]
13:09:38 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500408578075 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1500408578077 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578082 Marionette TRACE 376 <- [1,6,null,{}]
13:09:38 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1098ms
13:09:38 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578090 Marionette DEBUG Closed connection 376
13:09:38 INFO - 1500408578095 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49811
13:09:38 INFO - 1500408578097 Marionette DEBUG Closed connection 377
13:09:38 INFO - 1500408578099 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49812
13:09:38 INFO - 1500408578101 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1500408578103 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1500408578126 Marionette DEBUG Register listener.js for window 15
13:09:38 INFO - 1500408578139 Marionette TRACE 378 <- [1,1,null,{"sessionId":"21399812-a4ca-4fce-8e68-04d50d811d41","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578173 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:09:38 INFO - 1500408578175 Marionette TRACE 378 <- [1,2,null,{}]
13:09:38 INFO - 1500408578177 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:38 INFO - 1500408578178 Marionette TRACE 378 <- [1,3,null,{}]
13:09:38 INFO - 1500408578180 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:38 INFO - 1500408578182 Marionette TRACE 378 <- [1,4,null,{}]
13:09:38 INFO - 1500408578185 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}]
13:09:38 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500408578193 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1500408578195 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578199 Marionette TRACE 378 <- [1,6,null,{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578205 Marionette DEBUG Closed connection 378
13:09:38 INFO - 1500408578207 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49813
13:09:38 INFO - 1500408578209 Marionette DEBUG Closed connection 379
13:09:38 INFO - 1500408578210 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49814
13:09:38 INFO - 1500408578212 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1500408578215 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1500408578237 Marionette DEBUG Register listener.js for window 15
13:09:38 INFO - 1500408578249 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8b258534-599f-4418-8f7e-ccc7c339f990","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578279 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578283 Marionette TRACE 380 <- [1,2,null,{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578305 Marionette DEBUG Closed connection 380
13:09:38 INFO - 1500408578306 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49815
13:09:38 INFO - 1500408578311 Marionette DEBUG Closed connection 381
13:09:38 INFO - 1500408578313 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49816
13:09:38 INFO - 1500408578316 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:38 INFO - 1500408578319 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1500408578342 Marionette DEBUG Register listener.js for window 15
13:09:38 INFO - 1500408578353 Marionette TRACE 382 <- [1,1,null,{"sessionId":"54d6bf2b-38d9-45b5-9ad5-422e7c5fa7d9","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578364 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"}]
13:09:38 INFO - 1500408578368 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"
13:09:38 INFO - 1500408578374 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"
13:09:38 INFO - 1500408578375 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"
13:09:38 INFO - ++DOMWINDOW == 26 (0000008217B3B800) [pid = 320] [serial = 45] [outer = 0000008219FA7000]
13:09:38 INFO - 1500408578430 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"
13:09:38 INFO - 1500408578436 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"
13:09:38 INFO - 1500408578454 Marionette TRACE 382 <- [1,2,null,{}]
13:09:38 INFO - 1500408578460 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:09:38 INFO - 1500408578470 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50e489d1-3bd8-4977-8cf6-5965fc206ae8","ELEMENT":"50e489d1-3bd8-4977-8cf6-5965fc206ae8"}}]
13:09:38 INFO - 1500408578472 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"50e489d1-3bd8-4977-8cf6-5965fc206ae8"}]
13:09:38 INFO - 1500408578957 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:38 INFO - 1500408578959 Marionette TRACE 382 <- [1,4,null,{}]
13:09:38 INFO - 1500408578962 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}]
13:09:38 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500408578972 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1500408578974 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578978 Marionette TRACE 382 <- [1,6,null,{}]
13:09:38 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 895ms
13:09:38 INFO - TEST-START | test_click.py TestClick.test_input_file
13:09:38 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578984 Marionette DEBUG Closed connection 382
13:09:38 INFO - 1500408578985 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49817
13:09:38 INFO - 1500408578987 Marionette DEBUG Closed connection 383
13:09:38 INFO - 1500408578989 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49818
13:09:38 INFO - 1500408578991 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1500408578993 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579015 Marionette DEBUG Register listener.js for window 15
13:09:39 INFO - 1500408579027 Marionette TRACE 384 <- [1,1,null,{"sessionId":"68b3e19f-38db-4727-89ef-94b9e9f24c7f","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579033 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:09:39 INFO - 1500408579035 Marionette TRACE 384 <- [1,2,null,{}]
13:09:39 INFO - 1500408579065 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:39 INFO - 1500408579066 Marionette TRACE 384 <- [1,3,null,{}]
13:09:39 INFO - 1500408579068 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:39 INFO - 1500408579070 Marionette TRACE 384 <- [1,4,null,{}]
13:09:39 INFO - 1500408579073 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}]
13:09:39 INFO - TEST-START: test_click.py TestClick.test_input_file1500408579081 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:09:39 INFO - 1500408579083 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579087 Marionette TRACE 384 <- [1,6,null,{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579093 Marionette DEBUG Closed connection 384
13:09:39 INFO - 1500408579095 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49819
13:09:39 INFO - 1500408579097 Marionette DEBUG Closed connection 385
13:09:39 INFO - 1500408579098 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49820
13:09:39 INFO - 1500408579100 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:39 INFO - 1500408579103 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579125 Marionette DEBUG Register listener.js for window 15
13:09:39 INFO - 1500408579137 Marionette TRACE 386 <- [1,1,null,{"sessionId":"043d4b70-cbfd-4e19-b7f1-0736691a5063","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579144 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579148 Marionette TRACE 386 <- [1,2,null,{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579154 Marionette DEBUG Closed connection 386
13:09:39 INFO - 1500408579156 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49821
13:09:39 INFO - 1500408579157 Marionette DEBUG Closed connection 387
13:09:39 INFO - 1500408579159 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49822
13:09:39 INFO - 1500408579161 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:39 INFO - 1500408579165 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579192 Marionette DEBUG Register listener.js for window 15
13:09:39 INFO - 1500408579206 Marionette TRACE 388 <- [1,1,null,{"sessionId":"63c2089f-ffaf-458f-9660-f3effcf30666","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579212 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:09:39 INFO - 1500408579217 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"
13:09:39 INFO - 1500408579222 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"
13:09:39 INFO - 1500408579224 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"
13:09:39 INFO - ++DOMWINDOW == 27 (0000008219730800) [pid = 320] [serial = 46] [outer = 0000008219FA7000]
13:09:39 INFO - 1500408579281 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579287 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579305 Marionette TRACE 388 <- [1,2,null,{}]
13:09:39 INFO - 1500408579308 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:09:39 INFO - 1500408579317 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e0ddfc9b-1c35-4be8-8295-7527a20c6348","ELEMENT":"e0ddfc9b-1c35-4be8-8295-7527a20c6348"}}]
13:09:39 INFO - 1500408579320 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"e0ddfc9b-1c35-4be8-8295-7527a20c6348"}]
13:09:39 INFO - 1500408579547 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:39 INFO - 1500408579550 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}]
13:09:39 INFO - TEST-END: test_click.py TestClick.test_input_file1500408579558 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:09:39 INFO - 1500408579560 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579564 Marionette TRACE 388 <- [1,6,null,{}]
13:09:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 585ms
13:09:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579570 Marionette DEBUG Closed connection 388
13:09:39 INFO - 1500408579572 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49823
13:09:39 INFO - 1500408579573 Marionette DEBUG Closed connection 389
13:09:39 INFO - 1500408579574 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49824
13:09:39 INFO - 1500408579597 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:39 INFO - 1500408579600 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579623 Marionette DEBUG Register listener.js for window 15
13:09:39 INFO - 1500408579634 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1c25b9f9-d7ca-458a-bc9f-0b81d12f3435","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579644 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:09:39 INFO - 1500408579646 Marionette TRACE 390 <- [1,2,null,{}]
13:09:39 INFO - 1500408579649 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:39 INFO - 1500408579651 Marionette TRACE 390 <- [1,3,null,{}]
13:09:39 INFO - 1500408579653 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:39 INFO - 1500408579654 Marionette TRACE 390 <- [1,4,null,{}]
13:09:39 INFO - 1500408579658 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}]
13:09:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500408579666 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:09:39 INFO - 1500408579669 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:09:39 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579673 Marionette TRACE 390 <- [1,6,null,{}]
13:09:40 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580303 Marionette DEBUG Closed connection 390
13:09:40 INFO - 1500408580304 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49825
13:09:40 INFO - 1500408580319 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49826
13:09:40 INFO - 1500408580321 Marionette DEBUG Closed connection 391
13:09:40 INFO - 1500408580372 Marionette DEBUG Closed connection 392
13:09:40 INFO - 1500408580373 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49827
13:09:40 INFO - 1500408580421 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:40 INFO - 1500408580423 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:40 INFO - 1500408580446 Marionette DEBUG Register listener.js for window 15
13:09:40 INFO - 1500408580459 Marionette TRACE 393 <- [1,1,null,{"sessionId":"11c0a3e9-1788-4b1f-a203-0ce43fdf5f1f","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:40 INFO - 1500408580492 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:09:40 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580502 Marionette TRACE 393 <- [1,2,null,{}]
13:09:40 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580512 Marionette DEBUG Closed connection 393
13:09:40 INFO - 1500408580515 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49828
13:09:40 INFO - 1500408580537 Marionette DEBUG Closed connection 394
13:09:40 INFO - 1500408580540 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49829
13:09:40 INFO - 1500408580546 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:40 INFO - 1500408580556 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:40 INFO - 1500408580604 Marionette DEBUG Register listener.js for window 15
13:09:40 INFO - 1500408580637 Marionette TRACE 395 <- [1,1,null,{"sessionId":"ee8ff35f-08e8-4006-a8da-e90f5c292723","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:40 INFO - 1500408580657 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
13:09:40 INFO - 1500408580665 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:40 INFO - 1500408580674 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:40 INFO - 1500408580675 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:40 INFO - ++DOMWINDOW == 28 (000000820DCD0800) [pid = 320] [serial = 47] [outer = 0000008219FA7000]
13:09:40 INFO - 1500408580747 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"
13:09:40 INFO - 1500408580758 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"
13:09:40 INFO - 1500408580793 Marionette TRACE 395 <- [1,2,null,{}]
13:09:40 INFO - 1500408580799 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:09:40 INFO - 1500408580813 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d07c460f-f576-4896-902f-b14343684054","ELEMENT":"d07c460f-f576-4896-902f-b14343684054"}}]
13:09:40 INFO - 1500408580816 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:09:40 INFO - 1500408580828 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8988b32-da7b-415d-84e1-a6e35e220213","ELEMENT":"f8988b32-da7b-415d-84e1-a6e35e220213"}}]
13:09:40 INFO - 1500408580838 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"d07c460f-f576-4896-902f-b14343684054"}]
13:09:41 INFO - 1500408581474 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:41 INFO - 1500408581477 Marionette TRACE 395 <- [1,5,null,{}]
13:09:41 INFO - 1500408581484 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"f8988b32-da7b-415d-84e1-a6e35e220213"}]
13:09:41 INFO - 1500408581810 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:41 INFO - 1500408581818 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
13:09:41 INFO - 1500408581830 Marionette TRACE 395 <- [1,7,null,{"value":false}]
13:09:41 INFO - 1500408581834 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
13:09:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500408581844 Marionette TRACE 395 <- [1,8,null,{"value":null}]
13:09:41 INFO - 1500408581848 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
13:09:41 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:41 INFO - 1500408581855 Marionette TRACE 395 <- [1,9,null,{}]
13:09:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2291ms
13:09:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:09:41 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:41 INFO - 1500408581908 Marionette DEBUG Closed connection 395
13:09:41 INFO - 1500408581911 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49830
13:09:41 INFO - 1500408581927 Marionette DEBUG Closed connection 396
13:09:41 INFO - 1500408581930 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49831
13:09:41 INFO - 1500408581939 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:41 INFO - 1500408581944 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:41 INFO - 1500408581991 Marionette DEBUG Register listener.js for window 15
13:09:42 INFO - 1500408582010 Marionette TRACE 397 <- [1,1,null,{"sessionId":"374dd7fe-4edf-48a2-85a1-a1f80f165931","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:42 INFO - 1500408582069 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:09:42 INFO - 1500408582072 Marionette TRACE 397 <- [1,2,null,{}]
13:09:42 INFO - 1500408582077 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:42 INFO - 1500408582080 Marionette TRACE 397 <- [1,3,null,{}]
13:09:42 INFO - 1500408582082 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:42 INFO - 1500408582084 Marionette TRACE 397 <- [1,4,null,{}]
13:09:42 INFO - 1500408582136 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
13:09:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500408582146 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:09:42 INFO - 1500408582151 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:09:42 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:42 INFO - 1500408582157 Marionette TRACE 397 <- [1,6,null,{}]
13:09:42 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:42 INFO - 1500408582181 Marionette DEBUG Closed connection 397
13:09:42 INFO - 1500408582183 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49832
13:09:42 INFO - 1500408582236 Marionette DEBUG Closed connection 398
13:09:42 INFO - 1500408582238 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49833
13:09:42 INFO - 1500408582243 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:42 INFO - 1500408582247 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:42 INFO - 1500408582294 Marionette DEBUG Register listener.js for window 15
13:09:42 INFO - 1500408582311 Marionette TRACE 399 <- [1,1,null,{"sessionId":"ba93209f-5821-40cf-a8b2-045ae73fbb98","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:42 INFO - 1500408582323 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:09:42 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:42 INFO - 1500408582328 Marionette TRACE 399 <- [1,2,null,{}]
13:09:42 INFO - [Parent 320] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:42 INFO - 1500408582349 Marionette DEBUG Closed connection 399
13:09:42 INFO - 1500408582351 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49834
13:09:42 INFO - 1500408582373 Marionette DEBUG Closed connection 400
13:09:42 INFO - 1500408582375 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49835
13:09:42 INFO - 1500408582379 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:42 INFO - 1500408582383 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}
13:09:42 INFO - 1500408582432 Marionette DEBUG Register listener.js for window 15
13:09:42 INFO - 1500408582447 Marionette TRACE 401 <- [1,1,null,{"sessionId":"dd9b126d-b934-441f-80fe-b535d6f3445c","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzorwfa.mozrunner","moz:accessibilityChecks":false}}]
13:09:42 INFO - 1500408582625 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
13:09:42 INFO - 1500408582640 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"
13:09:42 INFO - 1500408582648 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"
13:09:42 INFO - 1500408582650 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"
13:09:42 INFO - ++DOMWINDOW == 29 (0000008211CCF000) [pid = 320] [serial = 48] [outer = 0000008219FA7000]
13:09:42 INFO - 1500408582730 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"
13:09:42 INFO - 1500408582738 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"
13:09:42 INFO - 1500408582758 Marionette TRACE 401 <- [1,2,null,{}]
13:09:42 INFO - 1500408582780 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:09:42 INFO - 1500408582797 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ef710702-91d1-4e08-a6f9-3c091b2e529a","ELEMENT":"ef710702-91d1-4e08-a6f9-3c091b2e529a"}}]
13:09:42 INFO - 1500408582814 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ef710702-91d1-4e08-a6f9-3c091b2e529a"}]
13:09:42 INFO - 1500408582974 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
13:09:42 INFO - 1500408582981 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"ef710702-91d1-4e08-a6f9-3c091b2e529a"}]
13:09:43 INFO - 1500408583143 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element