could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:51:12 INFO - 1500990672694 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:51:12 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500990672715 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:51:12 INFO - 1500990672717 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672722 Marionette TRACE 352 <- [1,6,null,{}]
06:51:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 553ms
06:51:12 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672726 Marionette DEBUG Closed connection 352
06:51:12 INFO - 1500990672727 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49787
06:51:12 INFO - 1500990672729 Marionette DEBUG Closed connection 353
06:51:12 INFO - 1500990672730 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49788
06:51:12 INFO - 1500990672732 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:12 INFO - 1500990672734 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:12 INFO - 1500990672754 Marionette DEBUG Register listener.js for window 4294967297
06:51:12 INFO - 1500990672766 Marionette TRACE 354 <- [1,1,null,{"sessionId":"3e664a9a-7900-4445-a797-e09a9691ea14","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:12 INFO - 1500990672769 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:51:12 INFO - 1500990672771 Marionette TRACE 354 <- [1,2,null,{}]
06:51:12 INFO - 1500990672773 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:12 INFO - 1500990672774 Marionette TRACE 354 <- [1,3,null,{}]
06:51:12 INFO - 1500990672776 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:12 INFO - 1500990672777 Marionette TRACE 354 <- [1,4,null,{}]
06:51:12 INFO - 1500990672780 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:51:12 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500990672787 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:51:12 INFO - 1500990672789 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672793 Marionette TRACE 354 <- [1,6,null,{}]
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672797 Marionette DEBUG Closed connection 354
06:51:12 INFO - 1500990672798 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49789
06:51:12 INFO - 1500990672800 Marionette DEBUG Closed connection 355
06:51:12 INFO - 1500990672801 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49790
06:51:12 INFO - 1500990672803 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:12 INFO - 1500990672805 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:12 INFO - 1500990672831 Marionette DEBUG Register listener.js for window 4294967297
06:51:12 INFO - 1500990672843 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b819b629-5e5d-4ecc-a75a-dfba84366a17","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:12 INFO - 1500990672846 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672851 Marionette TRACE 356 <- [1,2,null,{}]
06:51:12 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:12 INFO - 1500990672855 Marionette DEBUG Closed connection 356
06:51:12 INFO - 1500990672857 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49791
06:51:12 INFO - 1500990672859 Marionette DEBUG Closed connection 357
06:51:12 INFO - 1500990672861 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49792
06:51:12 INFO - 1500990672864 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:12 INFO - 1500990672866 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:12 INFO - 1500990672888 Marionette DEBUG Register listener.js for window 4294967297
06:51:12 INFO - 1500990672900 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1decc82d-76cb-4465-830e-3ab8abb74c8f","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:12 INFO - 1500990672903 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
06:51:12 INFO - 1500990672907 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
06:51:12 INFO - 1500990672924 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
06:51:12 INFO - 1500990672925 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
06:51:12 INFO - ++DOMWINDOW == 19 (000000DA22DE6000) [pid = 252] [serial = 25] [outer = 000000DA1AE1E000]
06:51:12 INFO - 1500990672949 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
06:51:12 INFO - 1500990672960 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
06:51:12 INFO - 1500990672967 Marionette TRACE 358 <- [1,2,null,{}]
06:51:12 INFO - 1500990672984 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:51:12 INFO - 1500990672990 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"588330ef-a6b2-4e24-bd24-e7fc82174905","ELEMENT":"588330ef-a6b2-4e24-bd24-e7fc82174905"}}]
06:51:12 INFO - 1500990672993 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"588330ef-a6b2-4e24-bd24-e7fc82174905"}]
06:51:12 INFO - --DOMWINDOW == 18 (000000DA22DDF000) [pid = 252] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:12 INFO - --DOMWINDOW == 17 (000000DA22E2B000) [pid = 252] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:12 INFO - --DOMWINDOW == 16 (000000DA22E35800) [pid = 252] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:12 INFO - --DOMWINDOW == 15 (000000DA242C0800) [pid = 252] [serial = 8] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:13 INFO - 1500990673215 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
06:51:13 INFO - 1500990673236 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
06:51:13 INFO - 1500990673237 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
06:51:13 INFO - ++DOMWINDOW == 16 (000000DA22E2D000) [pid = 252] [serial = 26] [outer = 000000DA1AE1E000]
06:51:13 INFO - 1500990673265 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
06:51:13 INFO - 1500990673269 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
06:51:13 INFO - 1500990673276 Marionette TRACE 358 <- [1,4,null,{}]
06:51:13 INFO - 1500990673292 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:51:13 INFO - 1500990673299 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b629b030-136e-4931-be1b-ef443901b230","ELEMENT":"b629b030-136e-4931-be1b-ef443901b230"}}]
06:51:13 INFO - 1500990673301 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:51:13 INFO - 1500990673302 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:51:13 INFO - 1500990673305 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:51:13 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500990673312 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:51:13 INFO - 1500990673314 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 597ms
06:51:13 INFO - TEST-START | test_click.py TestClick.test_container_element
06:51:13 INFO - 1500990673318 Marionette TRACE 358 <- [1,8,null,{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - 1500990673322 Marionette DEBUG Closed connection 358
06:51:13 INFO - 1500990673324 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49795
06:51:13 INFO - 1500990673325 Marionette DEBUG Closed connection 359
06:51:13 INFO - 1500990673327 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49796
06:51:13 INFO - 1500990673329 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:13 INFO - 1500990673331 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:13 INFO - 1500990673351 Marionette DEBUG Register listener.js for window 4294967297
06:51:13 INFO - 1500990673362 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ae539eb4-8cbf-4abc-8d10-78776ceb86ab","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:13 INFO - 1500990673365 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:51:13 INFO - 1500990673367 Marionette TRACE 360 <- [1,2,null,{}]
06:51:13 INFO - 1500990673368 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:13 INFO - 1500990673370 Marionette TRACE 360 <- [1,3,null,{}]
06:51:13 INFO - 1500990673371 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:13 INFO - 1500990673374 Marionette TRACE 360 <- [1,4,null,{}]
06:51:13 INFO - 1500990673377 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:51:13 INFO - TEST-START: test_click.py TestClick.test_container_element1500990673385 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:51:13 INFO - 1500990673386 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - 1500990673391 Marionette TRACE 360 <- [1,6,null,{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - 1500990673395 Marionette DEBUG Closed connection 360
06:51:13 INFO - 1500990673396 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49797
06:51:13 INFO - 1500990673398 Marionette DEBUG Closed connection 361
06:51:13 INFO - 1500990673399 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49798
06:51:13 INFO - 1500990673402 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:13 INFO - 1500990673404 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:13 INFO - 1500990673424 Marionette DEBUG Register listener.js for window 4294967297
06:51:13 INFO - 1500990673435 Marionette TRACE 362 <- [1,1,null,{"sessionId":"10d43cf4-cefe-484c-b240-8eac4b779605","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:13 INFO - 1500990673438 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - 1500990673442 Marionette TRACE 362 <- [1,2,null,{}]
06:51:13 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:13 INFO - 1500990673447 Marionette DEBUG Closed connection 362
06:51:13 INFO - 1500990673449 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49799
06:51:13 INFO - 1500990673450 Marionette DEBUG Closed connection 363
06:51:13 INFO - 1500990673452 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49800
06:51:13 INFO - 1500990673454 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:13 INFO - 1500990673456 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:13 INFO - 1500990673477 Marionette DEBUG Register listener.js for window 4294967297
06:51:13 INFO - 1500990673502 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7262c24b-6b16-4cb2-aa17-dfd83857909f","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:13 INFO - 1500990673505 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:51:13 INFO - 1500990673509 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
06:51:13 INFO - 1500990673514 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
06:51:13 INFO - 1500990673515 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
06:51:13 INFO - ++DOMWINDOW == 17 (000000DA2370F000) [pid = 252] [serial = 27] [outer = 000000DA1AE1E000]
06:51:13 INFO - 1500990673537 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"
06:51:13 INFO - 1500990673545 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"
06:51:13 INFO - 1500990673570 Marionette TRACE 364 <- [1,2,null,{}]
06:51:13 INFO - 1500990673588 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:51:13 INFO - 1500990673595 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"495406c0-9e88-425a-8930-34bd267eb50b","ELEMENT":"495406c0-9e88-425a-8930-34bd267eb50b"}}]
06:51:13 INFO - 1500990673597 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"495406c0-9e88-425a-8930-34bd267eb50b"}]
06:51:13 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
06:51:13 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
06:51:13 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
06:51:14 INFO - 1500990674263 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:51:14 INFO - 1500990674269 Marionette TRACE 364 <- [1,4,null,{}]
06:51:14 INFO - 1500990674271 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"495406c0-9e88-425a-8930-34bd267eb50b","name":"selected"}]
06:51:14 INFO - 1500990674340 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:51:14 INFO - 1500990674342 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:51:14 INFO - TEST-END: test_click.py TestClick.test_container_element1500990674387 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:51:14 INFO - 1500990674389 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674394 Marionette TRACE 364 <- [1,7,null,{}]
06:51:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1075ms
06:51:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674398 Marionette DEBUG Closed connection 364
06:51:14 INFO - 1500990674399 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49801
06:51:14 INFO - 1500990674401 Marionette DEBUG Closed connection 365
06:51:14 INFO - 1500990674403 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49802
06:51:14 INFO - 1500990674405 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:14 INFO - 1500990674407 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:14 INFO - 1500990674442 Marionette DEBUG Register listener.js for window 4294967297
06:51:14 INFO - 1500990674453 Marionette TRACE 366 <- [1,1,null,{"sessionId":"eca09fce-183c-40d6-b8de-f971949315d2","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:14 INFO - 1500990674456 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:51:14 INFO - 1500990674458 Marionette TRACE 366 <- [1,2,null,{}]
06:51:14 INFO - 1500990674459 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:14 INFO - 1500990674461 Marionette TRACE 366 <- [1,3,null,{}]
06:51:14 INFO - 1500990674463 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:14 INFO - 1500990674464 Marionette TRACE 366 <- [1,4,null,{}]
06:51:14 INFO - 1500990674467 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:51:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500990674509 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:51:14 INFO - 1500990674511 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674515 Marionette TRACE 366 <- [1,6,null,{}]
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674519 Marionette DEBUG Closed connection 366
06:51:14 INFO - 1500990674521 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49803
06:51:14 INFO - 1500990674522 Marionette DEBUG Closed connection 367
06:51:14 INFO - 1500990674524 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49804
06:51:14 INFO - 1500990674526 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:14 INFO - 1500990674528 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:14 INFO - 1500990674565 Marionette DEBUG Register listener.js for window 4294967297
06:51:14 INFO - 1500990674576 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f89dbd28-89ff-4afd-a377-09961b7ba7c2","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:14 INFO - 1500990674579 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674583 Marionette TRACE 368 <- [1,2,null,{}]
06:51:14 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:14 INFO - 1500990674587 Marionette DEBUG Closed connection 368
06:51:14 INFO - 1500990674589 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49805
06:51:14 INFO - 1500990674591 Marionette DEBUG Closed connection 369
06:51:14 INFO - 1500990674593 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49806
06:51:14 INFO - 1500990674596 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:14 INFO - 1500990674598 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:14 INFO - 1500990674633 Marionette DEBUG Register listener.js for window 4294967297
06:51:14 INFO - 1500990674644 Marionette TRACE 370 <- [1,1,null,{"sessionId":"75656ea2-97cc-4ab4-bbff-c199ee7b664d","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:14 INFO - 1500990674646 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:51:14 INFO - 1500990674660 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"
06:51:14 INFO - 1500990674665 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"
06:51:14 INFO - 1500990674665 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"
06:51:14 INFO - ++DOMWINDOW == 18 (000000DA26B0C000) [pid = 252] [serial = 28] [outer = 000000DA1AE1E000]
06:51:14 INFO - 1500990674687 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"
06:51:14 INFO - 1500990674695 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"
06:51:14 INFO - 1500990674706 Marionette TRACE 370 <- [1,2,null,{}]
06:51:14 INFO - 1500990674721 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:51:14 INFO - 1500990674731 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7a9f0a6-aad6-4733-8a95-04460a31a685","ELEMENT":"c7a9f0a6-aad6-4733-8a95-04460a31a685"}}]
06:51:14 INFO - 1500990674733 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c7a9f0a6-aad6-4733-8a95-04460a31a685"}]
06:51:14 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
06:51:14 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
06:51:14 INFO - [Child 252] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
06:51:15 INFO - 1500990675174 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:51:15 INFO - 1500990675176 Marionette TRACE 370 <- [1,4,null,{}]
06:51:15 INFO - 1500990675178 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c7a9f0a6-aad6-4733-8a95-04460a31a685","name":"selected"}]
06:51:15 INFO - 1500990675205 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:51:15 INFO - 1500990675208 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:51:15 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500990675215 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:51:15 INFO - 1500990675217 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 826ms
06:51:15 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:51:15 INFO - 1500990675221 Marionette TRACE 370 <- [1,7,null,{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - 1500990675225 Marionette DEBUG Closed connection 370
06:51:15 INFO - 1500990675227 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49807
06:51:15 INFO - 1500990675228 Marionette DEBUG Closed connection 371
06:51:15 INFO - 1500990675229 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49808
06:51:15 INFO - 1500990675231 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:15 INFO - 1500990675233 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:15 INFO - 1500990675255 Marionette DEBUG Register listener.js for window 4294967297
06:51:15 INFO - 1500990675267 Marionette TRACE 372 <- [1,1,null,{"sessionId":"70f8ab91-b3cf-4c78-b880-345639306792","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:15 INFO - 1500990675270 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:51:15 INFO - 1500990675271 Marionette TRACE 372 <- [1,2,null,{}]
06:51:15 INFO - 1500990675273 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:15 INFO - 1500990675274 Marionette TRACE 372 <- [1,3,null,{}]
06:51:15 INFO - 1500990675276 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:15 INFO - 1500990675278 Marionette TRACE 372 <- [1,4,null,{}]
06:51:15 INFO - 1500990675280 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:51:15 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500990675287 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:51:15 INFO - 1500990675290 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - 1500990675294 Marionette TRACE 372 <- [1,6,null,{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - 1500990675298 Marionette DEBUG Closed connection 372
06:51:15 INFO - 1500990675299 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49809
06:51:15 INFO - 1500990675301 Marionette DEBUG Closed connection 373
06:51:15 INFO - 1500990675302 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49810
06:51:15 INFO - 1500990675305 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:15 INFO - 1500990675307 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:15 INFO - 1500990675328 Marionette DEBUG Register listener.js for window 4294967297
06:51:15 INFO - 1500990675339 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3135f1ab-2fae-4545-8390-7b01d9e6558e","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:15 INFO - 1500990675342 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - 1500990675346 Marionette TRACE 374 <- [1,2,null,{}]
06:51:15 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:15 INFO - 1500990675350 Marionette DEBUG Closed connection 374
06:51:15 INFO - 1500990675352 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49811
06:51:15 INFO - 1500990675353 Marionette DEBUG Closed connection 375
06:51:15 INFO - 1500990675355 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49812
06:51:15 INFO - 1500990675357 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:15 INFO - 1500990675360 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:15 INFO - 1500990675383 Marionette DEBUG Register listener.js for window 4294967297
06:51:15 INFO - 1500990675394 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ebb357bd-5e52-404f-8164-91ccb057bd1d","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:15 INFO - 1500990675397 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:51:15 INFO - 1500990675401 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"
06:51:15 INFO - 1500990675407 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"
06:51:15 INFO - 1500990675408 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"
06:51:15 INFO - ++DOMWINDOW == 19 (000000DA2559D800) [pid = 252] [serial = 29] [outer = 000000DA1AE1E000]
06:51:15 INFO - 1500990675429 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"
06:51:15 INFO - 1500990675435 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"
06:51:15 INFO - 1500990675450 Marionette TRACE 376 <- [1,2,null,{}]
06:51:15 INFO - 1500990675465 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:51:15 INFO - 1500990675472 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d202376d-0771-4d39-9c69-5b46da78f98e","ELEMENT":"d202376d-0771-4d39-9c69-5b46da78f98e"}}]
06:51:15 INFO - 1500990675474 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d202376d-0771-4d39-9c69-5b46da78f98e"}]
06:51:15 INFO - 1500990675900 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:51:15 INFO - 1500990675902 Marionette TRACE 376 <- [1,4,null,{}]
06:51:15 INFO - 1500990675905 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:51:16 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500990676074 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:51:16 INFO - 1500990676078 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 863ms
06:51:16 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:51:16 INFO - 1500990676084 Marionette TRACE 376 <- [1,6,null,{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - 1500990676088 Marionette DEBUG Closed connection 376
06:51:16 INFO - 1500990676090 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49813
06:51:16 INFO - 1500990676091 Marionette DEBUG Closed connection 377
06:51:16 INFO - 1500990676093 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49814
06:51:16 INFO - 1500990676095 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:16 INFO - 1500990676097 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:16 INFO - 1500990676379 Marionette DEBUG Register listener.js for window 4294967297
06:51:16 INFO - 1500990676391 Marionette TRACE 378 <- [1,1,null,{"sessionId":"af3c4fb7-eddd-4253-9f86-eb3031ea01d5","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:16 INFO - 1500990676394 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:51:16 INFO - 1500990676396 Marionette TRACE 378 <- [1,2,null,{}]
06:51:16 INFO - 1500990676397 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:16 INFO - 1500990676399 Marionette TRACE 378 <- [1,3,null,{}]
06:51:16 INFO - 1500990676400 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:16 INFO - 1500990676402 Marionette TRACE 378 <- [1,4,null,{}]
06:51:16 INFO - 1500990676405 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:51:16 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500990676448 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:51:16 INFO - 1500990676450 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - 1500990676455 Marionette TRACE 378 <- [1,6,null,{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - 1500990676459 Marionette DEBUG Closed connection 378
06:51:16 INFO - 1500990676461 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49815
06:51:16 INFO - 1500990676469 Marionette DEBUG Closed connection 379
06:51:16 INFO - 1500990676471 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49816
06:51:16 INFO - 1500990676473 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:16 INFO - 1500990676476 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:16 INFO - 1500990676504 Marionette DEBUG Register listener.js for window 4294967297
06:51:16 INFO - 1500990676848 Marionette TRACE 380 <- [1,1,null,{"sessionId":"932ab533-5217-4c51-af22-c88b4d97f5ef","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:16 INFO - 1500990676851 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - 1500990676855 Marionette TRACE 380 <- [1,2,null,{}]
06:51:16 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:16 INFO - 1500990676861 Marionette DEBUG Closed connection 380
06:51:16 INFO - 1500990676863 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49817
06:51:16 INFO - 1500990676864 Marionette DEBUG Closed connection 381
06:51:16 INFO - 1500990676865 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49818
06:51:16 INFO - 1500990676868 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:16 INFO - 1500990676870 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:16 INFO - 1500990676894 Marionette DEBUG Register listener.js for window 4294967297
06:51:16 INFO - 1500990676905 Marionette TRACE 382 <- [1,1,null,{"sessionId":"809238d1-b319-4ab0-9337-801b7529d2d3","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:16 INFO - 1500990676908 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:51:16 INFO - 1500990676935 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"
06:51:16 INFO - 1500990676940 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"
06:51:16 INFO - 1500990676940 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"
06:51:16 INFO - ++DOMWINDOW == 20 (000000DA2683B800) [pid = 252] [serial = 30] [outer = 000000DA1AE1E000]
06:51:16 INFO - 1500990676965 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"
06:51:16 INFO - 1500990676970 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"
06:51:16 INFO - 1500990676981 Marionette TRACE 382 <- [1,2,null,{}]
06:51:16 INFO - 1500990676989 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:51:17 INFO - 1500990677000 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e62624aa-8391-4f27-9c4d-32bc8f86a82f","ELEMENT":"e62624aa-8391-4f27-9c4d-32bc8f86a82f"}}]
06:51:17 INFO - 1500990677002 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e62624aa-8391-4f27-9c4d-32bc8f86a82f"}]
06:51:17 INFO - 1500990677440 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:51:17 INFO - 1500990677442 Marionette TRACE 382 <- [1,4,null,{}]
06:51:17 INFO - 1500990677444 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:51:17 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500990677451 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:51:17 INFO - 1500990677453 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677458 Marionette TRACE 382 <- [1,6,null,{}]
06:51:17 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1373ms
06:51:17 INFO - TEST-START | test_click.py TestClick.test_input_file
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677462 Marionette DEBUG Closed connection 382
06:51:17 INFO - 1500990677464 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49819
06:51:17 INFO - 1500990677465 Marionette DEBUG Closed connection 383
06:51:17 INFO - 1500990677467 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49820
06:51:17 INFO - 1500990677469 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:17 INFO - 1500990677471 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:17 INFO - 1500990677493 Marionette DEBUG Register listener.js for window 4294967297
06:51:17 INFO - 1500990677504 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4efd9ff4-daf2-4991-84fd-807b24e60341","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:17 INFO - 1500990677507 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:51:17 INFO - 1500990677509 Marionette TRACE 384 <- [1,2,null,{}]
06:51:17 INFO - 1500990677511 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:17 INFO - 1500990677512 Marionette TRACE 384 <- [1,3,null,{}]
06:51:17 INFO - 1500990677514 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:17 INFO - 1500990677515 Marionette TRACE 384 <- [1,4,null,{}]
06:51:17 INFO - 1500990677518 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:51:17 INFO - TEST-START: test_click.py TestClick.test_input_file1500990677525 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:51:17 INFO - 1500990677527 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677531 Marionette TRACE 384 <- [1,6,null,{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677535 Marionette DEBUG Closed connection 384
06:51:17 INFO - 1500990677537 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49821
06:51:17 INFO - 1500990677538 Marionette DEBUG Closed connection 385
06:51:17 INFO - 1500990677540 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49822
06:51:17 INFO - 1500990677542 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:17 INFO - 1500990677544 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:17 INFO - 1500990677565 Marionette DEBUG Register listener.js for window 4294967297
06:51:17 INFO - 1500990677577 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e89efac4-5caa-4dc3-885b-44ee43557fac","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:17 INFO - 1500990677579 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677584 Marionette TRACE 386 <- [1,2,null,{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677588 Marionette DEBUG Closed connection 386
06:51:17 INFO - 1500990677589 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49823
06:51:17 INFO - 1500990677591 Marionette DEBUG Closed connection 387
06:51:17 INFO - 1500990677592 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49824
06:51:17 INFO - 1500990677594 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:17 INFO - 1500990677596 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:17 INFO - 1500990677618 Marionette DEBUG Register listener.js for window 4294967297
06:51:17 INFO - 1500990677629 Marionette TRACE 388 <- [1,1,null,{"sessionId":"a11213d9-6738-46c6-8e44-87a68b7a70e4","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:17 INFO - 1500990677632 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:51:17 INFO - 1500990677645 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"
06:51:17 INFO - 1500990677649 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"
06:51:17 INFO - 1500990677650 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"
06:51:17 INFO - ++DOMWINDOW == 21 (000000DA250CE800) [pid = 252] [serial = 31] [outer = 000000DA1AE1E000]
06:51:17 INFO - 1500990677679 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:51:17 INFO - 1500990677682 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:51:17 INFO - 1500990677687 Marionette TRACE 388 <- [1,2,null,{}]
06:51:17 INFO - 1500990677702 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:51:17 INFO - 1500990677709 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"229cfdb9-ba77-413f-a470-edfc6a663ccc","ELEMENT":"229cfdb9-ba77-413f-a470-edfc6a663ccc"}}]
06:51:17 INFO - 1500990677711 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"229cfdb9-ba77-413f-a470-edfc6a663ccc"}]
06:51:17 INFO - 1500990677862 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:51:17 INFO - 1500990677864 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:51:17 INFO - TEST-END: test_click.py TestClick.test_input_file1500990677872 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:51:17 INFO - 1500990677874 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 419ms
06:51:17 INFO - 1500990677878 Marionette TRACE 388 <- [1,6,null,{}]
06:51:17 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:51:17 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:17 INFO - 1500990677883 Marionette DEBUG Closed connection 388
06:51:17 INFO - 1500990677884 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49825
06:51:17 INFO - 1500990677886 Marionette DEBUG Closed connection 389
06:51:17 INFO - 1500990677888 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49826
06:51:17 INFO - 1500990677890 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:17 INFO - 1500990677892 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:17 INFO - 1500990677976 Marionette DEBUG Register listener.js for window 4294967297
06:51:17 INFO - 1500990677987 Marionette TRACE 390 <- [1,1,null,{"sessionId":"2417c648-a2c1-4b3a-9a4a-344d146367bc","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:17 INFO - 1500990677990 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:51:17 INFO - 1500990677992 Marionette TRACE 390 <- [1,2,null,{}]
06:51:17 INFO - 1500990677994 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:17 INFO - 1500990677995 Marionette TRACE 390 <- [1,3,null,{}]
06:51:18 INFO - 1500990677999 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:18 INFO - 1500990678000 Marionette TRACE 390 <- [1,4,null,{}]
06:51:18 INFO - 1500990678003 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:51:18 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500990678012 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:51:18 INFO - 1500990678014 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:51:18 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:18 INFO - 1500990678019 Marionette TRACE 390 <- [1,6,null,{}]
06:51:18 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:18 INFO - 1500990678024 Marionette DEBUG Closed connection 390
06:51:18 INFO - 1500990678025 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49827
06:51:18 INFO - 1500990678027 Marionette DEBUG Closed connection 391
06:51:18 INFO - 1500990678028 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49828
06:51:18 INFO - 1500990678031 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:18 INFO - 1500990678033 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:18 INFO - 1500990678062 Marionette DEBUG Register listener.js for window 4294967297
06:51:18 INFO - 1500990678088 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d4cf4a6d-e659-47a1-9941-3a4a8ce8936d","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:18 INFO - 1500990678091 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:51:18 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:18 INFO - 1500990678096 Marionette TRACE 392 <- [1,2,null,{}]
06:51:18 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:18 INFO - 1500990678100 Marionette DEBUG Closed connection 392
06:51:18 INFO - 1500990678101 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49829
06:51:18 INFO - 1500990678103 Marionette DEBUG Closed connection 393
06:51:18 INFO - 1500990678104 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49830
06:51:18 INFO - --DOMWINDOW == 20 (000000DA24D2F800) [pid = 252] [serial = 16] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:18 INFO - --DOMWINDOW == 19 (000000DA22DED000) [pid = 252] [serial = 15] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:18 INFO - --DOMWINDOW == 18 (000000DA22E8D800) [pid = 252] [serial = 14] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:18 INFO - --DOMWINDOW == 17 (000000DA1CAF0000) [pid = 252] [serial = 13] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:18 INFO - --DOMWINDOW == 16 (000000DA25447000) [pid = 252] [serial = 12] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
06:51:18 INFO - 1500990678108 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:18 INFO - 1500990678112 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:18 INFO - --DOMWINDOW == 15 (000000DA23A1C800) [pid = 252] [serial = 17] [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]
06:51:18 INFO - --DOMWINDOW == 14 (000000DA23712000) [pid = 252] [serial = 18] [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]
06:51:18 INFO - --DOMWINDOW == 13 (000000DA2371C800) [pid = 252] [serial = 19] [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]
06:51:18 INFO - --DOMWINDOW == 12 (000000DA23824000) [pid = 252] [serial = 20] [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]
06:51:18 INFO - --DOMWINDOW == 11 (000000DA26B08000) [pid = 252] [serial = 21] [outer = 0000000000000000] [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]
06:51:18 INFO - 1500990678136 Marionette DEBUG Register listener.js for window 4294967297
06:51:18 INFO - 1500990678147 Marionette TRACE 394 <- [1,1,null,{"sessionId":"21d648ef-0429-4660-a325-ab5c82d78715","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:18 INFO - 1500990678150 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:51:18 INFO - 1500990678496 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:51:18 INFO - 1500990678502 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:51:18 INFO - 1500990678503 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:51:18 INFO - ++DOMWINDOW == 12 (000000DA1C90E800) [pid = 252] [serial = 32] [outer = 000000DA1AE1E000]
06:51:18 INFO - 1500990678528 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"
06:51:18 INFO - 1500990678534 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"
06:51:18 INFO - 1500990678543 Marionette TRACE 394 <- [1,2,null,{}]
06:51:18 INFO - 1500990678558 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:51:18 INFO - 1500990678570 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6c6f705-4421-454f-a77f-31b51f15e8b9","ELEMENT":"b6c6f705-4421-454f-a77f-31b51f15e8b9"}}]
06:51:18 INFO - 1500990678572 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:51:18 INFO - 1500990678579 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b969bea-4293-4cbe-b0a8-5a07e27baba1","ELEMENT":"0b969bea-4293-4cbe-b0a8-5a07e27baba1"}}]
06:51:18 INFO - 1500990678581 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b6c6f705-4421-454f-a77f-31b51f15e8b9"}]
06:51:19 INFO - 1500990679050 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:51:19 INFO - 1500990679052 Marionette TRACE 394 <- [1,5,null,{}]
06:51:19 INFO - 1500990679055 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0b969bea-4293-4cbe-b0a8-5a07e27baba1"}]
06:51:19 INFO - 1500990679185 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:51:19 INFO - 1500990679188 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:51:19 INFO - 1500990679204 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:51:19 INFO - 1500990679207 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:51:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500990679213 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:51:19 INFO - 1500990679215 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679219 Marionette TRACE 394 <- [1,9,null,{}]
06:51:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1341ms
06:51:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679225 Marionette DEBUG Closed connection 394
06:51:19 INFO - 1500990679226 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49831
06:51:19 INFO - 1500990679228 Marionette DEBUG Closed connection 395
06:51:19 INFO - 1500990679230 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49832
06:51:19 INFO - 1500990679232 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:19 INFO - 1500990679234 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:19 INFO - 1500990679256 Marionette DEBUG Register listener.js for window 4294967297
06:51:19 INFO - 1500990679267 Marionette TRACE 396 <- [1,1,null,{"sessionId":"666c70c9-f639-4f3b-a489-addc5eefc0f4","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:19 INFO - 1500990679270 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:51:19 INFO - 1500990679272 Marionette TRACE 396 <- [1,2,null,{}]
06:51:19 INFO - 1500990679273 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:51:19 INFO - 1500990679275 Marionette TRACE 396 <- [1,3,null,{}]
06:51:19 INFO - 1500990679277 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:51:19 INFO - 1500990679278 Marionette TRACE 396 <- [1,4,null,{}]
06:51:19 INFO - 1500990679281 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:51:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500990679288 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:51:19 INFO - 1500990679290 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679294 Marionette TRACE 396 <- [1,6,null,{}]
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679299 Marionette DEBUG Closed connection 396
06:51:19 INFO - 1500990679301 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49833
06:51:19 INFO - 1500990679303 Marionette DEBUG Closed connection 397
06:51:19 INFO - 1500990679305 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49834
06:51:19 INFO - 1500990679307 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:51:19 INFO - 1500990679310 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:19 INFO - 1500990679333 Marionette DEBUG Register listener.js for window 4294967297
06:51:19 INFO - 1500990679344 Marionette TRACE 398 <- [1,1,null,{"sessionId":"ffd452fb-cf4a-43fe-a114-11dcbbeed8a0","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:19 INFO - 1500990679347 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679351 Marionette TRACE 398 <- [1,2,null,{}]
06:51:19 INFO - [Parent 2744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:51:19 INFO - 1500990679355 Marionette DEBUG Closed connection 398
06:51:19 INFO - 1500990679357 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49835
06:51:19 INFO - 1500990679358 Marionette DEBUG Closed connection 399
06:51:19 INFO - 1500990679360 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49836
06:51:19 INFO - 1500990679362 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:51:19 INFO - 1500990679364 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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}
06:51:19 INFO - 1500990679387 Marionette DEBUG Register listener.js for window 4294967297
06:51:19 INFO - 1500990679398 Marionette TRACE 400 <- [1,1,null,{"sessionId":"3a6c6318-b380-4898-b72a-e1d7f03dc28c","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":2744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-353.001\\AppData\\Local\\Temp\\tmpkgvavj.mozrunner","moz:accessibilityChecks":false}}]
06:51:19 INFO - 1500990679400 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:51:19 INFO - 1500990679404 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"
06:51:19 INFO - 1500990679409 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"
06:51:19 INFO - 1500990679410 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"
06:51:19 INFO - ++DOMWINDOW == 13 (000000DA23856800) [pid = 252] [serial = 33] [outer = 000000DA1AE1E000]
06:51:19 INFO - 1500990679436 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"
06:51:19 INFO - 1500990679440 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"
06:51:19 INFO - 1500990679453 Marionette TRACE 400 <- [1,2,null,{}]
06:51:19 INFO - 1500990679467 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:51:19 INFO - 1500990679473 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"136e83ba-360b-46bc-ab93-007842da98b6","ELEMENT":"136e83ba-360b-46bc-ab93-007842da98b6"}}]
06:51:19 INFO - 1500990679475 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"136e83ba-360b-46bc-ab93-007842da98b6"}]
06:51:19 INFO - 1500990679600 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:51:19 INFO - 1500990679602 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"136e83ba-360b-46bc-ab93-007842da98b6"}]
06:51:19 INFO - 1500990679742 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element