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]
12:55:18 INFO - 1500234918301 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}]
12:55:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500234918308 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:55:18 INFO - 1500234918310 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918314 Marionette TRACE 352 <- [1,6,null,{}]
12:55:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1652ms
12:55:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918320 Marionette DEBUG Closed connection 352
12:55:18 INFO - 1500234918322 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49795
12:55:18 INFO - 1500234918323 Marionette DEBUG Closed connection 353
12:55:18 INFO - 1500234918325 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49796
12:55:18 INFO - 1500234918327 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:18 INFO - 1500234918329 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:18 INFO - 1500234918351 Marionette DEBUG Register listener.js for window 15
12:55:18 INFO - 1500234918385 Marionette TRACE 354 <- [1,1,null,{"sessionId":"59acf52c-af7c-4e5b-8de2-e1c4d2e92337","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:18 INFO - 1500234918392 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:55:18 INFO - 1500234918393 Marionette TRACE 354 <- [1,2,null,{}]
12:55:18 INFO - 1500234918395 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:18 INFO - 1500234918396 Marionette TRACE 354 <- [1,3,null,{}]
12:55:18 INFO - 1500234918398 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:18 INFO - 1500234918400 Marionette TRACE 354 <- [1,4,null,{}]
12:55:18 INFO - 1500234918403 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}]
12:55:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500234918410 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:55:18 INFO - 1500234918411 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918416 Marionette TRACE 354 <- [1,6,null,{}]
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918440 Marionette DEBUG Closed connection 354
12:55:18 INFO - 1500234918442 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49797
12:55:18 INFO - 1500234918446 Marionette DEBUG Closed connection 355
12:55:18 INFO - 1500234918447 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49798
12:55:18 INFO - 1500234918449 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:18 INFO - 1500234918451 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:18 INFO - 1500234918472 Marionette DEBUG Register listener.js for window 15
12:55:18 INFO - 1500234918483 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5c809ef0-5ee6-4c9b-a3ec-16214ae25d67","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:18 INFO - 1500234918493 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918497 Marionette TRACE 356 <- [1,2,null,{}]
12:55:18 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:18 INFO - 1500234918519 Marionette DEBUG Closed connection 356
12:55:18 INFO - 1500234918521 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49799
12:55:18 INFO - 1500234918525 Marionette DEBUG Closed connection 357
12:55:18 INFO - 1500234918526 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49800
12:55:18 INFO - 1500234918580 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:18 INFO - 1500234918593 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:18 INFO - 1500234918635 Marionette DEBUG Register listener.js for window 15
12:55:18 INFO - 1500234918658 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a84bae3e-a8b2-4a08-9837-104770d8b42b","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:18 INFO - 1500234918678 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
12:55:18 INFO - 1500234918686 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
12:55:18 INFO - 1500234918704 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
12:55:18 INFO - 1500234918705 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
12:55:18 INFO - ++DOMWINDOW == 28 (0000005159704000) [pid = 1676] [serial = 40] [outer = 0000005166CB7000]
12:55:18 INFO - 1500234918804 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
12:55:18 INFO - 1500234918811 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
12:55:18 INFO - 1500234918835 Marionette TRACE 358 <- [1,2,null,{}]
12:55:18 INFO - 1500234918847 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:55:18 INFO - 1500234918861 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"710281ba-01c3-4f7b-8b35-8ed3705116c2","ELEMENT":"710281ba-01c3-4f7b-8b35-8ed3705116c2"}}]
12:55:18 INFO - 1500234918864 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"710281ba-01c3-4f7b-8b35-8ed3705116c2"}]
12:55:19 INFO - 1500234919281 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
12:55:19 INFO - [Parent 1676] 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
12:55:19 INFO - 1500234919303 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
12:55:19 INFO - 1500234919305 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
12:55:19 INFO - ++DOMWINDOW == 29 (000000515C4B5800) [pid = 1676] [serial = 41] [outer = 0000005166CB7000]
12:55:19 INFO - 1500234919383 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
12:55:19 INFO - 1500234919389 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
12:55:19 INFO - 1500234919410 Marionette TRACE 358 <- [1,4,null,{}]
12:55:19 INFO - 1500234919426 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:55:19 INFO - 1500234919438 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e2ead3b7-427e-4ab2-ab64-22eee9ca0bfb","ELEMENT":"e2ead3b7-427e-4ab2-ab64-22eee9ca0bfb"}}]
12:55:19 INFO - 1500234919442 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:55:19 INFO - 1500234919445 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:55:19 INFO - 1500234919449 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}]
12:55:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500234919461 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:55:19 INFO - 1500234919465 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919471 Marionette TRACE 358 <- [1,8,null,{}]
12:55:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1157ms
12:55:19 INFO - TEST-START | test_click.py TestClick.test_container_element
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919480 Marionette DEBUG Closed connection 358
12:55:19 INFO - 1500234919483 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49803
12:55:19 INFO - 1500234919537 Marionette DEBUG Closed connection 359
12:55:19 INFO - 1500234919539 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49804
12:55:19 INFO - 1500234919554 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:19 INFO - 1500234919559 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:19 INFO - 1500234919601 Marionette DEBUG Register listener.js for window 15
12:55:19 INFO - 1500234919619 Marionette TRACE 360 <- [1,1,null,{"sessionId":"81ebb5a5-da4d-4f8e-8b2f-4b36ba774b51","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:19 INFO - 1500234919630 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:55:19 INFO - 1500234919632 Marionette TRACE 360 <- [1,2,null,{}]
12:55:19 INFO - 1500234919644 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:19 INFO - 1500234919650 Marionette TRACE 360 <- [1,3,null,{}]
12:55:19 INFO - 1500234919702 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:19 INFO - 1500234919705 Marionette TRACE 360 <- [1,4,null,{}]
12:55:19 INFO - 1500234919708 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}]
12:55:19 INFO - TEST-START: test_click.py TestClick.test_container_element1500234919719 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:55:19 INFO - 1500234919723 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919728 Marionette TRACE 360 <- [1,6,null,{}]
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919786 Marionette DEBUG Closed connection 360
12:55:19 INFO - 1500234919789 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49805
12:55:19 INFO - 1500234919810 Marionette DEBUG Closed connection 361
12:55:19 INFO - 1500234919812 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49806
12:55:19 INFO - 1500234919815 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:19 INFO - 1500234919819 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:19 INFO - 1500234919860 Marionette DEBUG Register listener.js for window 15
12:55:19 INFO - 1500234919878 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b5bed611-5c96-45a6-97b3-0afd1c17036f","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:19 INFO - 1500234919887 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919892 Marionette TRACE 362 <- [1,2,null,{}]
12:55:19 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:19 INFO - 1500234919906 Marionette DEBUG Closed connection 362
12:55:19 INFO - 1500234919909 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49807
12:55:19 INFO - 1500234919933 Marionette DEBUG Closed connection 363
12:55:19 INFO - 1500234919935 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49808
12:55:19 INFO - 1500234919938 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:19 INFO - 1500234919941 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:19 INFO - 1500234919984 Marionette DEBUG Register listener.js for window 15
12:55:20 INFO - 1500234919999 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8d9dbefc-dab2-4669-9b07-7298b8f5e778","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:20 INFO - 1500234920011 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"}]
12:55:20 INFO - 1500234920017 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
12:55:20 INFO - 1500234920025 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
12:55:20 INFO - 1500234920026 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
12:55:20 INFO - ++DOMWINDOW == 30 (0000005167033800) [pid = 1676] [serial = 42] [outer = 0000005166CB7000]
12:55:20 INFO - 1500234920103 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"
12:55:20 INFO - 1500234920111 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"
12:55:20 INFO - 1500234920130 Marionette TRACE 364 <- [1,2,null,{}]
12:55:20 INFO - 1500234920156 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:55:20 INFO - 1500234920166 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dcba6a57-99c2-4ae6-9715-4d43e238b6bb","ELEMENT":"dcba6a57-99c2-4ae6-9715-4d43e238b6bb"}}]
12:55:20 INFO - 1500234920168 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"dcba6a57-99c2-4ae6-9715-4d43e238b6bb"}]
12:55:20 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:55:20 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:55:20 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:55:20 INFO - 1500234920788 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:55:20 INFO - 1500234920790 Marionette TRACE 364 <- [1,4,null,{}]
12:55:20 INFO - 1500234920792 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"dcba6a57-99c2-4ae6-9715-4d43e238b6bb","name":"selected"}]
12:55:20 INFO - 1500234920849 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:55:20 INFO - 1500234920883 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}]
12:55:20 INFO - TEST-END: test_click.py TestClick.test_container_element1500234920897 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:55:20 INFO - 1500234920908 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:55:20 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:20 INFO - 1500234920913 Marionette TRACE 364 <- [1,7,null,{}]
12:55:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1441ms
12:55:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:55:20 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:20 INFO - 1500234920931 Marionette DEBUG Closed connection 364
12:55:20 INFO - 1500234920933 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49809
12:55:20 INFO - 1500234920954 Marionette DEBUG Closed connection 365
12:55:20 INFO - 1500234920956 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49810
12:55:20 INFO - 1500234920960 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:20 INFO - 1500234920966 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:21 INFO - 1500234921009 Marionette DEBUG Register listener.js for window 15
12:55:21 INFO - 1500234921026 Marionette TRACE 366 <- [1,1,null,{"sessionId":"89e96d20-459f-4804-8510-1db8311e11fc","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:21 INFO - 1500234921226 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:55:21 INFO - 1500234921233 Marionette TRACE 366 <- [1,2,null,{}]
12:55:21 INFO - 1500234921241 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:21 INFO - 1500234921245 Marionette TRACE 366 <- [1,3,null,{}]
12:55:21 INFO - 1500234921250 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:21 INFO - 1500234921256 Marionette TRACE 366 <- [1,4,null,{}]
12:55:21 INFO - 1500234921310 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}]
12:55:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500234921322 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:55:21 INFO - 1500234921330 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:21 INFO - 1500234921339 Marionette TRACE 366 <- [1,6,null,{}]
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:21 INFO - 1500234921368 Marionette DEBUG Closed connection 366
12:55:21 INFO - 1500234921372 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49811
12:55:21 INFO - 1500234921376 Marionette DEBUG Closed connection 367
12:55:21 INFO - 1500234921378 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49812
12:55:21 INFO - 1500234921433 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:21 INFO - 1500234921442 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:21 INFO - 1500234921467 Marionette DEBUG Register listener.js for window 15
12:55:21 INFO - 1500234921490 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d413ae68-5e91-45ba-8de9-ce4221dbfdf3","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:21 INFO - 1500234921507 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:21 INFO - 1500234921514 Marionette TRACE 368 <- [1,2,null,{}]
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:21 INFO - 1500234921527 Marionette DEBUG Closed connection 368
12:55:21 INFO - 1500234921530 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49813
12:55:21 INFO - 1500234921551 Marionette DEBUG Closed connection 369
12:55:21 INFO - 1500234921553 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49814
12:55:21 INFO - 1500234921558 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:21 INFO - 1500234921562 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:21 INFO - 1500234921585 Marionette DEBUG Register listener.js for window 15
12:55:21 INFO - 1500234921601 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d1caaf32-86e8-469b-abfe-a8c287a278a6","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:21 INFO - 1500234921613 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"}]
12:55:21 INFO - 1500234921619 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"
12:55:21 INFO - 1500234921630 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"
12:55:21 INFO - 1500234921631 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"
12:55:21 INFO - ++DOMWINDOW == 31 (00000051592AD800) [pid = 1676] [serial = 43] [outer = 0000005166CB7000]
12:55:21 INFO - 1500234921693 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"
12:55:21 INFO - 1500234921710 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"
12:55:21 INFO - 1500234921730 Marionette TRACE 370 <- [1,2,null,{}]
12:55:21 INFO - 1500234921736 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:55:21 INFO - 1500234921745 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"16cf08ac-8379-421e-a67e-e38cf44356e8","ELEMENT":"16cf08ac-8379-421e-a67e-e38cf44356e8"}}]
12:55:21 INFO - 1500234921752 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"16cf08ac-8379-421e-a67e-e38cf44356e8"}]
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:55:21 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:55:22 INFO - --DOMWINDOW == 30 (000000515E8C6800) [pid = 1676] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 29 (000000515E81B000) [pid = 1676] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 28 (000000515A325000) [pid = 1676] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 27 (000000515F4A8800) [pid = 1676] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 26 (000000516B85A000) [pid = 1676] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 25 (000000515E9D3000) [pid = 1676] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - --DOMWINDOW == 24 (000000515E9D4800) [pid = 1676] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
12:55:22 INFO - 1500234922200 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:55:22 INFO - 1500234922203 Marionette TRACE 370 <- [1,4,null,{}]
12:55:22 INFO - 1500234922206 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"16cf08ac-8379-421e-a67e-e38cf44356e8","name":"selected"}]
12:55:22 INFO - 1500234922236 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:55:22 INFO - 1500234922239 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}]
12:55:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500234922249 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:55:22 INFO - 1500234922251 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922256 Marionette TRACE 370 <- [1,7,null,{}]
12:55:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1343ms
12:55:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922263 Marionette DEBUG Closed connection 370
12:55:22 INFO - 1500234922265 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49815
12:55:22 INFO - 1500234922267 Marionette DEBUG Closed connection 371
12:55:22 INFO - 1500234922269 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49816
12:55:22 INFO - 1500234922272 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:22 INFO - 1500234922275 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:22 INFO - 1500234922297 Marionette DEBUG Register listener.js for window 15
12:55:22 INFO - 1500234922312 Marionette TRACE 372 <- [1,1,null,{"sessionId":"278a4891-b63e-4792-95e5-d609a28a05b7","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:22 INFO - 1500234922320 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:55:22 INFO - 1500234922321 Marionette TRACE 372 <- [1,2,null,{}]
12:55:22 INFO - 1500234922323 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:22 INFO - 1500234922325 Marionette TRACE 372 <- [1,3,null,{}]
12:55:22 INFO - 1500234922327 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:22 INFO - 1500234922329 Marionette TRACE 372 <- [1,4,null,{}]
12:55:22 INFO - 1500234922332 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}]
12:55:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500234922340 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:55:22 INFO - 1500234922343 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922347 Marionette TRACE 372 <- [1,6,null,{}]
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922353 Marionette DEBUG Closed connection 372
12:55:22 INFO - 1500234922355 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49817
12:55:22 INFO - 1500234922357 Marionette DEBUG Closed connection 373
12:55:22 INFO - 1500234922359 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49818
12:55:22 INFO - 1500234922362 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:22 INFO - 1500234922364 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:22 INFO - 1500234922388 Marionette DEBUG Register listener.js for window 15
12:55:22 INFO - 1500234922405 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2928cb16-4abf-4a9c-80d4-d407fb6436c1","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:22 INFO - 1500234922468 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922473 Marionette TRACE 374 <- [1,2,null,{}]
12:55:22 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:22 INFO - 1500234922495 Marionette DEBUG Closed connection 374
12:55:22 INFO - 1500234922497 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49819
12:55:23 INFO - 1500234923233 Marionette DEBUG Closed connection 375
12:55:23 INFO - 1500234923235 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49820
12:55:23 INFO - 1500234923260 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:23 INFO - 1500234923265 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:23 INFO - 1500234923288 Marionette DEBUG Register listener.js for window 15
12:55:23 INFO - 1500234923303 Marionette TRACE 376 <- [1,1,null,{"sessionId":"55ae0e32-b109-4e79-9ccc-23a9ebd80a24","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:23 INFO - 1500234923410 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"}]
12:55:23 INFO - 1500234923415 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"
12:55:23 INFO - 1500234923423 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"
12:55:23 INFO - 1500234923424 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"
12:55:23 INFO - ++DOMWINDOW == 25 (000000515B2D6000) [pid = 1676] [serial = 44] [outer = 0000005166CB7000]
12:55:23 INFO - 1500234923534 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"
12:55:23 INFO - 1500234923541 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"
12:55:23 INFO - 1500234923560 Marionette TRACE 376 <- [1,2,null,{}]
12:55:23 INFO - 1500234923629 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:55:23 INFO - 1500234923639 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"511b6c9d-7cab-4a9e-8576-71ec2698d5fb","ELEMENT":"511b6c9d-7cab-4a9e-8576-71ec2698d5fb"}}]
12:55:23 INFO - 1500234923692 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"511b6c9d-7cab-4a9e-8576-71ec2698d5fb"}]
12:55:24 INFO - 1500234924193 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:55:24 INFO - 1500234924195 Marionette TRACE 376 <- [1,4,null,{}]
12:55:24 INFO - 1500234924198 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}]
12:55:24 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500234924210 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:55:24 INFO - 1500234924212 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924217 Marionette TRACE 376 <- [1,6,null,{}]
12:55:24 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1960ms
12:55:24 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924244 Marionette DEBUG Closed connection 376
12:55:24 INFO - 1500234924249 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49821
12:55:24 INFO - 1500234924253 Marionette DEBUG Closed connection 377
12:55:24 INFO - 1500234924255 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49822
12:55:24 INFO - 1500234924257 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:24 INFO - 1500234924260 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:24 INFO - 1500234924283 Marionette DEBUG Register listener.js for window 15
12:55:24 INFO - 1500234924297 Marionette TRACE 378 <- [1,1,null,{"sessionId":"108f0d86-217f-492f-827f-c877da1f6572","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:24 INFO - 1500234924307 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:55:24 INFO - 1500234924309 Marionette TRACE 378 <- [1,2,null,{}]
12:55:24 INFO - 1500234924311 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:24 INFO - 1500234924313 Marionette TRACE 378 <- [1,3,null,{}]
12:55:24 INFO - 1500234924314 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:24 INFO - 1500234924316 Marionette TRACE 378 <- [1,4,null,{}]
12:55:24 INFO - 1500234924318 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}]
12:55:24 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500234924327 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:55:24 INFO - 1500234924328 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924333 Marionette TRACE 378 <- [1,6,null,{}]
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924357 Marionette DEBUG Closed connection 378
12:55:24 INFO - 1500234924359 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49823
12:55:24 INFO - 1500234924363 Marionette DEBUG Closed connection 379
12:55:24 INFO - 1500234924364 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49824
12:55:24 INFO - 1500234924366 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:24 INFO - 1500234924369 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:24 INFO - 1500234924392 Marionette DEBUG Register listener.js for window 15
12:55:24 INFO - 1500234924403 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c9561108-5f72-4c61-b1d7-17a3a7bcf591","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:24 INFO - 1500234924413 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924418 Marionette TRACE 380 <- [1,2,null,{}]
12:55:24 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:24 INFO - 1500234924441 Marionette DEBUG Closed connection 380
12:55:24 INFO - 1500234924443 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49825
12:55:24 INFO - 1500234924466 Marionette DEBUG Closed connection 381
12:55:24 INFO - 1500234924468 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49826
12:55:24 INFO - 1500234924472 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:24 INFO - 1500234924475 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:24 INFO - 1500234924500 Marionette DEBUG Register listener.js for window 15
12:55:24 INFO - 1500234924511 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9584fa93-5926-4396-bf26-a9700399e3fd","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:24 INFO - 1500234924521 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"}]
12:55:24 INFO - 1500234924526 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"
12:55:24 INFO - 1500234924533 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"
12:55:24 INFO - 1500234924534 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"
12:55:24 INFO - ++DOMWINDOW == 26 (000000515E9C1800) [pid = 1676] [serial = 45] [outer = 0000005166CB7000]
12:55:24 INFO - 1500234924591 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"
12:55:24 INFO - 1500234924597 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"
12:55:24 INFO - 1500234924615 Marionette TRACE 382 <- [1,2,null,{}]
12:55:24 INFO - 1500234924622 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:55:24 INFO - 1500234924632 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bfeb4b05-b704-4d12-a4db-10d0948ed279","ELEMENT":"bfeb4b05-b704-4d12-a4db-10d0948ed279"}}]
12:55:24 INFO - 1500234924634 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"bfeb4b05-b704-4d12-a4db-10d0948ed279"}]
12:55:25 INFO - 1500234925074 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:55:25 INFO - 1500234925076 Marionette TRACE 382 <- [1,4,null,{}]
12:55:25 INFO - 1500234925078 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}]
12:55:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500234925088 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:55:25 INFO - 1500234925090 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925094 Marionette TRACE 382 <- [1,6,null,{}]
12:55:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 877ms
12:55:25 INFO - TEST-START | test_click.py TestClick.test_input_file
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925100 Marionette DEBUG Closed connection 382
12:55:25 INFO - 1500234925102 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49827
12:55:25 INFO - 1500234925103 Marionette DEBUG Closed connection 383
12:55:25 INFO - 1500234925104 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49828
12:55:25 INFO - 1500234925107 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:25 INFO - 1500234925109 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:25 INFO - 1500234925132 Marionette DEBUG Register listener.js for window 15
12:55:25 INFO - 1500234925143 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5dc8b4ec-a6c9-4fad-bbbf-a89c36c6fda7","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:25 INFO - 1500234925150 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:55:25 INFO - 1500234925151 Marionette TRACE 384 <- [1,2,null,{}]
12:55:25 INFO - 1500234925153 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:25 INFO - 1500234925155 Marionette TRACE 384 <- [1,3,null,{}]
12:55:25 INFO - 1500234925157 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:25 INFO - 1500234925159 Marionette TRACE 384 <- [1,4,null,{}]
12:55:25 INFO - 1500234925161 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}]
12:55:25 INFO - TEST-START: test_click.py TestClick.test_input_file1500234925169 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:55:25 INFO - 1500234925171 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925176 Marionette TRACE 384 <- [1,6,null,{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925228 Marionette DEBUG Closed connection 384
12:55:25 INFO - 1500234925229 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49829
12:55:25 INFO - 1500234925233 Marionette DEBUG Closed connection 385
12:55:25 INFO - 1500234925234 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49830
12:55:25 INFO - 1500234925237 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:25 INFO - 1500234925239 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:25 INFO - 1500234925262 Marionette DEBUG Register listener.js for window 15
12:55:25 INFO - 1500234925274 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cf7013ab-f356-4e34-bb94-c0833b311405","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:25 INFO - 1500234925280 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925284 Marionette TRACE 386 <- [1,2,null,{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925290 Marionette DEBUG Closed connection 386
12:55:25 INFO - 1500234925291 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49831
12:55:25 INFO - 1500234925293 Marionette DEBUG Closed connection 387
12:55:25 INFO - 1500234925295 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49832
12:55:25 INFO - 1500234925297 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:25 INFO - 1500234925300 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:25 INFO - 1500234925328 Marionette DEBUG Register listener.js for window 15
12:55:25 INFO - 1500234925342 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7a38ff71-4cf0-4676-80c7-e3349b69e8b9","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:25 INFO - 1500234925348 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:55:25 INFO - 1500234925353 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"
12:55:25 INFO - 1500234925362 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"
12:55:25 INFO - 1500234925364 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"
12:55:25 INFO - ++DOMWINDOW == 27 (000000516093F800) [pid = 1676] [serial = 46] [outer = 0000005166CB7000]
12:55:25 INFO - 1500234925420 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:55:25 INFO - 1500234925427 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:55:25 INFO - 1500234925445 Marionette TRACE 388 <- [1,2,null,{}]
12:55:25 INFO - 1500234925481 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:55:25 INFO - 1500234925489 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"336e2920-63a2-412f-a10f-2a78d468aaa6","ELEMENT":"336e2920-63a2-412f-a10f-2a78d468aaa6"}}]
12:55:25 INFO - 1500234925491 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"336e2920-63a2-412f-a10f-2a78d468aaa6"}]
12:55:25 INFO - 1500234925667 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]
12:55:25 INFO - 1500234925685 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}]
12:55:25 INFO - TEST-END: test_click.py TestClick.test_input_file1500234925694 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:55:25 INFO - 1500234925696 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925700 Marionette TRACE 388 <- [1,6,null,{}]
12:55:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 606ms
12:55:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925706 Marionette DEBUG Closed connection 388
12:55:25 INFO - 1500234925708 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49833
12:55:25 INFO - 1500234925709 Marionette DEBUG Closed connection 389
12:55:25 INFO - 1500234925711 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49834
12:55:25 INFO - 1500234925713 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:25 INFO - 1500234925715 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:25 INFO - 1500234925738 Marionette DEBUG Register listener.js for window 15
12:55:25 INFO - 1500234925774 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cd5aa060-3757-45fb-8f1b-443298b38844","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:25 INFO - 1500234925781 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:55:25 INFO - 1500234925783 Marionette TRACE 390 <- [1,2,null,{}]
12:55:25 INFO - 1500234925784 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:25 INFO - 1500234925786 Marionette TRACE 390 <- [1,3,null,{}]
12:55:25 INFO - 1500234925788 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:25 INFO - 1500234925789 Marionette TRACE 390 <- [1,4,null,{}]
12:55:25 INFO - 1500234925791 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}]
12:55:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500234925799 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:55:25 INFO - 1500234925805 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925809 Marionette TRACE 390 <- [1,6,null,{}]
12:55:25 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:25 INFO - 1500234925831 Marionette DEBUG Closed connection 390
12:55:25 INFO - 1500234925833 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49835
12:55:26 INFO - 1500234926454 Marionette DEBUG Closed connection 391
12:55:26 INFO - 1500234926455 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49836
12:55:26 INFO - 1500234926499 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:26 INFO - 1500234926501 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:26 INFO - 1500234926525 Marionette DEBUG Register listener.js for window 15
12:55:26 INFO - 1500234926535 Marionette TRACE 392 <- [1,1,null,{"sessionId":"18f45eab-af9a-44cc-a70b-1018e77ea03e","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:26 INFO - 1500234926541 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:55:26 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:26 INFO - 1500234926545 Marionette TRACE 392 <- [1,2,null,{}]
12:55:26 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:26 INFO - 1500234926551 Marionette DEBUG Closed connection 392
12:55:26 INFO - 1500234926553 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49837
12:55:26 INFO - 1500234926554 Marionette DEBUG Closed connection 393
12:55:26 INFO - 1500234926555 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49838
12:55:26 INFO - 1500234926558 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:26 INFO - 1500234926560 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:26 INFO - 1500234926583 Marionette DEBUG Register listener.js for window 15
12:55:26 INFO - 1500234926632 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0f040086-049b-48af-a824-9638aa4ce94f","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:26 INFO - 1500234926714 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:55:26 INFO - 1500234926721 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:55:26 INFO - 1500234926732 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:55:26 INFO - 1500234926734 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:55:26 INFO - ++DOMWINDOW == 28 (00000051592A9000) [pid = 1676] [serial = 47] [outer = 0000005166CB7000]
12:55:26 INFO - 1500234926805 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"
12:55:26 INFO - 1500234926814 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"
12:55:26 INFO - 1500234926835 Marionette TRACE 394 <- [1,2,null,{}]
12:55:26 INFO - 1500234926844 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:55:26 INFO - 1500234926859 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ef89192e-17db-4d48-92e7-172cf0fbf072","ELEMENT":"ef89192e-17db-4d48-92e7-172cf0fbf072"}}]
12:55:26 INFO - 1500234926864 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:55:26 INFO - 1500234926877 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dde11b4f-56c0-4152-a61f-276f7b310086","ELEMENT":"dde11b4f-56c0-4152-a61f-276f7b310086"}}]
12:55:26 INFO - 1500234926893 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ef89192e-17db-4d48-92e7-172cf0fbf072"}]
12:55:27 INFO - 1500234927517 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:55:27 INFO - 1500234927522 Marionette TRACE 394 <- [1,5,null,{}]
12:55:27 INFO - 1500234927528 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"dde11b4f-56c0-4152-a61f-276f7b310086"}]
12:55:27 INFO - 1500234927841 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:55:27 INFO - 1500234927851 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:55:27 INFO - 1500234927866 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:55:27 INFO - 1500234927870 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:55:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500234927880 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:55:27 INFO - 1500234927911 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:55:27 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:27 INFO - 1500234927919 Marionette TRACE 394 <- [1,9,null,{}]
12:55:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2229ms
12:55:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:55:27 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:27 INFO - 1500234927955 Marionette DEBUG Closed connection 394
12:55:27 INFO - 1500234927958 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49839
12:55:27 INFO - 1500234927963 Marionette DEBUG Closed connection 395
12:55:27 INFO - 1500234927966 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49840
12:55:28 INFO - 1500234928018 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:28 INFO - 1500234928028 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:28 INFO - 1500234928074 Marionette DEBUG Register listener.js for window 15
12:55:28 INFO - 1500234928094 Marionette TRACE 396 <- [1,1,null,{"sessionId":"d5d6a36f-fd15-4afd-b617-24e5a333e6dd","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:28 INFO - 1500234928108 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:55:28 INFO - 1500234928111 Marionette TRACE 396 <- [1,2,null,{}]
12:55:28 INFO - 1500234928123 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:55:28 INFO - 1500234928128 Marionette TRACE 396 <- [1,3,null,{}]
12:55:28 INFO - 1500234928180 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:55:28 INFO - 1500234928183 Marionette TRACE 396 <- [1,4,null,{}]
12:55:28 INFO - 1500234928186 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:55:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500234928197 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:55:28 INFO - 1500234928201 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:55:28 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:28 INFO - 1500234928207 Marionette TRACE 396 <- [1,6,null,{}]
12:55:28 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:28 INFO - 1500234928265 Marionette DEBUG Closed connection 396
12:55:28 INFO - 1500234928269 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49841
12:55:28 INFO - 1500234928290 Marionette DEBUG Closed connection 397
12:55:28 INFO - 1500234928292 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49842
12:55:28 INFO - 1500234928295 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:55:28 INFO - 1500234928298 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:28 INFO - 1500234928344 Marionette DEBUG Register listener.js for window 15
12:55:28 INFO - 1500234928362 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6fc906eb-3f37-4be5-b29a-27d3ab62f796","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:28 INFO - 1500234928393 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:55:28 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:28 INFO - 1500234928400 Marionette TRACE 398 <- [1,2,null,{}]
12:55:28 INFO - [Parent 1676] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:55:28 INFO - 1500234928428 Marionette DEBUG Closed connection 398
12:55:28 INFO - 1500234928430 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49843
12:55:28 INFO - 1500234928435 Marionette DEBUG Closed connection 399
12:55:28 INFO - 1500234928437 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49844
12:55:28 INFO - 1500234928449 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:55:28 INFO - 1500234928454 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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}
12:55:28 INFO - 1500234928501 Marionette DEBUG Register listener.js for window 15
12:55:28 INFO - 1500234928517 Marionette TRACE 400 <- [1,1,null,{"sessionId":"dab35e3c-27ca-4efa-95c7-ec7d2fce6f68","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":1676,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-231.001\\AppData\\Local\\Temp\\tmpmbaedh.mozrunner","moz:accessibilityChecks":false}}]
12:55:28 INFO - 1500234928530 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:55:28 INFO - 1500234928536 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"
12:55:28 INFO - 1500234928545 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"
12:55:28 INFO - 1500234928546 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"
12:55:28 INFO - ++DOMWINDOW == 29 (0000005166A66800) [pid = 1676] [serial = 48] [outer = 0000005166CB7000]
12:55:28 INFO - 1500234928615 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"
12:55:28 INFO - 1500234928622 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"
12:55:28 INFO - 1500234928811 Marionette TRACE 400 <- [1,2,null,{}]
12:55:28 INFO - 1500234928823 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:55:28 INFO - 1500234928842 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4afa8129-37ff-4997-9a5c-99ba9d18c973","ELEMENT":"4afa8129-37ff-4997-9a5c-99ba9d18c973"}}]
12:55:28 INFO - 1500234928858 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4afa8129-37ff-4997-9a5c-99ba9d18c973"}]
12:55:29 INFO - 1500234929013 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:55:29 INFO - 1500234929021 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"4afa8129-37ff-4997-9a5c-99ba9d18c973"}]
12:55:29 INFO - 1500234929192 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element