could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:48:34 INFO - 1500936514106 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
15:48:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500936514116 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:48:34 INFO - 1500936514143 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514148 Marionette TRACE 352 <- [1,6,null,{}]
15:48:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1041ms
15:48:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514156 Marionette DEBUG Closed connection 352
15:48:34 INFO - 1500936514158 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49780
15:48:34 INFO - 1500936514203 Marionette DEBUG Closed connection 353
15:48:34 INFO - 1500936514206 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
15:48:34 INFO - 1500936514228 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:34 INFO - 1500936514231 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:34 INFO - 1500936514273 Marionette DEBUG Register listener.js for window 12
15:48:34 INFO - 1500936514288 Marionette TRACE 354 <- [1,1,null,{"sessionId":"cd37a27c-cde6-42de-8b0b-ffc250dd25ed","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:34 INFO - 1500936514299 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:48:34 INFO - 1500936514301 Marionette TRACE 354 <- [1,2,null,{}]
15:48:34 INFO - 1500936514329 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:34 INFO - 1500936514331 Marionette TRACE 354 <- [1,3,null,{}]
15:48:34 INFO - 1500936514334 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:34 INFO - 1500936514336 Marionette TRACE 354 <- [1,4,null,{}]
15:48:34 INFO - 1500936514339 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
15:48:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500936514350 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:48:34 INFO - 1500936514401 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514407 Marionette TRACE 354 <- [1,6,null,{}]
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514431 Marionette DEBUG Closed connection 354
15:48:34 INFO - 1500936514433 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
15:48:34 INFO - 1500936514437 Marionette DEBUG Closed connection 355
15:48:34 INFO - 1500936514439 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
15:48:34 INFO - 1500936514442 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:34 INFO - 1500936514448 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:34 INFO - 1500936514492 Marionette DEBUG Register listener.js for window 12
15:48:34 INFO - 1500936514508 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f3d94adb-f143-4ea2-8e03-30db13c75685","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:34 INFO - 1500936514522 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514528 Marionette TRACE 356 <- [1,2,null,{}]
15:48:34 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:34 INFO - 1500936514536 Marionette DEBUG Closed connection 356
15:48:34 INFO - 1500936514542 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
15:48:34 INFO - 1500936514573 Marionette DEBUG Closed connection 357
15:48:34 INFO - 1500936514575 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
15:48:34 INFO - 1500936514578 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:34 INFO - 1500936514581 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:34 INFO - 1500936514623 Marionette DEBUG Register listener.js for window 12
15:48:34 INFO - 1500936514639 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8bec700b-6d13-4c74-ad04-d358426c6864","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:34 INFO - 1500936514646 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
15:48:34 INFO - 1500936514653 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
15:48:34 INFO - 1500936514673 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
15:48:34 INFO - 1500936514674 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
15:48:34 INFO - ++DOMWINDOW == 27 (000000F97AC1F800) [pid = 2736] [serial = 41] [outer = 000000F972405000]
15:48:34 INFO - 1500936514759 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
15:48:34 INFO - 1500936514766 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
15:48:34 INFO - 1500936514790 Marionette TRACE 358 <- [1,2,null,{}]
15:48:34 INFO - 1500936514806 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:48:34 INFO - 1500936514815 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f843879-7c80-4d87-ad2c-7b1875acc244","ELEMENT":"2f843879-7c80-4d87-ad2c-7b1875acc244"}}]
15:48:34 INFO - 1500936514818 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2f843879-7c80-4d87-ad2c-7b1875acc244"}]
15:48:35 INFO - 1500936515189 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
15:48:35 INFO - [Parent 2736] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
15:48:35 INFO - 1500936515209 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
15:48:35 INFO - 1500936515211 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
15:48:35 INFO - ++DOMWINDOW == 28 (000000F97CB89800) [pid = 2736] [serial = 42] [outer = 000000F972405000]
15:48:35 INFO - 1500936515291 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
15:48:35 INFO - 1500936515297 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
15:48:35 INFO - 1500936515315 Marionette TRACE 358 <- [1,4,null,{}]
15:48:35 INFO - 1500936515318 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:48:35 INFO - 1500936515330 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"573f7ad1-2b87-496b-af7b-1857d63f98ed","ELEMENT":"573f7ad1-2b87-496b-af7b-1857d63f98ed"}}]
15:48:35 INFO - 1500936515375 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:48:35 INFO - 1500936515377 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:48:35 INFO - 1500936515381 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
15:48:35 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500936515391 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:48:35 INFO - 1500936515395 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:35 INFO - 1500936515400 Marionette TRACE 358 <- [1,8,null,{}]
15:48:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1253ms
15:48:35 INFO - TEST-START | test_click.py TestClick.test_container_element
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:35 INFO - 1500936515422 Marionette DEBUG Closed connection 358
15:48:35 INFO - 1500936515423 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
15:48:35 INFO - 1500936515445 Marionette DEBUG Closed connection 359
15:48:35 INFO - 1500936515446 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
15:48:35 INFO - 1500936515450 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:35 INFO - 1500936515452 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:35 INFO - 1500936515496 Marionette DEBUG Register listener.js for window 12
15:48:35 INFO - 1500936515510 Marionette TRACE 360 <- [1,1,null,{"sessionId":"07f76c6d-3159-4ed1-922b-37fde85afdc0","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:35 INFO - 1500936515706 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:48:35 INFO - 1500936515713 Marionette TRACE 360 <- [1,2,null,{}]
15:48:35 INFO - 1500936515723 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:35 INFO - 1500936515728 Marionette TRACE 360 <- [1,3,null,{}]
15:48:35 INFO - 1500936515782 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:35 INFO - 1500936515786 Marionette TRACE 360 <- [1,4,null,{}]
15:48:35 INFO - 1500936515795 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
15:48:35 INFO - TEST-START: test_click.py TestClick.test_container_element1500936515810 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:48:35 INFO - 1500936515815 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:35 INFO - 1500936515823 Marionette TRACE 360 <- [1,6,null,{}]
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:35 INFO - 1500936515834 Marionette DEBUG Closed connection 360
15:48:35 INFO - 1500936515837 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
15:48:35 INFO - 1500936515841 Marionette DEBUG Closed connection 361
15:48:35 INFO - 1500936515843 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
15:48:35 INFO - 1500936515847 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:35 INFO - 1500936515855 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:35 INFO - 1500936515880 Marionette DEBUG Register listener.js for window 12
15:48:35 INFO - 1500936515923 Marionette TRACE 362 <- [1,1,null,{"sessionId":"36386cc3-00f9-4ff8-a44b-fd2575006703","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:35 INFO - 1500936515967 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:35 INFO - 1500936515974 Marionette TRACE 362 <- [1,2,null,{}]
15:48:35 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:36 INFO - 1500936515999 Marionette DEBUG Closed connection 362
15:48:36 INFO - 1500936516002 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
15:48:36 INFO - 1500936516038 Marionette DEBUG Closed connection 363
15:48:36 INFO - 1500936516040 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
15:48:36 INFO - 1500936516045 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:36 INFO - 1500936516049 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:36 INFO - 1500936516073 Marionette DEBUG Register listener.js for window 12
15:48:36 INFO - 1500936516089 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c65c37be-f983-4784-bb2c-aa8989741a34","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:36 INFO - 1500936516105 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:48:36 INFO - 1500936516111 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
15:48:36 INFO - 1500936516120 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
15:48:36 INFO - 1500936516121 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
15:48:36 INFO - ++DOMWINDOW == 29 (000000F96681C000) [pid = 2736] [serial = 43] [outer = 000000F972405000]
15:48:36 INFO - 1500936516187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:36 INFO - 1500936516193 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:36 INFO - 1500936516219 Marionette TRACE 364 <- [1,2,null,{}]
15:48:36 INFO - 1500936516222 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:48:36 INFO - 1500936516232 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83ec8df5-7913-4fb2-8b59-abd48d13d5e4","ELEMENT":"83ec8df5-7913-4fb2-8b59-abd48d13d5e4"}}]
15:48:36 INFO - 1500936516239 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"83ec8df5-7913-4fb2-8b59-abd48d13d5e4"}]
15:48:36 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:48:36 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:48:36 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:48:36 INFO - --DOMWINDOW == 28 (000000F966B2B000) [pid = 2736] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:36 INFO - --DOMWINDOW == 27 (000000F978AE7000) [pid = 2736] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:36 INFO - --DOMWINDOW == 26 (000000F971641800) [pid = 2736] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:36 INFO - --DOMWINDOW == 25 (000000F971B4F800) [pid = 2736] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:36 INFO - --DOMWINDOW == 24 (000000F975FCE800) [pid = 2736] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:37 INFO - 1500936517388 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:48:37 INFO - 1500936517410 Marionette TRACE 364 <- [1,4,null,{}]
15:48:37 INFO - 1500936517454 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"83ec8df5-7913-4fb2-8b59-abd48d13d5e4","name":"selected"}]
15:48:37 INFO - 1500936517486 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:48:37 INFO - 1500936517536 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
15:48:37 INFO - TEST-END: test_click.py TestClick.test_container_element1500936517548 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:48:37 INFO - 1500936517595 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517601 Marionette TRACE 364 <- [1,7,null,{}]
15:48:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 2199ms
15:48:37 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517608 Marionette DEBUG Closed connection 364
15:48:37 INFO - 1500936517610 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
15:48:37 INFO - 1500936517612 Marionette DEBUG Closed connection 365
15:48:37 INFO - 1500936517614 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
15:48:37 INFO - 1500936517616 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:37 INFO - 1500936517620 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:37 INFO - 1500936517641 Marionette DEBUG Register listener.js for window 12
15:48:37 INFO - 1500936517656 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d663f820-3a28-42c0-9413-a8dc1b67f82e","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:37 INFO - 1500936517765 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:48:37 INFO - 1500936517767 Marionette TRACE 366 <- [1,2,null,{}]
15:48:37 INFO - 1500936517795 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:37 INFO - 1500936517797 Marionette TRACE 366 <- [1,3,null,{}]
15:48:37 INFO - 1500936517822 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:37 INFO - 1500936517824 Marionette TRACE 366 <- [1,4,null,{}]
15:48:37 INFO - 1500936517828 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
15:48:37 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500936517837 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:48:37 INFO - 1500936517841 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517845 Marionette TRACE 366 <- [1,6,null,{}]
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517869 Marionette DEBUG Closed connection 366
15:48:37 INFO - 1500936517871 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
15:48:37 INFO - 1500936517876 Marionette DEBUG Closed connection 367
15:48:37 INFO - 1500936517878 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
15:48:37 INFO - 1500936517881 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:37 INFO - 1500936517884 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:37 INFO - 1500936517906 Marionette DEBUG Register listener.js for window 12
15:48:37 INFO - 1500936517920 Marionette TRACE 368 <- [1,1,null,{"sessionId":"364a3b8e-76b4-424c-a2e0-d1abea46cf01","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:37 INFO - 1500936517931 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517935 Marionette TRACE 368 <- [1,2,null,{}]
15:48:37 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:37 INFO - 1500936517957 Marionette DEBUG Closed connection 368
15:48:37 INFO - 1500936517959 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
15:48:37 INFO - 1500936517963 Marionette DEBUG Closed connection 369
15:48:37 INFO - 1500936517965 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
15:48:37 INFO - 1500936517968 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:37 INFO - 1500936517973 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:37 INFO - 1500936517995 Marionette DEBUG Register listener.js for window 12
15:48:38 INFO - 1500936518010 Marionette TRACE 370 <- [1,1,null,{"sessionId":"87e97af6-b922-4089-914c-b1879b1ee6f4","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:38 INFO - 1500936518020 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:48:38 INFO - 1500936518024 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518032 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518033 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - ++DOMWINDOW == 25 (000000F971877800) [pid = 2736] [serial = 44] [outer = 000000F972405000]
15:48:38 INFO - 1500936518090 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518101 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518114 Marionette TRACE 370 <- [1,2,null,{}]
15:48:38 INFO - 1500936518126 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:48:38 INFO - 1500936518134 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa420804-a039-45bd-8558-e718b7896594","ELEMENT":"aa420804-a039-45bd-8558-e718b7896594"}}]
15:48:38 INFO - 1500936518142 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"aa420804-a039-45bd-8558-e718b7896594"}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:48:38 INFO - 1500936518607 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:48:38 INFO - 1500936518609 Marionette TRACE 370 <- [1,4,null,{}]
15:48:38 INFO - 1500936518611 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"aa420804-a039-45bd-8558-e718b7896594","name":"selected"}]
15:48:38 INFO - 1500936518642 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:48:38 INFO - 1500936518645 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
15:48:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500936518653 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:48:38 INFO - 1500936518655 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518660 Marionette TRACE 370 <- [1,7,null,{}]
15:48:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1059ms
15:48:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518668 Marionette DEBUG Closed connection 370
15:48:38 INFO - 1500936518673 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
15:48:38 INFO - 1500936518675 Marionette DEBUG Closed connection 371
15:48:38 INFO - 1500936518676 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
15:48:38 INFO - 1500936518681 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:38 INFO - 1500936518684 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:38 INFO - 1500936518707 Marionette DEBUG Register listener.js for window 12
15:48:38 INFO - 1500936518751 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3662abed-3abb-410c-8dd7-5d98d41c41e9","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:38 INFO - 1500936518758 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:48:38 INFO - 1500936518760 Marionette TRACE 372 <- [1,2,null,{}]
15:48:38 INFO - 1500936518762 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:38 INFO - 1500936518763 Marionette TRACE 372 <- [1,3,null,{}]
15:48:38 INFO - 1500936518765 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:38 INFO - 1500936518767 Marionette TRACE 372 <- [1,4,null,{}]
15:48:38 INFO - 1500936518769 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
15:48:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500936518778 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:48:38 INFO - 1500936518780 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518784 Marionette TRACE 372 <- [1,6,null,{}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518790 Marionette DEBUG Closed connection 372
15:48:38 INFO - 1500936518792 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
15:48:38 INFO - 1500936518793 Marionette DEBUG Closed connection 373
15:48:38 INFO - 1500936518795 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
15:48:38 INFO - 1500936518819 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:38 INFO - 1500936518821 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:38 INFO - 1500936518843 Marionette DEBUG Register listener.js for window 12
15:48:38 INFO - 1500936518855 Marionette TRACE 374 <- [1,1,null,{"sessionId":"03cb4a1b-473f-457c-b8f7-69fccc624ff4","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:38 INFO - 1500936518864 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518868 Marionette TRACE 374 <- [1,2,null,{}]
15:48:38 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:38 INFO - 1500936518891 Marionette DEBUG Closed connection 374
15:48:38 INFO - 1500936518893 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
15:48:38 INFO - 1500936518897 Marionette DEBUG Closed connection 375
15:48:38 INFO - 1500936518899 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
15:48:38 INFO - 1500936518901 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:38 INFO - 1500936518905 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:38 INFO - 1500936518927 Marionette DEBUG Register listener.js for window 12
15:48:38 INFO - 1500936518939 Marionette TRACE 376 <- [1,1,null,{"sessionId":"306a6c6c-430b-4b87-95b5-27f719614893","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:38 INFO - 1500936518949 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
15:48:38 INFO - 1500936518953 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518961 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - 1500936518962 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:38 INFO - ++DOMWINDOW == 26 (000000F971656800) [pid = 2736] [serial = 45] [outer = 000000F972405000]
15:48:39 INFO - 1500936519022 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:48:39 INFO - 1500936519027 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:48:39 INFO - 1500936519059 Marionette TRACE 376 <- [1,2,null,{}]
15:48:39 INFO - 1500936519069 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:48:39 INFO - 1500936519078 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"444093f2-552e-4e97-af17-ffbfff38ba41","ELEMENT":"444093f2-552e-4e97-af17-ffbfff38ba41"}}]
15:48:39 INFO - 1500936519080 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"444093f2-552e-4e97-af17-ffbfff38ba41"}]
15:48:39 INFO - 1500936519552 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:48:39 INFO - 1500936519554 Marionette TRACE 376 <- [1,4,null,{}]
15:48:39 INFO - 1500936519557 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
15:48:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500936519566 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:48:39 INFO - 1500936519568 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519572 Marionette TRACE 376 <- [1,6,null,{}]
15:48:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 912ms
15:48:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519578 Marionette DEBUG Closed connection 376
15:48:39 INFO - 1500936519580 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
15:48:39 INFO - 1500936519581 Marionette DEBUG Closed connection 377
15:48:39 INFO - 1500936519583 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
15:48:39 INFO - 1500936519585 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:39 INFO - 1500936519588 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:39 INFO - 1500936519610 Marionette DEBUG Register listener.js for window 12
15:48:39 INFO - 1500936519621 Marionette TRACE 378 <- [1,1,null,{"sessionId":"27b5c91b-d043-43e4-aa8d-ca274a2a98b2","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:39 INFO - 1500936519656 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:48:39 INFO - 1500936519658 Marionette TRACE 378 <- [1,2,null,{}]
15:48:39 INFO - 1500936519660 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:39 INFO - 1500936519662 Marionette TRACE 378 <- [1,3,null,{}]
15:48:39 INFO - 1500936519664 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:39 INFO - 1500936519666 Marionette TRACE 378 <- [1,4,null,{}]
15:48:39 INFO - 1500936519668 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
15:48:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500936519676 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:48:39 INFO - 1500936519678 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519682 Marionette TRACE 378 <- [1,6,null,{}]
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519688 Marionette DEBUG Closed connection 378
15:48:39 INFO - 1500936519690 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
15:48:39 INFO - 1500936519713 Marionette DEBUG Closed connection 379
15:48:39 INFO - 1500936519714 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
15:48:39 INFO - 1500936519717 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:39 INFO - 1500936519719 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:39 INFO - 1500936519741 Marionette DEBUG Register listener.js for window 12
15:48:39 INFO - 1500936519753 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c9d06909-3e6a-4522-b8bf-322208ab08cb","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:39 INFO - 1500936519764 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519768 Marionette TRACE 380 <- [1,2,null,{}]
15:48:39 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:39 INFO - 1500936519791 Marionette DEBUG Closed connection 380
15:48:39 INFO - 1500936519792 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
15:48:40 INFO - 1500936520402 Marionette DEBUG Closed connection 381
15:48:40 INFO - 1500936520404 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
15:48:40 INFO - 1500936520461 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:40 INFO - 1500936520465 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:40 INFO - 1500936520492 Marionette DEBUG Register listener.js for window 12
15:48:40 INFO - 1500936520506 Marionette TRACE 382 <- [1,1,null,{"sessionId":"63fead85-fdd9-45a9-80af-bd889415508a","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:40 INFO - 1500936520549 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:48:40 INFO - 1500936520553 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:48:40 INFO - 1500936520562 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:48:40 INFO - 1500936520563 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:48:40 INFO - ++DOMWINDOW == 27 (000000F975740800) [pid = 2736] [serial = 46] [outer = 000000F972405000]
15:48:40 INFO - 1500936520652 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:40 INFO - 1500936520658 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:40 INFO - 1500936520677 Marionette TRACE 382 <- [1,2,null,{}]
15:48:40 INFO - 1500936520693 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:48:40 INFO - 1500936520701 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a11cbd27-890a-47f7-b928-70674fc378ce","ELEMENT":"a11cbd27-890a-47f7-b928-70674fc378ce"}}]
15:48:40 INFO - 1500936520703 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a11cbd27-890a-47f7-b928-70674fc378ce"}]
15:48:41 INFO - 1500936521153 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:48:41 INFO - 1500936521156 Marionette TRACE 382 <- [1,4,null,{}]
15:48:41 INFO - 1500936521164 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
15:48:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500936521179 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:48:41 INFO - 1500936521185 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521196 Marionette TRACE 382 <- [1,6,null,{}]
15:48:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1624ms
15:48:41 INFO - TEST-START | test_click.py TestClick.test_input_file
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521206 Marionette DEBUG Closed connection 382
15:48:41 INFO - 1500936521208 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
15:48:41 INFO - 1500936521211 Marionette DEBUG Closed connection 383
15:48:41 INFO - 1500936521214 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
15:48:41 INFO - 1500936521219 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:41 INFO - 1500936521230 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:41 INFO - 1500936521275 Marionette DEBUG Register listener.js for window 12
15:48:41 INFO - 1500936521309 Marionette TRACE 384 <- [1,1,null,{"sessionId":"45ee63f2-e735-4070-850e-83870c1fe9bd","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:41 INFO - 1500936521347 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:48:41 INFO - 1500936521350 Marionette TRACE 384 <- [1,2,null,{}]
15:48:41 INFO - 1500936521353 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:41 INFO - 1500936521357 Marionette TRACE 384 <- [1,3,null,{}]
15:48:41 INFO - 1500936521410 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:41 INFO - 1500936521413 Marionette TRACE 384 <- [1,4,null,{}]
15:48:41 INFO - 1500936521417 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
15:48:41 INFO - TEST-START: test_click.py TestClick.test_input_file1500936521430 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:48:41 INFO - 1500936521434 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521440 Marionette TRACE 384 <- [1,6,null,{}]
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521452 Marionette DEBUG Closed connection 384
15:48:41 INFO - 1500936521455 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
15:48:41 INFO - 1500936521507 Marionette DEBUG Closed connection 385
15:48:41 INFO - 1500936521509 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
15:48:41 INFO - 1500936521531 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:41 INFO - 1500936521535 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:41 INFO - 1500936521580 Marionette DEBUG Register listener.js for window 12
15:48:41 INFO - 1500936521619 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4468532c-041d-422b-9199-5a52230b7535","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:41 INFO - 1500936521631 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521639 Marionette TRACE 386 <- [1,2,null,{}]
15:48:41 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:41 INFO - 1500936521677 Marionette DEBUG Closed connection 386
15:48:41 INFO - 1500936521679 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
15:48:41 INFO - 1500936521685 Marionette DEBUG Closed connection 387
15:48:41 INFO - 1500936521687 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
15:48:41 INFO - 1500936521695 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:41 INFO - 1500936521702 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:41 INFO - 1500936521747 Marionette DEBUG Register listener.js for window 12
15:48:41 INFO - 1500936521763 Marionette TRACE 388 <- [1,1,null,{"sessionId":"3f24276a-1c26-4448-8336-1606af527658","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:41 INFO - 1500936521782 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:48:41 INFO - 1500936521788 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:41 INFO - 1500936521798 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:41 INFO - 1500936521800 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:48:41 INFO - ++DOMWINDOW == 28 (000000F97163F800) [pid = 2736] [serial = 47] [outer = 000000F972405000]
15:48:41 INFO - 1500936521912 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:48:41 INFO - 1500936521919 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:48:41 INFO - 1500936521939 Marionette TRACE 388 <- [1,2,null,{}]
15:48:41 INFO - 1500936521944 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:48:41 INFO - 1500936521966 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3fcfdf82-4374-4896-84bf-d29f13a1f6e8","ELEMENT":"3fcfdf82-4374-4896-84bf-d29f13a1f6e8"}}]
15:48:41 INFO - 1500936521968 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3fcfdf82-4374-4896-84bf-d29f13a1f6e8"}]
15:48:42 INFO - 1500936522220 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:48:42 INFO - 1500936522244 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
15:48:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500936522255 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:48:42 INFO - 1500936522258 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522263 Marionette TRACE 388 <- [1,6,null,{}]
15:48:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1067ms
15:48:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522270 Marionette DEBUG Closed connection 388
15:48:42 INFO - 1500936522272 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
15:48:42 INFO - 1500936522295 Marionette DEBUG Closed connection 389
15:48:42 INFO - 1500936522297 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
15:48:42 INFO - 1500936522345 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:42 INFO - 1500936522348 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:42 INFO - 1500936522394 Marionette DEBUG Register listener.js for window 12
15:48:42 INFO - 1500936522409 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6d6811a0-512b-4c28-8789-117ac2f37ad2","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:42 INFO - 1500936522422 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:48:42 INFO - 1500936522425 Marionette TRACE 390 <- [1,2,null,{}]
15:48:42 INFO - 1500936522442 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:42 INFO - 1500936522444 Marionette TRACE 390 <- [1,3,null,{}]
15:48:42 INFO - 1500936522450 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:42 INFO - 1500936522452 Marionette TRACE 390 <- [1,4,null,{}]
15:48:42 INFO - 1500936522456 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
15:48:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500936522467 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:48:42 INFO - 1500936522469 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522474 Marionette TRACE 390 <- [1,6,null,{}]
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522481 Marionette DEBUG Closed connection 390
15:48:42 INFO - 1500936522483 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
15:48:42 INFO - 1500936522485 Marionette DEBUG Closed connection 391
15:48:42 INFO - 1500936522487 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
15:48:42 INFO - 1500936522490 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:42 INFO - 1500936522496 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:42 INFO - 1500936522542 Marionette DEBUG Register listener.js for window 12
15:48:42 INFO - 1500936522559 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ae4f7e9e-35f6-432b-9c99-bf4203291697","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:42 INFO - 1500936522729 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522740 Marionette TRACE 392 <- [1,2,null,{}]
15:48:42 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:42 INFO - 1500936522792 Marionette DEBUG Closed connection 392
15:48:42 INFO - 1500936522795 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
15:48:42 INFO - 1500936522821 Marionette DEBUG Closed connection 393
15:48:42 INFO - 1500936522824 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
15:48:42 INFO - 1500936522847 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:42 INFO - 1500936522858 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:42 INFO - 1500936522883 Marionette DEBUG Register listener.js for window 12
15:48:42 INFO - 1500936522903 Marionette TRACE 394 <- [1,1,null,{"sessionId":"78f1dce5-2b4f-4ab4-8506-0c18e3883517","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:42 INFO - 1500936522926 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
15:48:42 INFO - 1500936522934 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:48:42 INFO - 1500936522944 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:48:42 INFO - 1500936522945 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:48:42 INFO - ++DOMWINDOW == 29 (000000F96CA5D800) [pid = 2736] [serial = 48] [outer = 000000F972405000]
15:48:43 INFO - 1500936523022 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:48:43 INFO - 1500936523029 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:48:43 INFO - 1500936523049 Marionette TRACE 394 <- [1,2,null,{}]
15:48:43 INFO - 1500936523054 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:48:43 INFO - 1500936523067 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00548ed4-c8ca-42ee-b32d-688a6cfbd1ec","ELEMENT":"00548ed4-c8ca-42ee-b32d-688a6cfbd1ec"}}]
15:48:43 INFO - 1500936523070 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:48:43 INFO - 1500936523081 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a18436ba-2316-469c-9898-14307c94a986","ELEMENT":"a18436ba-2316-469c-9898-14307c94a986"}}]
15:48:43 INFO - 1500936523084 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"00548ed4-c8ca-42ee-b32d-688a6cfbd1ec"}]
15:48:43 INFO - 1500936523539 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:48:43 INFO - 1500936523542 Marionette TRACE 394 <- [1,5,null,{}]
15:48:43 INFO - 1500936523547 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a18436ba-2316-469c-9898-14307c94a986"}]
15:48:43 INFO - 1500936523685 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:48:43 INFO - 1500936523689 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:48:43 INFO - 1500936523700 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:48:43 INFO - 1500936523703 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
15:48:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500936523712 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:48:43 INFO - 1500936523716 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:48:43 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:43 INFO - 1500936523722 Marionette TRACE 394 <- [1,9,null,{}]
15:48:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1458ms
15:48:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:48:43 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:43 INFO - 1500936523729 Marionette DEBUG Closed connection 394
15:48:43 INFO - 1500936523732 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
15:48:43 INFO - 1500936523734 Marionette DEBUG Closed connection 395
15:48:43 INFO - 1500936523737 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
15:48:43 INFO - 1500936523793 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:43 INFO - 1500936523799 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:43 INFO - 1500936523824 Marionette DEBUG Register listener.js for window 12
15:48:43 INFO - 1500936523841 Marionette TRACE 396 <- [1,1,null,{"sessionId":"16c44c5c-3e66-44fe-83bf-d093a2821cec","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:43 INFO - 1500936523853 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:48:43 INFO - 1500936523855 Marionette TRACE 396 <- [1,2,null,{}]
15:48:43 INFO - 1500936523863 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:48:43 INFO - 1500936523865 Marionette TRACE 396 <- [1,3,null,{}]
15:48:43 INFO - --DOMWINDOW == 28 (000000F975749000) [pid = 2736] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:43 INFO - --DOMWINDOW == 27 (000000F97126D800) [pid = 2736] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:43 INFO - --DOMWINDOW == 26 (000000F967334800) [pid = 2736] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:43 INFO - --DOMWINDOW == 25 (000000F9796D1800) [pid = 2736] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
15:48:43 INFO - --DOMWINDOW == 24 (000000F97AD85000) [pid = 2736] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicks%20%3D%200%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicks%2B%2B%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20]
15:48:43 INFO - --DOMWINDOW == 23 (000000F97A586000) [pid = 2736] [serial = 35] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
15:48:43 INFO - --DOMWINDOW == 22 (000000F979799000) [pid = 2736] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
15:48:43 INFO - --DOMWINDOW == 21 (000000F976D85000) [pid = 2736] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
15:48:43 INFO - 1500936523873 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:48:43 INFO - 1500936523875 Marionette TRACE 396 <- [1,4,null,{}]
15:48:43 INFO - 1500936523884 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
15:48:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500936523894 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:48:43 INFO - 1500936523898 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:48:43 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:43 INFO - 1500936523904 Marionette TRACE 396 <- [1,6,null,{}]
15:48:43 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:43 INFO - 1500936523913 Marionette DEBUG Closed connection 396
15:48:43 INFO - 1500936523915 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
15:48:43 INFO - 1500936523937 Marionette DEBUG Closed connection 397
15:48:43 INFO - 1500936523939 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
15:48:43 INFO - 1500936523960 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:48:43 INFO - 1500936523963 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:43 INFO - 1500936523987 Marionette DEBUG Register listener.js for window 12
15:48:44 INFO - 1500936524003 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5dbd4513-fc17-4704-a2d0-0b9c9385bfd8","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:44 INFO - 1500936524130 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:48:44 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:44 INFO - 1500936524135 Marionette TRACE 398 <- [1,2,null,{}]
15:48:44 INFO - [Parent 2736] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:48:44 INFO - 1500936524162 Marionette DEBUG Closed connection 398
15:48:44 INFO - 1500936524164 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
15:48:44 INFO - 1500936524167 Marionette DEBUG Closed connection 399
15:48:44 INFO - 1500936524170 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
15:48:44 INFO - 1500936524175 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:48:44 INFO - 1500936524179 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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}
15:48:44 INFO - 1500936524203 Marionette DEBUG Register listener.js for window 12
15:48:44 INFO - 1500936524217 Marionette TRACE 400 <- [1,1,null,{"sessionId":"754e795d-04c9-4ed5-a328-a052a95a4ab6","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":2736,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-193.001\\AppData\\Local\\Temp\\tmp5yf0e6.mozrunner","moz:accessibilityChecks":false}}]
15:48:44 INFO - 1500936524226 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
15:48:44 INFO - 1500936524231 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:48:44 INFO - 1500936524240 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:48:44 INFO - 1500936524241 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:48:44 INFO - ++DOMWINDOW == 22 (000000F975733000) [pid = 2736] [serial = 49] [outer = 000000F972405000]
15:48:44 INFO - 1500936524312 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
15:48:44 INFO - 1500936524318 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
15:48:44 INFO - 1500936524330 Marionette TRACE 400 <- [1,2,null,{}]
15:48:44 INFO - 1500936524349 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:48:44 INFO - 1500936524358 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cd41e869-c7c4-45fc-a2d3-4615e6d813b5","ELEMENT":"cd41e869-c7c4-45fc-a2d3-4615e6d813b5"}}]
15:48:44 INFO - 1500936524360 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cd41e869-c7c4-45fc-a2d3-4615e6d813b5"}]
15:48:44 INFO - 1500936524500 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:48:44 INFO - 1500936524518 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"cd41e869-c7c4-45fc-a2d3-4615e6d813b5"}]
15:48:44 INFO - 1500936524659 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element