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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
19:18:18 INFO - 1499393898232 Marionette TRACE 353 -> [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}]
19:18:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499393898242 Marionette TRACE 353 <- [1,5,null,{"value":null}]
19:18:18 INFO - 1499393898244 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898250 Marionette TRACE 353 <- [1,6,null,{}]
19:18:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1114ms
19:18:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898257 Marionette DEBUG Closed connection 353
19:18:18 INFO - 1499393898260 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49783
19:18:18 INFO - 1499393898261 Marionette DEBUG Closed connection 354
19:18:18 INFO - 1499393898264 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49784
19:18:18 INFO - 1499393898267 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:18 INFO - 1499393898270 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:18 INFO - 1499393898316 Marionette DEBUG Register listener.js for window 12
19:18:18 INFO - 1499393898357 Marionette TRACE 355 <- [1,1,null,{"sessionId":"d3493dbb-2a87-422f-94bc-5502ab9d2adc","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:18 INFO - 1499393898395 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
19:18:18 INFO - 1499393898398 Marionette TRACE 355 <- [1,2,null,{}]
19:18:18 INFO - 1499393898400 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:18 INFO - 1499393898403 Marionette TRACE 355 <- [1,3,null,{}]
19:18:18 INFO - 1499393898455 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:18 INFO - 1499393898457 Marionette TRACE 355 <- [1,4,null,{}]
19:18:18 INFO - 1499393898460 Marionette TRACE 355 -> [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}]
19:18:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499393898470 Marionette TRACE 355 <- [1,5,null,{"value":null}]
19:18:18 INFO - 1499393898473 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898478 Marionette TRACE 355 <- [1,6,null,{}]
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898505 Marionette DEBUG Closed connection 355
19:18:18 INFO - 1499393898507 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49785
19:18:18 INFO - 1499393898521 Marionette DEBUG Closed connection 356
19:18:18 INFO - 1499393898524 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49786
19:18:18 INFO - 1499393898526 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:18 INFO - 1499393898529 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:18 INFO - 1499393898574 Marionette DEBUG Register listener.js for window 12
19:18:18 INFO - 1499393898593 Marionette TRACE 357 <- [1,1,null,{"sessionId":"4d1137c6-094c-4b2b-8784-f83d7b3d135e","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:18 INFO - 1499393898605 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898611 Marionette TRACE 357 <- [1,2,null,{}]
19:18:18 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:18 INFO - 1499393898626 Marionette DEBUG Closed connection 357
19:18:18 INFO - 1499393898632 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49787
19:18:18 INFO - 1499393898634 Marionette DEBUG Closed connection 358
19:18:18 INFO - 1499393898636 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
19:18:18 INFO - 1499393898639 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:18 INFO - 1499393898644 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:18 INFO - 1499393898689 Marionette DEBUG Register listener.js for window 12
19:18:18 INFO - 1499393898705 Marionette TRACE 359 <- [1,1,null,{"sessionId":"1f0b23fd-c9b0-44b3-ac82-2c21655e05de","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:18 INFO - 1499393898731 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
19:18:18 INFO - 1499393898757 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
19:18:18 INFO - 1499393898782 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
19:18:18 INFO - 1499393898783 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
19:18:18 INFO - ++DOMWINDOW == 29 (0000003D7C11E800) [pid = 2944] [serial = 44] [outer = 0000003D75ABC800]
19:18:18 INFO - 1499393898858 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
19:18:18 INFO - 1499393898863 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
19:18:18 INFO - 1499393898884 Marionette TRACE 359 <- [1,2,null,{}]
19:18:18 INFO - 1499393898887 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:18:18 INFO - 1499393898902 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4555ca7f-c87e-4a16-aa94-682f413249db","ELEMENT":"4555ca7f-c87e-4a16-aa94-682f413249db"}}]
19:18:18 INFO - 1499393898904 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"4555ca7f-c87e-4a16-aa94-682f413249db"}]
19:18:19 INFO - 1499393899292 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
19:18:19 INFO - [2944] 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
19:18:19 INFO - 1499393899311 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
19:18:19 INFO - 1499393899314 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
19:18:19 INFO - ++DOMWINDOW == 30 (0000003D7EB7C800) [pid = 2944] [serial = 45] [outer = 0000003D75ABC800]
19:18:19 INFO - 1499393899386 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
19:18:19 INFO - 1499393899392 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
19:18:19 INFO - 1499393899409 Marionette TRACE 359 <- [1,4,null,{}]
19:18:19 INFO - 1499393899457 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:18:19 INFO - 1499393899466 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"15ef4fc2-373c-47be-9c97-b84e5ebcb9a0","ELEMENT":"15ef4fc2-373c-47be-9c97-b84e5ebcb9a0"}}]
19:18:19 INFO - 1499393899468 Marionette TRACE 359 -> [0,6,"getTitle",{}]
19:18:19 INFO - 1499393899470 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
19:18:19 INFO - 1499393899473 Marionette TRACE 359 -> [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}]
19:18:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499393899482 Marionette TRACE 359 <- [1,7,null,{"value":null}]
19:18:19 INFO - 1499393899484 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
19:18:19 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:19 INFO - 1499393899489 Marionette TRACE 359 <- [1,8,null,{}]
19:18:19 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:19 INFO - 1499393899496 Marionette DEBUG Closed connection 359
19:18:19 INFO - --DOMWINDOW == 29 (0000003D7618D000) [pid = 2944] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:19 INFO - --DOMWINDOW == 28 (0000003D69210800) [pid = 2944] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:19 INFO - --DOMWINDOW == 27 (0000003D756E9000) [pid = 2944] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:19 INFO - --DOMWINDOW == 26 (0000003D79911800) [pid = 2944] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:19 INFO - --DOMWINDOW == 25 (0000003D69CBD000) [pid = 2944] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1239ms
19:18:20 INFO - TEST-START | test_click.py TestClick.test_container_element
19:18:21 INFO - 1499393901086 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49791
19:18:21 INFO - 1499393901098 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49792
19:18:21 INFO - 1499393901100 Marionette DEBUG Closed connection 360
19:18:21 INFO - 1499393901149 Marionette DEBUG Closed connection 361
19:18:21 INFO - 1499393901152 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49793
19:18:21 INFO - 1499393901212 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:21 INFO - 1499393901220 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:21 INFO - 1499393901244 Marionette DEBUG Register listener.js for window 12
19:18:21 INFO - 1499393901265 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5c629deb-a144-48d1-b060-08cffca38685","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:21 INFO - 1499393901320 Marionette TRACE 362 -> [0,2,"setTimeouts",{"script":30000}]
19:18:21 INFO - 1499393901325 Marionette TRACE 362 <- [1,2,null,{}]
19:18:21 INFO - 1499393901381 Marionette TRACE 362 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:21 INFO - 1499393901384 Marionette TRACE 362 <- [1,3,null,{}]
19:18:21 INFO - 1499393901425 Marionette TRACE 362 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:21 INFO - 1499393901428 Marionette TRACE 362 <- [1,4,null,{}]
19:18:21 INFO - 1499393901447 Marionette TRACE 362 -> [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}]
19:18:21 INFO - TEST-START: test_click.py TestClick.test_container_element1499393901459 Marionette TRACE 362 <- [1,5,null,{"value":null}]
19:18:21 INFO - 1499393901462 Marionette TRACE 362 -> [0,6,"deleteSession",{}]
19:18:21 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:21 INFO - 1499393901468 Marionette TRACE 362 <- [1,6,null,{}]
19:18:21 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:21 INFO - 1499393901495 Marionette DEBUG Closed connection 362
19:18:21 INFO - 1499393901498 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49794
19:18:21 INFO - 1499393901503 Marionette DEBUG Closed connection 363
19:18:21 INFO - 1499393901505 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49795
19:18:21 INFO - 1499393901510 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:21 INFO - 1499393901515 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:21 INFO - 1499393901538 Marionette DEBUG Register listener.js for window 12
19:18:21 INFO - 1499393901556 Marionette TRACE 364 <- [1,1,null,{"sessionId":"06648b4a-a479-41f9-9089-ce01c34a4d70","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:21 INFO - 1499393901563 Marionette TRACE 364 -> [0,2,"deleteSession",{}]
19:18:21 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:21 INFO - 1499393901568 Marionette TRACE 364 <- [1,2,null,{}]
19:18:21 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:21 INFO - 1499393901592 Marionette DEBUG Closed connection 364
19:18:21 INFO - 1499393901594 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49796
19:18:21 INFO - 1499393901599 Marionette DEBUG Closed connection 365
19:18:21 INFO - 1499393901601 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49797
19:18:21 INFO - 1499393901603 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:21 INFO - 1499393901607 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:21 INFO - 1499393901631 Marionette DEBUG Register listener.js for window 12
19:18:21 INFO - 1499393901647 Marionette TRACE 366 <- [1,1,null,{"sessionId":"cde50a8c-8f10-4650-927d-bbb1296cfefb","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:21 INFO - 1499393901654 Marionette TRACE 366 -> [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"}]
19:18:21 INFO - 1499393901660 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
19:18:21 INFO - 1499393901669 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
19:18:21 INFO - 1499393901670 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
19:18:21 INFO - ++DOMWINDOW == 26 (0000003D68EAC000) [pid = 2944] [serial = 46] [outer = 0000003D75ABC800]
19:18:21 INFO - 1499393901724 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"
19:18:21 INFO - 1499393901744 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"
19:18:21 INFO - 1499393901761 Marionette TRACE 366 <- [1,2,null,{}]
19:18:21 INFO - 1499393901771 Marionette TRACE 366 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:18:21 INFO - 1499393901785 Marionette TRACE 366 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"658ea387-54a0-4007-aeba-90c42ecf70a7","ELEMENT":"658ea387-54a0-4007-aeba-90c42ecf70a7"}}]
19:18:21 INFO - 1499393901787 Marionette TRACE 366 -> [0,4,"clickElement",{"id":"658ea387-54a0-4007-aeba-90c42ecf70a7"}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
19:18:22 INFO - 1499393902254 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:18:22 INFO - 1499393902257 Marionette TRACE 366 <- [1,4,null,{}]
19:18:22 INFO - 1499393902259 Marionette TRACE 366 -> [0,5,"getElementProperty",{"id":"658ea387-54a0-4007-aeba-90c42ecf70a7","name":"selected"}]
19:18:22 INFO - 1499393902289 Marionette TRACE 366 <- [1,5,null,{"value":true}]
19:18:22 INFO - 1499393902292 Marionette TRACE 366 -> [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}]
19:18:22 INFO - TEST-END: test_click.py TestClick.test_container_element1499393902301 Marionette TRACE 366 <- [1,6,null,{"value":null}]
19:18:22 INFO - 1499393902308 Marionette TRACE 366 -> [0,7,"deleteSession",{}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902313 Marionette TRACE 366 <- [1,7,null,{}]
19:18:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1970ms
19:18:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902320 Marionette DEBUG Closed connection 366
19:18:22 INFO - 1499393902322 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49798
19:18:22 INFO - 1499393902326 Marionette DEBUG Closed connection 367
19:18:22 INFO - 1499393902328 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49799
19:18:22 INFO - 1499393902359 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:22 INFO - 1499393902361 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:22 INFO - 1499393902386 Marionette DEBUG Register listener.js for window 12
19:18:22 INFO - 1499393902401 Marionette TRACE 368 <- [1,1,null,{"sessionId":"65009019-cab9-4589-8c1c-2b38eda0eeb1","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:22 INFO - 1499393902405 Marionette TRACE 368 -> [0,2,"setTimeouts",{"script":30000}]
19:18:22 INFO - 1499393902407 Marionette TRACE 368 <- [1,2,null,{}]
19:18:22 INFO - 1499393902409 Marionette TRACE 368 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:22 INFO - 1499393902411 Marionette TRACE 368 <- [1,3,null,{}]
19:18:22 INFO - 1499393902413 Marionette TRACE 368 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:22 INFO - 1499393902415 Marionette TRACE 368 <- [1,4,null,{}]
19:18:22 INFO - 1499393902418 Marionette TRACE 368 -> [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}]
19:18:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499393902428 Marionette TRACE 368 <- [1,5,null,{"value":null}]
19:18:22 INFO - 1499393902430 Marionette TRACE 368 -> [0,6,"deleteSession",{}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902435 Marionette TRACE 368 <- [1,6,null,{}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902441 Marionette DEBUG Closed connection 368
19:18:22 INFO - 1499393902443 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49800
19:18:22 INFO - 1499393902444 Marionette DEBUG Closed connection 369
19:18:22 INFO - 1499393902449 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49801
19:18:22 INFO - 1499393902452 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:22 INFO - 1499393902454 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:22 INFO - 1499393902478 Marionette DEBUG Register listener.js for window 12
19:18:22 INFO - 1499393902494 Marionette TRACE 370 <- [1,1,null,{"sessionId":"35185464-0706-4137-bb69-6dcdcacffad9","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:22 INFO - 1499393902497 Marionette TRACE 370 -> [0,2,"deleteSession",{}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902502 Marionette TRACE 370 <- [1,2,null,{}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:22 INFO - 1499393902510 Marionette DEBUG Closed connection 370
19:18:22 INFO - 1499393902511 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49802
19:18:22 INFO - 1499393902513 Marionette DEBUG Closed connection 371
19:18:22 INFO - 1499393902514 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49803
19:18:22 INFO - 1499393902517 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:22 INFO - 1499393902521 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:22 INFO - 1499393902545 Marionette DEBUG Register listener.js for window 12
19:18:22 INFO - 1499393902559 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ab93fd07-88f8-458a-ab57-1d97041bff31","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:22 INFO - 1499393902562 Marionette TRACE 372 -> [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"}]
19:18:22 INFO - 1499393902566 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"
19:18:22 INFO - 1499393902574 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"
19:18:22 INFO - 1499393902575 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"
19:18:22 INFO - ++DOMWINDOW == 27 (0000003D6B542800) [pid = 2944] [serial = 47] [outer = 0000003D75ABC800]
19:18:22 INFO - 1499393902636 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"
19:18:22 INFO - 1499393902642 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"
19:18:22 INFO - 1499393902661 Marionette TRACE 372 <- [1,2,null,{}]
19:18:22 INFO - 1499393902740 Marionette TRACE 372 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:18:22 INFO - 1499393902747 Marionette TRACE 372 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ab47d98-da68-4078-975b-aba8c0b037bc","ELEMENT":"9ab47d98-da68-4078-975b-aba8c0b037bc"}}]
19:18:22 INFO - 1499393902749 Marionette TRACE 372 -> [0,4,"clickElement",{"id":"9ab47d98-da68-4078-975b-aba8c0b037bc"}]
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
19:18:22 INFO - [2944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
19:18:23 INFO - 1499393903191 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:18:23 INFO - 1499393903193 Marionette TRACE 372 <- [1,4,null,{}]
19:18:23 INFO - 1499393903196 Marionette TRACE 372 -> [0,5,"getElementProperty",{"id":"9ab47d98-da68-4078-975b-aba8c0b037bc","name":"selected"}]
19:18:23 INFO - 1499393903228 Marionette TRACE 372 <- [1,5,null,{"value":true}]
19:18:23 INFO - 1499393903238 Marionette TRACE 372 -> [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}]
19:18:23 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499393903246 Marionette TRACE 372 <- [1,6,null,{"value":null}]
19:18:23 INFO - 1499393903248 Marionette TRACE 372 -> [0,7,"deleteSession",{}]
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903253 Marionette TRACE 372 <- [1,7,null,{}]
19:18:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 939ms
19:18:23 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903259 Marionette DEBUG Closed connection 372
19:18:23 INFO - 1499393903261 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49804
19:18:23 INFO - 1499393903262 Marionette DEBUG Closed connection 373
19:18:23 INFO - 1499393903264 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49805
19:18:23 INFO - 1499393903266 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:23 INFO - 1499393903268 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:23 INFO - 1499393903292 Marionette DEBUG Register listener.js for window 12
19:18:23 INFO - 1499393903305 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a6ff565f-45a5-4731-8931-65f4d6f9fc1e","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:23 INFO - 1499393903331 Marionette TRACE 374 -> [0,2,"setTimeouts",{"script":30000}]
19:18:23 INFO - 1499393903333 Marionette TRACE 374 <- [1,2,null,{}]
19:18:23 INFO - 1499393903336 Marionette TRACE 374 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:23 INFO - 1499393903337 Marionette TRACE 374 <- [1,3,null,{}]
19:18:23 INFO - 1499393903339 Marionette TRACE 374 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:23 INFO - 1499393903340 Marionette TRACE 374 <- [1,4,null,{}]
19:18:23 INFO - 1499393903343 Marionette TRACE 374 -> [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}]
19:18:23 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499393903351 Marionette TRACE 374 <- [1,5,null,{"value":null}]
19:18:23 INFO - 1499393903353 Marionette TRACE 374 -> [0,6,"deleteSession",{}]
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903357 Marionette TRACE 374 <- [1,6,null,{}]
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903363 Marionette DEBUG Closed connection 374
19:18:23 INFO - 1499393903365 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49806
19:18:23 INFO - 1499393903366 Marionette DEBUG Closed connection 375
19:18:23 INFO - 1499393903368 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49807
19:18:23 INFO - 1499393903370 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:23 INFO - 1499393903372 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:23 INFO - 1499393903396 Marionette DEBUG Register listener.js for window 12
19:18:23 INFO - 1499393903408 Marionette TRACE 376 <- [1,1,null,{"sessionId":"22bac46e-7ce9-43d0-a43f-ea77c6d5f578","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:23 INFO - 1499393903435 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903439 Marionette TRACE 376 <- [1,2,null,{}]
19:18:23 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:23 INFO - 1499393903461 Marionette DEBUG Closed connection 376
19:18:23 INFO - 1499393903463 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49808
19:18:23 INFO - 1499393903468 Marionette DEBUG Closed connection 377
19:18:23 INFO - 1499393903469 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49809
19:18:23 INFO - 1499393903472 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:23 INFO - 1499393903475 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:23 INFO - 1499393903499 Marionette DEBUG Register listener.js for window 12
19:18:23 INFO - 1499393903511 Marionette TRACE 378 <- [1,1,null,{"sessionId":"80df494d-2479-4ac5-9383-210261975862","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:23 INFO - 1499393903518 Marionette TRACE 378 -> [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"}]
19:18:23 INFO - 1499393903523 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"
19:18:23 INFO - 1499393903530 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"
19:18:23 INFO - 1499393903530 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"
19:18:23 INFO - ++DOMWINDOW == 28 (0000003D74A6B800) [pid = 2944] [serial = 48] [outer = 0000003D75ABC800]
19:18:23 INFO - 1499393903601 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"
19:18:23 INFO - 1499393903608 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"
19:18:23 INFO - 1499393903625 Marionette TRACE 378 <- [1,2,null,{}]
19:18:23 INFO - 1499393903633 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:18:23 INFO - 1499393903641 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edf956f5-769a-44ef-8f24-784fc627b0b9","ELEMENT":"edf956f5-769a-44ef-8f24-784fc627b0b9"}}]
19:18:23 INFO - 1499393903643 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"edf956f5-769a-44ef-8f24-784fc627b0b9"}]
19:18:24 INFO - 1499393904588 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:18:24 INFO - 1499393904592 Marionette TRACE 378 <- [1,4,null,{}]
19:18:24 INFO - 1499393904630 Marionette TRACE 378 -> [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}]
19:18:24 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499393904639 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:18:24 INFO - 1499393904642 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:18:24 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:24 INFO - 1499393904646 Marionette TRACE 378 <- [1,6,null,{}]
19:18:24 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1392ms
19:18:24 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:18:24 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:24 INFO - 1499393904652 Marionette DEBUG Closed connection 378
19:18:24 INFO - 1499393904654 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49810
19:18:24 INFO - 1499393904655 Marionette DEBUG Closed connection 379
19:18:24 INFO - 1499393904657 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49811
19:18:24 INFO - 1499393904659 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:24 INFO - 1499393904661 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:24 INFO - 1499393904685 Marionette DEBUG Register listener.js for window 12
19:18:24 INFO - 1499393904739 Marionette TRACE 380 <- [1,1,null,{"sessionId":"62c84030-e3b1-4fa5-8604-c7164d9853eb","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:24 INFO - 1499393904837 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
19:18:24 INFO - 1499393904842 Marionette TRACE 380 <- [1,2,null,{}]
19:18:24 INFO - 1499393904847 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:24 INFO - 1499393904852 Marionette TRACE 380 <- [1,3,null,{}]
19:18:24 INFO - 1499393904857 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:24 INFO - 1499393904862 Marionette TRACE 380 <- [1,4,null,{}]
19:18:24 INFO - 1499393904866 Marionette TRACE 380 -> [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}]
19:18:24 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499393904880 Marionette TRACE 380 <- [1,5,null,{"value":null}]
19:18:24 INFO - 1499393904883 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
19:18:24 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:24 INFO - 1499393904891 Marionette TRACE 380 <- [1,6,null,{}]
19:18:24 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:24 INFO - 1499393904903 Marionette DEBUG Closed connection 380
19:18:24 INFO - 1499393904907 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49812
19:18:24 INFO - 1499393904932 Marionette DEBUG Closed connection 381
19:18:24 INFO - 1499393904934 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49813
19:18:24 INFO - 1499393904955 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:24 INFO - 1499393904964 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:25 INFO - 1499393905012 Marionette DEBUG Register listener.js for window 12
19:18:25 INFO - 1499393905036 Marionette TRACE 382 <- [1,1,null,{"sessionId":"17f4ca8e-597e-46bd-a3ea-531bf8233287","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:25 INFO - 1499393905044 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
19:18:25 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:25 INFO - 1499393905051 Marionette TRACE 382 <- [1,2,null,{}]
19:18:25 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:25 INFO - 1499393905108 Marionette DEBUG Closed connection 382
19:18:25 INFO - 1499393905112 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49814
19:18:25 INFO - 1499393905116 Marionette DEBUG Closed connection 383
19:18:25 INFO - 1499393905118 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49815
19:18:25 INFO - 1499393905141 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:25 INFO - 1499393905146 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:25 INFO - 1499393905193 Marionette DEBUG Register listener.js for window 12
19:18:25 INFO - 1499393905213 Marionette TRACE 384 <- [1,1,null,{"sessionId":"eedad122-153d-425f-9a31-24f11908611e","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:25 INFO - 1499393905220 Marionette TRACE 384 -> [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"}]
19:18:25 INFO - 1499393905227 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"
19:18:25 INFO - 1499393905238 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"
19:18:25 INFO - 1499393905239 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"
19:18:25 INFO - ++DOMWINDOW == 29 (0000003D696E3000) [pid = 2944] [serial = 49] [outer = 0000003D75ABC800]
19:18:25 INFO - 1499393905318 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"
19:18:25 INFO - 1499393905325 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"
19:18:25 INFO - 1499393905348 Marionette TRACE 384 <- [1,2,null,{}]
19:18:25 INFO - 1499393905355 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:18:25 INFO - 1499393905366 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e1e2c30-8d37-4a62-af05-e3864918238a","ELEMENT":"2e1e2c30-8d37-4a62-af05-e3864918238a"}}]
19:18:25 INFO - 1499393905373 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"2e1e2c30-8d37-4a62-af05-e3864918238a"}]
19:18:25 INFO - 1499393905995 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:18:26 INFO - 1499393905999 Marionette TRACE 384 <- [1,4,null,{}]
19:18:26 INFO - 1499393906003 Marionette TRACE 384 -> [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}]
19:18:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499393906017 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:18:26 INFO - 1499393906020 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906026 Marionette TRACE 384 <- [1,6,null,{}]
19:18:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1381ms
19:18:26 INFO - TEST-START | test_click.py TestClick.test_input_file
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906051 Marionette DEBUG Closed connection 384
19:18:26 INFO - 1499393906054 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49816
19:18:26 INFO - 1499393906056 Marionette DEBUG Closed connection 385
19:18:26 INFO - 1499393906059 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49817
19:18:26 INFO - 1499393906061 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:26 INFO - 1499393906065 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:26 INFO - 1499393906113 Marionette DEBUG Register listener.js for window 12
19:18:26 INFO - 1499393906140 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d838cb59-8dd8-4976-a190-3abc89e55ba5","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:26 INFO - 1499393906149 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
19:18:26 INFO - 1499393906152 Marionette TRACE 386 <- [1,2,null,{}]
19:18:26 INFO - 1499393906154 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:26 INFO - 1499393906156 Marionette TRACE 386 <- [1,3,null,{}]
19:18:26 INFO - 1499393906207 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:26 INFO - 1499393906210 Marionette TRACE 386 <- [1,4,null,{}]
19:18:26 INFO - 1499393906213 Marionette TRACE 386 -> [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}]
19:18:26 INFO - TEST-START: test_click.py TestClick.test_input_file1499393906223 Marionette TRACE 386 <- [1,5,null,{"value":null}]
19:18:26 INFO - 1499393906227 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906232 Marionette TRACE 386 <- [1,6,null,{}]
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906266 Marionette DEBUG Closed connection 386
19:18:26 INFO - 1499393906269 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49818
19:18:26 INFO - 1499393906313 Marionette DEBUG Closed connection 387
19:18:26 INFO - 1499393906316 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49819
19:18:26 INFO - 1499393906338 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:26 INFO - 1499393906341 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:26 INFO - 1499393906390 Marionette DEBUG Register listener.js for window 12
19:18:26 INFO - 1499393906408 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ffdefd6a-5828-4a92-856e-4e403140de56","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:26 INFO - 1499393906414 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906420 Marionette TRACE 388 <- [1,2,null,{}]
19:18:26 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:26 INFO - 1499393906604 Marionette DEBUG Closed connection 388
19:18:26 INFO - 1499393906607 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49820
19:18:26 INFO - 1499393906629 Marionette DEBUG Closed connection 389
19:18:26 INFO - 1499393906632 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49821
19:18:26 INFO - 1499393906637 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:26 INFO - 1499393906649 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:26 INFO - 1499393906677 Marionette DEBUG Register listener.js for window 12
19:18:26 INFO - 1499393906699 Marionette TRACE 390 <- [1,1,null,{"sessionId":"3f3c72c7-265c-44fb-b3e4-2f88b171ea68","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:26 INFO - 1499393906735 Marionette TRACE 390 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:18:26 INFO - 1499393906742 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"
19:18:26 INFO - 1499393906753 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"
19:18:26 INFO - 1499393906756 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"
19:18:26 INFO - ++DOMWINDOW == 30 (0000003D68E3A000) [pid = 2944] [serial = 50] [outer = 0000003D75ABC800]
19:18:26 INFO - 1499393906818 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:18:26 INFO - 1499393906825 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:18:26 INFO - 1499393906844 Marionette TRACE 390 <- [1,2,null,{}]
19:18:26 INFO - 1499393906859 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:18:26 INFO - 1499393906871 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ae7f66b-73f5-4180-b893-c4dfffd8fbf0","ELEMENT":"9ae7f66b-73f5-4180-b893-c4dfffd8fbf0"}}]
19:18:26 INFO - 1499393906875 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"9ae7f66b-73f5-4180-b893-c4dfffd8fbf0"}]
19:18:27 INFO - 1499393907056 Marionette TRACE 390 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
19:18:27 INFO - 1499393907072 Marionette TRACE 390 -> [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}]
19:18:27 INFO - TEST-END: test_click.py TestClick.test_input_file1499393907088 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:18:27 INFO - 1499393907094 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907100 Marionette TRACE 390 <- [1,6,null,{}]
19:18:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1077ms
19:18:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907114 Marionette DEBUG Closed connection 390
19:18:27 INFO - 1499393907117 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49822
19:18:27 INFO - 1499393907127 Marionette DEBUG Closed connection 391
19:18:27 INFO - 1499393907129 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49823
19:18:27 INFO - 1499393907149 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:27 INFO - 1499393907153 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:27 INFO - 1499393907180 Marionette DEBUG Register listener.js for window 12
19:18:27 INFO - 1499393907215 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e5f56566-8c92-45df-a43f-0f5ac905bb20","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:27 INFO - 1499393907220 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
19:18:27 INFO - 1499393907222 Marionette TRACE 392 <- [1,2,null,{}]
19:18:27 INFO - 1499393907263 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:27 INFO - 1499393907265 Marionette TRACE 392 <- [1,3,null,{}]
19:18:27 INFO - 1499393907268 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:27 INFO - 1499393907270 Marionette TRACE 392 <- [1,4,null,{}]
19:18:27 INFO - 1499393907298 Marionette TRACE 392 -> [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}]
19:18:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499393907312 Marionette TRACE 392 <- [1,5,null,{"value":null}]
19:18:27 INFO - 1499393907317 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907323 Marionette TRACE 392 <- [1,6,null,{}]
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907333 Marionette DEBUG Closed connection 392
19:18:27 INFO - 1499393907335 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49824
19:18:27 INFO - 1499393907357 Marionette DEBUG Closed connection 393
19:18:27 INFO - 1499393907359 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49825
19:18:27 INFO - 1499393907362 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:27 INFO - 1499393907366 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:27 INFO - 1499393907390 Marionette DEBUG Register listener.js for window 12
19:18:27 INFO - 1499393907405 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d29593c8-54ba-4dbf-90e3-af94e1f4bbbe","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:27 INFO - 1499393907412 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907418 Marionette TRACE 394 <- [1,2,null,{}]
19:18:27 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:27 INFO - 1499393907425 Marionette DEBUG Closed connection 394
19:18:27 INFO - 1499393907427 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49826
19:18:27 INFO - 1499393907451 Marionette DEBUG Closed connection 395
19:18:27 INFO - 1499393907453 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49827
19:18:27 INFO - 1499393907456 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:27 INFO - 1499393907459 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:27 INFO - 1499393907484 Marionette DEBUG Register listener.js for window 12
19:18:27 INFO - 1499393907499 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c148d437-a3f2-4f04-ace4-fc1fc93722aa","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:27 INFO - 1499393907506 Marionette TRACE 396 -> [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"}]
19:18:27 INFO - 1499393907511 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:18:27 INFO - 1499393907520 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:18:27 INFO - 1499393907521 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:18:27 INFO - ++DOMWINDOW == 31 (0000003D00EF9800) [pid = 2944] [serial = 51] [outer = 0000003D75ABC800]
19:18:27 INFO - 1499393907591 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"
19:18:27 INFO - 1499393907597 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"
19:18:27 INFO - 1499393907615 Marionette TRACE 396 <- [1,2,null,{}]
19:18:27 INFO - 1499393907617 Marionette TRACE 396 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:18:27 INFO - 1499393907628 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c12506f2-28e5-4be8-ae92-9a700dca4d3b","ELEMENT":"c12506f2-28e5-4be8-ae92-9a700dca4d3b"}}]
19:18:27 INFO - 1499393907630 Marionette TRACE 396 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:18:27 INFO - 1499393907637 Marionette TRACE 396 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dee0db1a-8f52-4f44-98ba-5a6d090cd748","ELEMENT":"dee0db1a-8f52-4f44-98ba-5a6d090cd748"}}]
19:18:27 INFO - 1499393907640 Marionette TRACE 396 -> [0,5,"clickElement",{"id":"c12506f2-28e5-4be8-ae92-9a700dca4d3b"}]
19:18:27 INFO - --DOMWINDOW == 30 (0000003D7D154800) [pid = 2944] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:27 INFO - --DOMWINDOW == 29 (0000003D71D95000) [pid = 2944] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:27 INFO - --DOMWINDOW == 28 (0000003D7250F800) [pid = 2944] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:27 INFO - --DOMWINDOW == 27 (0000003D7C111800) [pid = 2944] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
19:18:27 INFO - --DOMWINDOW == 26 (0000003D7F0D3800) [pid = 2944] [serial = 39] [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]
19:18:27 INFO - --DOMWINDOW == 25 (0000003D7E116800) [pid = 2944] [serial = 38] [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]
19:18:27 INFO - --DOMWINDOW == 24 (0000003D7EF77000) [pid = 2944] [serial = 37] [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]
19:18:27 INFO - --DOMWINDOW == 23 (0000003D7D857800) [pid = 2944] [serial = 36] [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]
19:18:28 INFO - 1499393908108 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:18:28 INFO - 1499393908110 Marionette TRACE 396 <- [1,5,null,{}]
19:18:28 INFO - 1499393908113 Marionette TRACE 396 -> [0,6,"clickElement",{"id":"dee0db1a-8f52-4f44-98ba-5a6d090cd748"}]
19:18:28 INFO - 1499393908253 Marionette TRACE 396 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
19:18:28 INFO - 1499393908259 Marionette TRACE 396 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:18:28 INFO - 1499393908271 Marionette TRACE 396 <- [1,7,null,{"value":false}]
19:18:28 INFO - 1499393908274 Marionette TRACE 396 -> [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}]
19:18:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499393908282 Marionette TRACE 396 <- [1,8,null,{"value":null}]
19:18:28 INFO - 1499393908284 Marionette TRACE 396 -> [0,9,"deleteSession",{}]
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908289 Marionette TRACE 396 <- [1,9,null,{}]
19:18:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1184ms
19:18:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908295 Marionette DEBUG Closed connection 396
19:18:28 INFO - 1499393908297 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49828
19:18:28 INFO - 1499393908299 Marionette DEBUG Closed connection 397
19:18:28 INFO - 1499393908301 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49829
19:18:28 INFO - 1499393908303 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:28 INFO - 1499393908306 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:28 INFO - 1499393908331 Marionette DEBUG Register listener.js for window 12
19:18:28 INFO - 1499393908347 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7013479c-5a6f-44f3-81a4-41cb1abb50f9","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:28 INFO - 1499393908350 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
19:18:28 INFO - 1499393908351 Marionette TRACE 398 <- [1,2,null,{}]
19:18:28 INFO - 1499393908353 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:18:28 INFO - 1499393908355 Marionette TRACE 398 <- [1,3,null,{}]
19:18:28 INFO - 1499393908357 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
19:18:28 INFO - 1499393908358 Marionette TRACE 398 <- [1,4,null,{}]
19:18:28 INFO - 1499393908361 Marionette TRACE 398 -> [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}]
19:18:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499393908370 Marionette TRACE 398 <- [1,5,null,{"value":null}]
19:18:28 INFO - 1499393908372 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908376 Marionette TRACE 398 <- [1,6,null,{}]
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908385 Marionette DEBUG Closed connection 398
19:18:28 INFO - 1499393908390 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49830
19:18:28 INFO - 1499393908392 Marionette DEBUG Closed connection 399
19:18:28 INFO - 1499393908394 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49831
19:18:28 INFO - 1499393908396 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:18:28 INFO - 1499393908400 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:28 INFO - 1499393908425 Marionette DEBUG Register listener.js for window 12
19:18:28 INFO - 1499393908440 Marionette TRACE 400 <- [1,1,null,{"sessionId":"72bbe851-51c0-48c4-8bb7-86e0168fb2b3","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:28 INFO - 1499393908442 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908447 Marionette TRACE 400 <- [1,2,null,{}]
19:18:28 INFO - [2944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:18:28 INFO - 1499393908452 Marionette DEBUG Closed connection 400
19:18:28 INFO - 1499393908454 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49832
19:18:28 INFO - 1499393908455 Marionette DEBUG Closed connection 401
19:18:28 INFO - 1499393908457 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49833
19:18:28 INFO - 1499393908459 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:18:28 INFO - 1499393908461 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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}
19:18:28 INFO - 1499393908486 Marionette DEBUG Register listener.js for window 12
19:18:28 INFO - 1499393908498 Marionette TRACE 402 <- [1,1,null,{"sessionId":"a4e30673-c1c4-4f65-8264-dfae5812a17d","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":2944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-045\\AppData\\Local\\Temp\\tmptaryuo.mozrunner","moz:accessibilityChecks":false}}]
19:18:28 INFO - 1499393908501 Marionette TRACE 402 -> [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"}]
19:18:28 INFO - 1499393908505 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"
19:18:28 INFO - 1499393908513 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"
19:18:28 INFO - 1499393908513 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"
19:18:28 INFO - ++DOMWINDOW == 24 (0000003D75EAF800) [pid = 2944] [serial = 52] [outer = 0000003D75ABC800]
19:18:28 INFO - 1499393908570 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"
19:18:28 INFO - 1499393908576 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"
19:18:28 INFO - 1499393908593 Marionette TRACE 402 <- [1,2,null,{}]
19:18:28 INFO - 1499393908597 Marionette TRACE 402 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:18:28 INFO - 1499393908606 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d6e87c87-3f1a-4268-ae61-683f584ff20e","ELEMENT":"d6e87c87-3f1a-4268-ae61-683f584ff20e"}}]
19:18:28 INFO - 1499393908608 Marionette TRACE 402 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d6e87c87-3f1a-4268-ae61-683f584ff20e"}]
19:18:28 INFO - 1499393908740 Marionette TRACE 402 <- [1,4,null,{"value":"none"}]
19:18:28 INFO - 1499393908742 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"d6e87c87-3f1a-4268-ae61-683f584ff20e"}]
19:18:28 INFO - 1499393908892 Marionette TRACE 402 <- [1,5,{"error":"element click intercepted","message":"Element