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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
17:30:19 INFO - 1499733019148 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}]
17:30:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499733019159 Marionette TRACE 353 <- [1,5,null,{"value":null}]
17:30:19 INFO - 1499733019161 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019167 Marionette TRACE 353 <- [1,6,null,{}]
17:30:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1019ms
17:30:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019174 Marionette DEBUG Closed connection 353
17:30:19 INFO - 1499733019177 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49768
17:30:19 INFO - 1499733019178 Marionette DEBUG Closed connection 354
17:30:19 INFO - 1499733019180 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49769
17:30:19 INFO - 1499733019183 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:19 INFO - 1499733019190 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:19 INFO - 1499733019232 Marionette DEBUG Register listener.js for window 12
17:30:19 INFO - 1499733019259 Marionette TRACE 355 <- [1,1,null,{"sessionId":"b8c29c5d-80f8-4c35-b2b7-c19055e0fec2","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:19 INFO - 1499733019267 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
17:30:19 INFO - 1499733019269 Marionette TRACE 355 <- [1,2,null,{}]
17:30:19 INFO - 1499733019292 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:19 INFO - 1499733019294 Marionette TRACE 355 <- [1,3,null,{}]
17:30:19 INFO - 1499733019296 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:19 INFO - 1499733019298 Marionette TRACE 355 <- [1,4,null,{}]
17:30:19 INFO - 1499733019350 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}]
17:30:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499733019365 Marionette TRACE 355 <- [1,5,null,{"value":null}]
17:30:19 INFO - 1499733019367 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019372 Marionette TRACE 355 <- [1,6,null,{}]
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019395 Marionette DEBUG Closed connection 355
17:30:19 INFO - 1499733019397 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49770
17:30:19 INFO - 1499733019401 Marionette DEBUG Closed connection 356
17:30:19 INFO - 1499733019406 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49771
17:30:19 INFO - 1499733019409 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:19 INFO - 1499733019412 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:19 INFO - 1499733019453 Marionette DEBUG Register listener.js for window 12
17:30:19 INFO - 1499733019468 Marionette TRACE 357 <- [1,1,null,{"sessionId":"3a36feed-d59a-4bda-a624-83913abb70d8","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:19 INFO - 1499733019487 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019493 Marionette TRACE 357 <- [1,2,null,{}]
17:30:19 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:19 INFO - 1499733019514 Marionette DEBUG Closed connection 357
17:30:19 INFO - 1499733019516 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49772
17:30:19 INFO - 1499733019665 Marionette DEBUG Closed connection 358
17:30:19 INFO - 1499733019668 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49773
17:30:19 INFO - 1499733019676 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:19 INFO - 1499733019687 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:19 INFO - 1499733019713 Marionette DEBUG Register listener.js for window 12
17:30:19 INFO - 1499733019746 Marionette TRACE 359 <- [1,1,null,{"sessionId":"2bed13a3-d1f1-44db-8ad7-26f6f86e204f","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:19 INFO - 1499733019765 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49318/clicks.html"}]
17:30:19 INFO - 1499733019771 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49318/hidden.html"
17:30:19 INFO - 1499733019794 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49318/hidden.html"
17:30:19 INFO - 1499733019795 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49318/hidden.html"
17:30:19 INFO - ++DOMWINDOW == 22 (0000007D2E4A8000) [pid = 2500] [serial = 41] [outer = 0000007D2ED48000]
17:30:19 INFO - 1499733019874 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49318/clicks.html"
17:30:19 INFO - 1499733019882 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49318/clicks.html"
17:30:19 INFO - 1499733019906 Marionette TRACE 359 <- [1,2,null,{}]
17:30:19 INFO - 1499733019915 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:30:19 INFO - 1499733019927 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4fe897e-0272-42b7-9cd1-3e5735967992","ELEMENT":"f4fe897e-0272-42b7-9cd1-3e5735967992"}}]
17:30:19 INFO - 1499733019930 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"f4fe897e-0272-42b7-9cd1-3e5735967992"}]
17:30:20 INFO - 1499733020155 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49318/clicks.html"
17:30:20 INFO - [Parent 2500] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
17:30:20 INFO - 1499733020176 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49318/clicks.html"
17:30:20 INFO - 1499733020178 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49318/clicks.html"
17:30:20 INFO - ++DOMWINDOW == 23 (0000007D2ED5F000) [pid = 2500] [serial = 42] [outer = 0000007D2ED48000]
17:30:20 INFO - 1499733020249 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49318/test.html"
17:30:20 INFO - 1499733020254 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49318/test.html"
17:30:20 INFO - 1499733020275 Marionette TRACE 359 <- [1,4,null,{}]
17:30:20 INFO - 1499733020323 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:30:20 INFO - 1499733020333 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c7ad5d3-d6fb-4c32-8fe2-06a280386613","ELEMENT":"6c7ad5d3-d6fb-4c32-8fe2-06a280386613"}}]
17:30:20 INFO - 1499733020336 Marionette TRACE 359 -> [0,6,"getTitle",{}]
17:30:20 INFO - 1499733020339 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
17:30:20 INFO - 1499733020342 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}]
17:30:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499733020354 Marionette TRACE 359 <- [1,7,null,{"value":null}]
17:30:20 INFO - 1499733020357 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020362 Marionette TRACE 359 <- [1,8,null,{}]
17:30:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1195ms
17:30:20 INFO - TEST-START | test_click.py TestClick.test_container_element
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020388 Marionette DEBUG Closed connection 359
17:30:20 INFO - 1499733020391 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49776
17:30:20 INFO - 1499733020395 Marionette DEBUG Closed connection 360
17:30:20 INFO - 1499733020399 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49777
17:30:20 INFO - 1499733020403 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:20 INFO - 1499733020408 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:20 INFO - 1499733020432 Marionette DEBUG Register listener.js for window 12
17:30:20 INFO - 1499733020458 Marionette TRACE 361 <- [1,1,null,{"sessionId":"ec9aa3be-e223-4740-a57e-06861744e32b","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:20 INFO - 1499733020471 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
17:30:20 INFO - 1499733020473 Marionette TRACE 361 <- [1,2,null,{}]
17:30:20 INFO - 1499733020479 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:20 INFO - 1499733020485 Marionette TRACE 361 <- [1,3,null,{}]
17:30:20 INFO - 1499733020513 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:20 INFO - 1499733020515 Marionette TRACE 361 <- [1,4,null,{}]
17:30:20 INFO - 1499733020519 Marionette TRACE 361 -> [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}]
17:30:20 INFO - TEST-START: test_click.py TestClick.test_container_element1499733020529 Marionette TRACE 361 <- [1,5,null,{"value":null}]
17:30:20 INFO - 1499733020533 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020539 Marionette TRACE 361 <- [1,6,null,{}]
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020547 Marionette DEBUG Closed connection 361
17:30:20 INFO - 1499733020549 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49778
17:30:20 INFO - 1499733020568 Marionette DEBUG Closed connection 362
17:30:20 INFO - 1499733020571 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49779
17:30:20 INFO - 1499733020574 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:20 INFO - 1499733020577 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:20 INFO - 1499733020600 Marionette DEBUG Register listener.js for window 12
17:30:20 INFO - 1499733020615 Marionette TRACE 363 <- [1,1,null,{"sessionId":"0380a3e1-20e8-40f1-b716-1b6dad938428","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:20 INFO - 1499733020624 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020629 Marionette TRACE 363 <- [1,2,null,{}]
17:30:20 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:20 INFO - 1499733020653 Marionette DEBUG Closed connection 363
17:30:20 INFO - 1499733020655 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49780
17:30:20 INFO - 1499733020659 Marionette DEBUG Closed connection 364
17:30:20 INFO - 1499733020661 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49781
17:30:20 INFO - 1499733020667 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:20 INFO - 1499733020670 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:20 INFO - 1499733020694 Marionette DEBUG Register listener.js for window 12
17:30:20 INFO - 1499733020709 Marionette TRACE 365 <- [1,1,null,{"sessionId":"66346731-d0fe-4eea-abf4-03ce68038d8a","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:20 INFO - 1499733020724 Marionette TRACE 365 -> [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"}]
17:30:20 INFO - 1499733020730 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49318/test.html"
17:30:20 INFO - 1499733020736 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49318/test.html"
17:30:20 INFO - 1499733020737 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49318/test.html"
17:30:20 INFO - ++DOMWINDOW == 24 (0000007D239D4800) [pid = 2500] [serial = 43] [outer = 0000007D2ED48000]
17:30:20 INFO - 1499733020820 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"
17:30:20 INFO - 1499733020826 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"
17:30:20 INFO - 1499733020844 Marionette TRACE 365 <- [1,2,null,{}]
17:30:20 INFO - --DOMWINDOW == 23 (0000007D3235D000) [pid = 2500] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49318/test_accessibility.html]
17:30:20 INFO - --DOMWINDOW == 22 (0000007D24560000) [pid = 2500] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49318/test_accessibility.html]
17:30:20 INFO - --DOMWINDOW == 21 (0000007D30311800) [pid = 2500] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49318/test_accessibility.html]
17:30:20 INFO - 1499733020857 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:30:20 INFO - 1499733020866 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d1bacb5e-faff-458e-b1f5-493f0f6cd4de","ELEMENT":"d1bacb5e-faff-458e-b1f5-493f0f6cd4de"}}]
17:30:20 INFO - 1499733020868 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"d1bacb5e-faff-458e-b1f5-493f0f6cd4de"}]
17:30:21 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:30:21 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:30:21 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:30:21 INFO - 1499733021977 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:22 INFO - 1499733021996 Marionette TRACE 365 <- [1,4,null,{}]
17:30:22 INFO - 1499733022037 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"d1bacb5e-faff-458e-b1f5-493f0f6cd4de","name":"selected"}]
17:30:22 INFO - 1499733022062 Marionette TRACE 365 <- [1,5,null,{"value":true}]
17:30:22 INFO - 1499733022065 Marionette TRACE 365 -> [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}]
17:30:22 INFO - TEST-END: test_click.py TestClick.test_container_element1499733022077 Marionette TRACE 365 <- [1,6,null,{"value":null}]
17:30:22 INFO - 1499733022079 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022083 Marionette TRACE 365 <- [1,7,null,{}]
17:30:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1720ms
17:30:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022089 Marionette DEBUG Closed connection 365
17:30:22 INFO - 1499733022091 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49782
17:30:22 INFO - 1499733022093 Marionette DEBUG Closed connection 366
17:30:22 INFO - 1499733022095 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49783
17:30:22 INFO - 1499733022097 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:22 INFO - 1499733022103 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:22 INFO - 1499733022126 Marionette DEBUG Register listener.js for window 12
17:30:22 INFO - 1499733022186 Marionette TRACE 367 <- [1,1,null,{"sessionId":"66a317cb-6ad8-4c09-a1ac-1affa6214a08","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:22 INFO - 1499733022243 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
17:30:22 INFO - 1499733022245 Marionette TRACE 367 <- [1,2,null,{}]
17:30:22 INFO - 1499733022285 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:22 INFO - 1499733022287 Marionette TRACE 367 <- [1,3,null,{}]
17:30:22 INFO - 1499733022299 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:22 INFO - 1499733022301 Marionette TRACE 367 <- [1,4,null,{}]
17:30:22 INFO - 1499733022304 Marionette TRACE 367 -> [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}]
17:30:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499733022312 Marionette TRACE 367 <- [1,5,null,{"value":null}]
17:30:22 INFO - 1499733022315 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022319 Marionette TRACE 367 <- [1,6,null,{}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022340 Marionette DEBUG Closed connection 367
17:30:22 INFO - 1499733022342 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49784
17:30:22 INFO - 1499733022347 Marionette DEBUG Closed connection 368
17:30:22 INFO - 1499733022352 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49785
17:30:22 INFO - 1499733022354 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:22 INFO - 1499733022357 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:22 INFO - 1499733022381 Marionette DEBUG Register listener.js for window 12
17:30:22 INFO - 1499733022394 Marionette TRACE 369 <- [1,1,null,{"sessionId":"7560965b-8b35-4549-ab3b-ab2412858cc9","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:22 INFO - 1499733022405 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022410 Marionette TRACE 369 <- [1,2,null,{}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:22 INFO - 1499733022430 Marionette DEBUG Closed connection 369
17:30:22 INFO - 1499733022432 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49786
17:30:22 INFO - 1499733022436 Marionette DEBUG Closed connection 370
17:30:22 INFO - 1499733022437 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49787
17:30:22 INFO - 1499733022440 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:22 INFO - 1499733022442 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:22 INFO - 1499733022465 Marionette DEBUG Register listener.js for window 12
17:30:22 INFO - 1499733022477 Marionette TRACE 371 <- [1,1,null,{"sessionId":"0128ba5d-5f9c-4e4a-b4ee-9f81fdd2fcb5","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:22 INFO - 1499733022486 Marionette TRACE 371 -> [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"}]
17:30:22 INFO - 1499733022490 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"
17:30:22 INFO - 1499733022497 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"
17:30:22 INFO - 1499733022498 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"
17:30:22 INFO - ++DOMWINDOW == 22 (0000007D2ED54000) [pid = 2500] [serial = 44] [outer = 0000007D2ED48000]
17:30:22 INFO - 1499733022554 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"
17:30:22 INFO - 1499733022560 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"
17:30:22 INFO - 1499733022581 Marionette TRACE 371 <- [1,2,null,{}]
17:30:22 INFO - 1499733022584 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:30:22 INFO - 1499733022594 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1195ffe2-b5f1-42e6-ad22-509c22305387","ELEMENT":"1195ffe2-b5f1-42e6-ad22-509c22305387"}}]
17:30:22 INFO - 1499733022596 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"1195ffe2-b5f1-42e6-ad22-509c22305387"}]
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:30:22 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:30:23 INFO - 1499733023042 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:23 INFO - 1499733023044 Marionette TRACE 371 <- [1,4,null,{}]
17:30:23 INFO - 1499733023046 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"1195ffe2-b5f1-42e6-ad22-509c22305387","name":"selected"}]
17:30:23 INFO - 1499733023069 Marionette TRACE 371 <- [1,5,null,{"value":true}]
17:30:23 INFO - 1499733023071 Marionette TRACE 371 -> [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}]
17:30:23 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499733023079 Marionette TRACE 371 <- [1,6,null,{"value":null}]
17:30:23 INFO - 1499733023081 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023086 Marionette TRACE 371 <- [1,7,null,{}]
17:30:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1002ms
17:30:23 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023091 Marionette DEBUG Closed connection 371
17:30:23 INFO - 1499733023093 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49788
17:30:23 INFO - 1499733023094 Marionette DEBUG Closed connection 372
17:30:23 INFO - 1499733023096 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49789
17:30:23 INFO - 1499733023098 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:23 INFO - 1499733023100 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:23 INFO - 1499733023123 Marionette DEBUG Register listener.js for window 12
17:30:23 INFO - 1499733023163 Marionette TRACE 373 <- [1,1,null,{"sessionId":"93c1eafc-535a-42bc-b6e5-7a0af3f54696","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:23 INFO - 1499733023174 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
17:30:23 INFO - 1499733023176 Marionette TRACE 373 <- [1,2,null,{}]
17:30:23 INFO - 1499733023177 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:23 INFO - 1499733023179 Marionette TRACE 373 <- [1,3,null,{}]
17:30:23 INFO - 1499733023181 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:23 INFO - 1499733023182 Marionette TRACE 373 <- [1,4,null,{}]
17:30:23 INFO - 1499733023186 Marionette TRACE 373 -> [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}]
17:30:23 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499733023196 Marionette TRACE 373 <- [1,5,null,{"value":null}]
17:30:23 INFO - 1499733023197 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023202 Marionette TRACE 373 <- [1,6,null,{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023209 Marionette DEBUG Closed connection 373
17:30:23 INFO - 1499733023210 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49790
17:30:23 INFO - 1499733023212 Marionette DEBUG Closed connection 374
17:30:23 INFO - 1499733023213 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49791
17:30:23 INFO - 1499733023215 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:23 INFO - 1499733023218 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:23 INFO - 1499733023242 Marionette DEBUG Register listener.js for window 12
17:30:23 INFO - 1499733023254 Marionette TRACE 375 <- [1,1,null,{"sessionId":"9f60a474-8dcd-4bbe-a40a-7da9dcfb3b6d","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:23 INFO - 1499733023260 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023264 Marionette TRACE 375 <- [1,2,null,{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023270 Marionette DEBUG Closed connection 375
17:30:23 INFO - 1499733023272 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49792
17:30:23 INFO - 1499733023273 Marionette DEBUG Closed connection 376
17:30:23 INFO - 1499733023275 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49793
17:30:23 INFO - 1499733023277 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:23 INFO - 1499733023279 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:23 INFO - 1499733023302 Marionette DEBUG Register listener.js for window 12
17:30:23 INFO - 1499733023328 Marionette TRACE 377 <- [1,1,null,{"sessionId":"36ff2c87-9616-4fed-9494-fd18a975f706","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:23 INFO - 1499733023336 Marionette TRACE 377 -> [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"}]
17:30:23 INFO - 1499733023340 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"
17:30:23 INFO - 1499733023347 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"
17:30:23 INFO - 1499733023348 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"
17:30:23 INFO - ++DOMWINDOW == 23 (0000007D314B1000) [pid = 2500] [serial = 45] [outer = 0000007D2ED48000]
17:30:23 INFO - 1499733023402 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"
17:30:23 INFO - 1499733023409 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"
17:30:23 INFO - 1499733023426 Marionette TRACE 377 <- [1,2,null,{}]
17:30:23 INFO - 1499733023480 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:30:23 INFO - 1499733023487 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"41214aa0-1aa4-4922-91e6-c147c32c4357","ELEMENT":"41214aa0-1aa4-4922-91e6-c147c32c4357"}}]
17:30:23 INFO - 1499733023490 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"41214aa0-1aa4-4922-91e6-c147c32c4357"}]
17:30:23 INFO - 1499733023906 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:23 INFO - 1499733023908 Marionette TRACE 377 <- [1,4,null,{}]
17:30:23 INFO - 1499733023911 Marionette TRACE 377 -> [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}]
17:30:23 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499733023919 Marionette TRACE 377 <- [1,5,null,{"value":null}]
17:30:23 INFO - 1499733023921 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023925 Marionette TRACE 377 <- [1,6,null,{}]
17:30:23 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 840ms
17:30:23 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:30:23 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:23 INFO - 1499733023931 Marionette DEBUG Closed connection 377
17:30:23 INFO - 1499733023932 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49794
17:30:23 INFO - 1499733023934 Marionette DEBUG Closed connection 378
17:30:23 INFO - 1499733023935 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49795
17:30:23 INFO - 1499733023938 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:23 INFO - 1499733023941 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:23 INFO - 1499733023968 Marionette DEBUG Register listener.js for window 12
17:30:23 INFO - 1499733023983 Marionette TRACE 379 <- [1,1,null,{"sessionId":"59717ffc-7b2a-4637-82b2-d01eed75d5b6","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:23 INFO - 1499733023989 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
17:30:23 INFO - 1499733023991 Marionette TRACE 379 <- [1,2,null,{}]
17:30:23 INFO - 1499733023993 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:23 INFO - 1499733023995 Marionette TRACE 379 <- [1,3,null,{}]
17:30:23 INFO - 1499733023996 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:24 INFO - 1499733023998 Marionette TRACE 379 <- [1,4,null,{}]
17:30:24 INFO - 1499733024000 Marionette TRACE 379 -> [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}]
17:30:24 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499733024008 Marionette TRACE 379 <- [1,5,null,{"value":null}]
17:30:24 INFO - 1499733024010 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
17:30:24 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:24 INFO - 1499733024014 Marionette TRACE 379 <- [1,6,null,{}]
17:30:24 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:24 INFO - 1499733024020 Marionette DEBUG Closed connection 379
17:30:24 INFO - 1499733024022 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49796
17:30:24 INFO - 1499733024023 Marionette DEBUG Closed connection 380
17:30:24 INFO - 1499733024025 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49797
17:30:24 INFO - 1499733024027 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:24 INFO - 1499733024029 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:24 INFO - 1499733024053 Marionette DEBUG Register listener.js for window 12
17:30:24 INFO - 1499733024064 Marionette TRACE 381 <- [1,1,null,{"sessionId":"0ac23866-39d7-404b-affd-a00dd8b9bcc7","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:24 INFO - 1499733024070 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
17:30:24 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:24 INFO - 1499733024074 Marionette TRACE 381 <- [1,2,null,{}]
17:30:24 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:24 INFO - 1499733024079 Marionette DEBUG Closed connection 381
17:30:24 INFO - 1499733024081 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49798
17:30:24 INFO - 1499733024082 Marionette DEBUG Closed connection 382
17:30:24 INFO - 1499733024084 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49799
17:30:24 INFO - 1499733024086 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:24 INFO - 1499733024089 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:24 INFO - 1499733024112 Marionette DEBUG Register listener.js for window 12
17:30:24 INFO - 1499733024132 Marionette TRACE 383 <- [1,1,null,{"sessionId":"8ef3489d-6885-45c4-9344-722d9be83437","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:24 INFO - 1499733024138 Marionette TRACE 383 -> [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"}]
17:30:24 INFO - 1499733024142 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"
17:30:24 INFO - 1499733024152 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"
17:30:24 INFO - 1499733024153 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"
17:30:24 INFO - ++DOMWINDOW == 24 (0000007D33054000) [pid = 2500] [serial = 46] [outer = 0000007D2ED48000]
17:30:24 INFO - 1499733024285 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"
17:30:24 INFO - 1499733024292 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"
17:30:24 INFO - 1499733024309 Marionette TRACE 383 <- [1,2,null,{}]
17:30:24 INFO - 1499733024315 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:30:24 INFO - 1499733024324 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"30b6e2c3-b1b1-4e5a-a79e-1b62d6f4f00b","ELEMENT":"30b6e2c3-b1b1-4e5a-a79e-1b62d6f4f00b"}}]
17:30:24 INFO - 1499733024326 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"30b6e2c3-b1b1-4e5a-a79e-1b62d6f4f00b"}]
17:30:25 INFO - 1499733025302 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:25 INFO - 1499733025318 Marionette TRACE 383 <- [1,4,null,{}]
17:30:25 INFO - 1499733025324 Marionette TRACE 383 -> [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}]
17:30:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499733025331 Marionette TRACE 383 <- [1,5,null,{"value":null}]
17:30:25 INFO - 1499733025344 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025348 Marionette TRACE 383 <- [1,6,null,{}]
17:30:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1423ms
17:30:25 INFO - TEST-START | test_click.py TestClick.test_input_file
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025370 Marionette DEBUG Closed connection 383
17:30:25 INFO - 1499733025372 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49800
17:30:25 INFO - 1499733025425 Marionette DEBUG Closed connection 384
17:30:25 INFO - 1499733025428 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49801
17:30:25 INFO - 1499733025435 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:25 INFO - 1499733025445 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:25 INFO - 1499733025493 Marionette DEBUG Register listener.js for window 12
17:30:25 INFO - 1499733025516 Marionette TRACE 385 <- [1,1,null,{"sessionId":"00a06d91-9d85-4254-9514-b7717aac8d25","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:25 INFO - 1499733025530 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
17:30:25 INFO - 1499733025533 Marionette TRACE 385 <- [1,2,null,{}]
17:30:25 INFO - 1499733025538 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:25 INFO - 1499733025541 Marionette TRACE 385 <- [1,3,null,{}]
17:30:25 INFO - 1499733025545 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:25 INFO - 1499733025549 Marionette TRACE 385 <- [1,4,null,{}]
17:30:25 INFO - 1499733025554 Marionette TRACE 385 -> [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}]
17:30:25 INFO - TEST-START: test_click.py TestClick.test_input_file1499733025568 Marionette TRACE 385 <- [1,5,null,{"value":null}]
17:30:25 INFO - 1499733025580 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025588 Marionette TRACE 385 <- [1,6,null,{}]
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025615 Marionette DEBUG Closed connection 385
17:30:25 INFO - 1499733025618 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49802
17:30:25 INFO - 1499733025638 Marionette DEBUG Closed connection 386
17:30:25 INFO - 1499733025643 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49803
17:30:25 INFO - 1499733025647 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:25 INFO - 1499733025652 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:25 INFO - 1499733025697 Marionette DEBUG Register listener.js for window 12
17:30:25 INFO - 1499733025716 Marionette TRACE 387 <- [1,1,null,{"sessionId":"05dc6fb8-30b0-41df-9059-c2270510d1a2","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:25 INFO - 1499733025746 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025752 Marionette TRACE 387 <- [1,2,null,{}]
17:30:25 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:25 INFO - 1499733025761 Marionette DEBUG Closed connection 387
17:30:25 INFO - 1499733025764 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49804
17:30:25 INFO - 1499733025787 Marionette DEBUG Closed connection 388
17:30:25 INFO - 1499733025791 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49805
17:30:25 INFO - 1499733025843 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:25 INFO - 1499733025848 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:25 INFO - 1499733025894 Marionette DEBUG Register listener.js for window 12
17:30:25 INFO - 1499733025911 Marionette TRACE 389 <- [1,1,null,{"sessionId":"fc60a004-1d82-4670-b0db-954bba617543","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:25 INFO - 1499733025924 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:30:25 INFO - 1499733025930 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"
17:30:25 INFO - 1499733025941 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"
17:30:25 INFO - 1499733025944 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"
17:30:25 INFO - ++DOMWINDOW == 25 (0000007D2DD56000) [pid = 2500] [serial = 47] [outer = 0000007D2ED48000]
17:30:26 INFO - 1499733026006 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:26 INFO - 1499733026034 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:26 INFO - 1499733026054 Marionette TRACE 389 <- [1,2,null,{}]
17:30:26 INFO - 1499733026067 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:30:26 INFO - 1499733026082 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18a7d4f1-3f0c-4401-8975-8a34bc3694e8","ELEMENT":"18a7d4f1-3f0c-4401-8975-8a34bc3694e8"}}]
17:30:26 INFO - 1499733026084 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"18a7d4f1-3f0c-4401-8975-8a34bc3694e8"}]
17:30:26 INFO - 1499733026312 Marionette TRACE 389 <- [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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
17:30:26 INFO - 1499733026327 Marionette TRACE 389 -> [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}]
17:30:26 INFO - TEST-END: test_click.py TestClick.test_input_file1499733026340 Marionette TRACE 389 <- [1,5,null,{"value":null}]
17:30:26 INFO - 1499733026343 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026349 Marionette TRACE 389 <- [1,6,null,{}]
17:30:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 999ms
17:30:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026407 Marionette DEBUG Closed connection 389
17:30:26 INFO - 1499733026409 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49806
17:30:26 INFO - 1499733026430 Marionette DEBUG Closed connection 390
17:30:26 INFO - 1499733026432 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49807
17:30:26 INFO - 1499733026435 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:26 INFO - 1499733026438 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:26 INFO - 1499733026486 Marionette DEBUG Register listener.js for window 12
17:30:26 INFO - 1499733026501 Marionette TRACE 391 <- [1,1,null,{"sessionId":"0afa1ca9-3c41-460a-a50f-b98ba11fd1d9","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:26 INFO - 1499733026534 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
17:30:26 INFO - 1499733026537 Marionette TRACE 391 <- [1,2,null,{}]
17:30:26 INFO - 1499733026545 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:26 INFO - 1499733026547 Marionette TRACE 391 <- [1,3,null,{}]
17:30:26 INFO - 1499733026549 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:26 INFO - 1499733026551 Marionette TRACE 391 <- [1,4,null,{}]
17:30:26 INFO - 1499733026554 Marionette TRACE 391 -> [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}]
17:30:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499733026565 Marionette TRACE 391 <- [1,5,null,{"value":null}]
17:30:26 INFO - 1499733026567 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026572 Marionette TRACE 391 <- [1,6,null,{}]
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026579 Marionette DEBUG Closed connection 391
17:30:26 INFO - 1499733026581 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49808
17:30:26 INFO - 1499733026583 Marionette DEBUG Closed connection 392
17:30:26 INFO - 1499733026585 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49809
17:30:26 INFO - 1499733026587 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:26 INFO - 1499733026594 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:26 INFO - 1499733026639 Marionette DEBUG Register listener.js for window 12
17:30:26 INFO - 1499733026657 Marionette TRACE 393 <- [1,1,null,{"sessionId":"aef97619-bb76-494f-af1c-fe2170909fbe","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:26 INFO - 1499733026699 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026704 Marionette TRACE 393 <- [1,2,null,{}]
17:30:26 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:26 INFO - 1499733026727 Marionette DEBUG Closed connection 393
17:30:26 INFO - 1499733026729 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49810
17:30:26 INFO - 1499733026766 Marionette DEBUG Closed connection 394
17:30:26 INFO - 1499733026771 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49811
17:30:26 INFO - 1499733026777 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:26 INFO - 1499733026780 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:26 INFO - 1499733026828 Marionette DEBUG Register listener.js for window 12
17:30:26 INFO - 1499733026844 Marionette TRACE 395 <- [1,1,null,{"sessionId":"c620467c-9989-4799-ab69-447273aeb2a5","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:26 INFO - 1499733026855 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
17:30:26 INFO - 1499733026860 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:26 INFO - 1499733026870 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:26 INFO - 1499733026871 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:26 INFO - ++DOMWINDOW == 26 (0000007D366C5000) [pid = 2500] [serial = 48] [outer = 0000007D2ED48000]
17:30:26 INFO - 1499733026967 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"
17:30:26 INFO - 1499733026975 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"
17:30:27 INFO - 1499733027007 Marionette TRACE 395 <- [1,2,null,{}]
17:30:27 INFO - 1499733027011 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:30:27 INFO - 1499733027020 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14f20fd5-0d02-48b3-8a41-9bcd665507e2","ELEMENT":"14f20fd5-0d02-48b3-8a41-9bcd665507e2"}}]
17:30:27 INFO - 1499733027022 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:30:27 INFO - 1499733027030 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77bed619-3358-43cb-a4d5-394d18284ad0","ELEMENT":"77bed619-3358-43cb-a4d5-394d18284ad0"}}]
17:30:27 INFO - 1499733027032 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"14f20fd5-0d02-48b3-8a41-9bcd665507e2"}]
17:30:27 INFO - 1499733027575 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:27 INFO - 1499733027579 Marionette TRACE 395 <- [1,5,null,{}]
17:30:27 INFO - 1499733027597 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"77bed619-3358-43cb-a4d5-394d18284ad0"}]
17:30:27 INFO - 1499733027746 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
17:30:27 INFO - 1499733027755 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:30:27 INFO - 1499733027771 Marionette TRACE 395 <- [1,7,null,{"value":false}]
17:30:27 INFO - 1499733027786 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
17:30:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499733027803 Marionette TRACE 395 <- [1,8,null,{"value":null}]
17:30:27 INFO - 1499733027820 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
17:30:27 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:27 INFO - 1499733027829 Marionette TRACE 395 <- [1,9,null,{}]
17:30:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1481ms
17:30:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:30:27 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:27 INFO - 1499733027886 Marionette DEBUG Closed connection 395
17:30:27 INFO - 1499733027890 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49812
17:30:27 INFO - 1499733027904 Marionette DEBUG Closed connection 396
17:30:27 INFO - 1499733027907 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49813
17:30:27 INFO - 1499733027910 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:27 INFO - 1499733027921 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:27 INFO - 1499733027949 Marionette DEBUG Register listener.js for window 12
17:30:27 INFO - 1499733027969 Marionette TRACE 397 <- [1,1,null,{"sessionId":"ca9a301c-2d40-4190-949d-540c20191c88","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:28 INFO - 1499733028003 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
17:30:28 INFO - 1499733028006 Marionette TRACE 397 <- [1,2,null,{}]
17:30:28 INFO - 1499733028015 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:28 INFO - 1499733028018 Marionette TRACE 397 <- [1,3,null,{}]
17:30:28 INFO - 1499733028074 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:28 INFO - 1499733028076 Marionette TRACE 397 <- [1,4,null,{}]
17:30:28 INFO - 1499733028082 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
17:30:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499733028095 Marionette TRACE 397 <- [1,5,null,{"value":null}]
17:30:28 INFO - 1499733028099 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
17:30:28 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:28 INFO - 1499733028106 Marionette TRACE 397 <- [1,6,null,{}]
17:30:28 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:28 INFO - 1499733028117 Marionette DEBUG Closed connection 397
17:30:28 INFO - 1499733028120 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49814
17:30:28 INFO - 1499733028142 Marionette DEBUG Closed connection 398
17:30:28 INFO - 1499733028143 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49815
17:30:28 INFO - 1499733028151 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:28 INFO - 1499733028157 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:28 INFO - 1499733028183 Marionette DEBUG Register listener.js for window 12
17:30:28 INFO - 1499733028198 Marionette TRACE 399 <- [1,1,null,{"sessionId":"6ccbdbcf-c422-42b2-be43-6108c5058950","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:28 INFO - 1499733028209 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
17:30:28 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:28 INFO - 1499733028216 Marionette TRACE 399 <- [1,2,null,{}]
17:30:28 INFO - [Parent 2500] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:28 INFO - 1499733028242 Marionette DEBUG Closed connection 399
17:30:28 INFO - 1499733028244 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49816
17:30:28 INFO - 1499733028249 Marionette DEBUG Closed connection 400
17:30:28 INFO - 1499733028251 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49817
17:30:28 INFO - 1499733028258 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:28 INFO - 1499733028262 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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}
17:30:28 INFO - 1499733028288 Marionette DEBUG Register listener.js for window 12
17:30:28 INFO - 1499733028302 Marionette TRACE 401 <- [1,1,null,{"sessionId":"2dd90dfc-3e8c-458f-8bdd-b066a6086fa6","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":2500,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-170.001\\AppData\\Local\\Temp\\tmpgynefg.mozrunner","moz:accessibilityChecks":false}}]
17:30:28 INFO - 1499733028317 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
17:30:28 INFO - 1499733028323 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"
17:30:28 INFO - 1499733028334 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"
17:30:28 INFO - 1499733028335 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"
17:30:28 INFO - ++DOMWINDOW == 27 (0000007D2E835000) [pid = 2500] [serial = 49] [outer = 0000007D2ED48000]
17:30:28 INFO - 1499733028398 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"
17:30:28 INFO - 1499733028405 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"
17:30:28 INFO - 1499733028424 Marionette TRACE 401 <- [1,2,null,{}]
17:30:28 INFO - --DOMWINDOW == 26 (0000007D239C4000) [pid = 2500] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
17:30:28 INFO - --DOMWINDOW == 25 (0000007D28EB8800) [pid = 2500] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
17:30:28 INFO - --DOMWINDOW == 24 (0000007D308D6800) [pid = 2500] [serial = 35] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
17:30:28 INFO - --DOMWINDOW == 23 (0000007D314B8800) [pid = 2500] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicks%20%3D%200%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicks%2B%2B%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20]
17:30:28 INFO - --DOMWINDOW == 22 (0000007D325B5800) [pid = 2500] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49318/test_accessibility.html]
17:30:28 INFO - 1499733028439 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:30:28 INFO - 1499733028447 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af7ca4a1-f826-4ab0-b78e-bdf23b6c5748","ELEMENT":"af7ca4a1-f826-4ab0-b78e-bdf23b6c5748"}}]
17:30:28 INFO - 1499733028449 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"af7ca4a1-f826-4ab0-b78e-bdf23b6c5748"}]
17:30:28 INFO - 1499733028571 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
17:30:28 INFO - 1499733028682 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"af7ca4a1-f826-4ab0-b78e-bdf23b6c5748"}]
17:30:28 INFO - 1499733028802 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element