could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:17:43 INFO - 1499879863291 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}]
10:17:43 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499879863304 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:17:43 INFO - 1499879863306 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863311 Marionette TRACE 352 <- [1,6,null,{}]
10:17:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 823ms
10:17:43 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863334 Marionette DEBUG Closed connection 352
10:17:43 INFO - 1499879863336 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49799
10:17:43 INFO - 1499879863338 Marionette DEBUG Closed connection 353
10:17:43 INFO - 1499879863340 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49800
10:17:43 INFO - 1499879863343 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:43 INFO - 1499879863346 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:43 INFO - 1499879863369 Marionette DEBUG Register listener.js for window 15
10:17:43 INFO - 1499879863386 Marionette TRACE 354 <- [1,1,null,{"sessionId":"54c89732-7825-43db-994f-c3ca10fc3ae4","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:43 INFO - 1499879863399 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:17:43 INFO - 1499879863401 Marionette TRACE 354 <- [1,2,null,{}]
10:17:43 INFO - 1499879863403 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:43 INFO - 1499879863404 Marionette TRACE 354 <- [1,3,null,{}]
10:17:43 INFO - 1499879863411 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:43 INFO - 1499879863413 Marionette TRACE 354 <- [1,4,null,{}]
10:17:43 INFO - --DOMWINDOW == 21 (000000FF48511800) [pid = 3592] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
10:17:43 INFO - --DOMWINDOW == 20 (000000FF4B63C800) [pid = 3592] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
10:17:43 INFO - --DOMWINDOW == 19 (000000FF42D63000) [pid = 3592] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
10:17:43 INFO - --DOMWINDOW == 18 (000000FF49B09000) [pid = 3592] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
10:17:43 INFO - 1499879863422 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}]
10:17:43 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499879863433 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:17:43 INFO - 1499879863443 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863448 Marionette TRACE 354 <- [1,6,null,{}]
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863470 Marionette DEBUG Closed connection 354
10:17:43 INFO - 1499879863472 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49801
10:17:43 INFO - 1499879863475 Marionette DEBUG Closed connection 355
10:17:43 INFO - 1499879863477 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49802
10:17:43 INFO - 1499879863482 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:43 INFO - 1499879863487 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:43 INFO - 1499879863511 Marionette DEBUG Register listener.js for window 15
10:17:43 INFO - 1499879863528 Marionette TRACE 356 <- [1,1,null,{"sessionId":"90d7a228-84f8-42c8-90f0-09443fdaf174","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:43 INFO - 1499879863633 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863638 Marionette TRACE 356 <- [1,2,null,{}]
10:17:43 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:43 INFO - 1499879863663 Marionette DEBUG Closed connection 356
10:17:43 INFO - 1499879863669 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49803
10:17:43 INFO - 1499879863672 Marionette DEBUG Closed connection 357
10:17:43 INFO - 1499879863674 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49804
10:17:43 INFO - 1499879863678 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:43 INFO - 1499879863681 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:43 INFO - 1499879863704 Marionette DEBUG Register listener.js for window 15
10:17:43 INFO - 1499879863718 Marionette TRACE 358 <- [1,1,null,{"sessionId":"423a481f-9873-45d4-a507-85110f25f1b6","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:43 INFO - 1499879863726 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49334/clicks.html"}]
10:17:43 INFO - 1499879863732 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/hidden.html"
10:17:43 INFO - 1499879863747 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/hidden.html"
10:17:43 INFO - 1499879863748 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/hidden.html"
10:17:43 INFO - ++DOMWINDOW == 19 (000000FF3D45B000) [pid = 3592] [serial = 40] [outer = 000000FF499DE800]
10:17:43 INFO - 1499879863812 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/clicks.html"
10:17:43 INFO - 1499879863819 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/clicks.html"
10:17:43 INFO - 1499879863840 Marionette TRACE 358 <- [1,2,null,{}]
10:17:43 INFO - 1499879863853 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:17:43 INFO - 1499879863860 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22349b00-abda-4f42-8692-31440461c59c","ELEMENT":"22349b00-abda-4f42-8692-31440461c59c"}}]
10:17:43 INFO - 1499879863862 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"22349b00-abda-4f42-8692-31440461c59c"}]
10:17:44 INFO - 1499879864107 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/clicks.html"
10:17:44 INFO - [Parent 3592] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
10:17:44 INFO - 1499879864131 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/clicks.html"
10:17:44 INFO - 1499879864133 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/clicks.html"
10:17:44 INFO - ++DOMWINDOW == 20 (000000FF4EBD0800) [pid = 3592] [serial = 41] [outer = 000000FF499DE800]
10:17:44 INFO - 1499879864197 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/test.html"
10:17:44 INFO - 1499879864203 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/test.html"
10:17:44 INFO - 1499879864223 Marionette TRACE 358 <- [1,4,null,{}]
10:17:44 INFO - 1499879864234 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:17:44 INFO - 1499879864243 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a4a69dd-3122-4695-9384-de984ac75d66","ELEMENT":"5a4a69dd-3122-4695-9384-de984ac75d66"}}]
10:17:44 INFO - 1499879864245 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:17:44 INFO - 1499879864247 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:17:44 INFO - 1499879864249 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}]
10:17:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499879864257 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:17:44 INFO - 1499879864259 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:17:44 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:44 INFO - 1499879864264 Marionette TRACE 358 <- [1,8,null,{}]
10:17:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 952ms
10:17:44 INFO - TEST-START | test_click.py TestClick.test_container_element
10:17:44 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:44 INFO - 1499879864270 Marionette DEBUG Closed connection 358
10:17:44 INFO - 1499879864271 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49807
10:17:44 INFO - 1499879864273 Marionette DEBUG Closed connection 359
10:17:44 INFO - 1499879864275 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49808
10:17:44 INFO - 1499879864277 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:44 INFO - 1499879864279 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:44 INFO - 1499879864302 Marionette DEBUG Register listener.js for window 15
10:17:44 INFO - 1499879864313 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d675d23c-8009-4ec2-91ec-74c32b739013","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:44 INFO - 1499879864320 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:17:44 INFO - 1499879864321 Marionette TRACE 360 <- [1,2,null,{}]
10:17:44 INFO - 1499879864346 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:44 INFO - 1499879864347 Marionette TRACE 360 <- [1,3,null,{}]
10:17:45 INFO - 1499879865021 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:45 INFO - 1499879865023 Marionette TRACE 360 <- [1,4,null,{}]
10:17:45 INFO - 1499879865076 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}]
10:17:45 INFO - TEST-START: test_click.py TestClick.test_container_element1499879865086 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:17:45 INFO - 1499879865137 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865141 Marionette TRACE 360 <- [1,6,null,{}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865186 Marionette DEBUG Closed connection 360
10:17:45 INFO - 1499879865188 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49809
10:17:45 INFO - 1499879865200 Marionette DEBUG Closed connection 361
10:17:45 INFO - 1499879865202 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49810
10:17:45 INFO - 1499879865206 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:45 INFO - 1499879865209 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:45 INFO - 1499879865232 Marionette DEBUG Register listener.js for window 15
10:17:45 INFO - 1499879865243 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d03f64f4-d267-4e5f-9920-9b1026c3ed93","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:45 INFO - 1499879865252 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865256 Marionette TRACE 362 <- [1,2,null,{}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865279 Marionette DEBUG Closed connection 362
10:17:45 INFO - 1499879865280 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49811
10:17:45 INFO - 1499879865284 Marionette DEBUG Closed connection 363
10:17:45 INFO - 1499879865285 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49812
10:17:45 INFO - 1499879865288 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:45 INFO - 1499879865291 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:45 INFO - 1499879865313 Marionette DEBUG Register listener.js for window 15
10:17:45 INFO - 1499879865325 Marionette TRACE 364 <- [1,1,null,{"sessionId":"35aaf996-6092-4b80-8759-d09a8ba0cfe4","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:45 INFO - 1499879865331 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"}]
10:17:45 INFO - 1499879865335 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/test.html"
10:17:45 INFO - 1499879865342 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/test.html"
10:17:45 INFO - 1499879865342 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/test.html"
10:17:45 INFO - ++DOMWINDOW == 21 (000000FF4A116000) [pid = 3592] [serial = 42] [outer = 000000FF499DE800]
10:17:45 INFO - 1499879865397 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"
10:17:45 INFO - 1499879865407 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"
10:17:45 INFO - 1499879865428 Marionette TRACE 364 <- [1,2,null,{}]
10:17:45 INFO - 1499879865432 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:17:45 INFO - 1499879865440 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f5c94bd-b76b-405e-be0b-8a777a95d826","ELEMENT":"5f5c94bd-b76b-405e-be0b-8a777a95d826"}}]
10:17:45 INFO - 1499879865450 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5f5c94bd-b76b-405e-be0b-8a777a95d826"}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
10:17:45 INFO - 1499879865866 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:17:45 INFO - 1499879865868 Marionette TRACE 364 <- [1,4,null,{}]
10:17:45 INFO - 1499879865871 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5f5c94bd-b76b-405e-be0b-8a777a95d826","name":"selected"}]
10:17:45 INFO - 1499879865893 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:17:45 INFO - 1499879865902 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}]
10:17:45 INFO - TEST-END: test_click.py TestClick.test_container_element1499879865910 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:17:45 INFO - 1499879865914 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865918 Marionette TRACE 364 <- [1,7,null,{}]
10:17:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1654ms
10:17:45 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:17:45 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:45 INFO - 1499879865940 Marionette DEBUG Closed connection 364
10:17:45 INFO - 1499879865942 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49813
10:17:45 INFO - 1499879865946 Marionette DEBUG Closed connection 365
10:17:45 INFO - 1499879865947 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49814
10:17:45 INFO - 1499879865949 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:45 INFO - 1499879865951 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:45 INFO - 1499879865974 Marionette DEBUG Register listener.js for window 15
10:17:45 INFO - 1499879865986 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e2b84ac4-13e9-4cde-829b-3128205b4897","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:45 INFO - 1499879865992 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:17:45 INFO - 1499879865994 Marionette TRACE 366 <- [1,2,null,{}]
10:17:45 INFO - 1499879865995 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:45 INFO - 1499879865998 Marionette TRACE 366 <- [1,3,null,{}]
10:17:46 INFO - 1499879866006 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:46 INFO - 1499879866008 Marionette TRACE 366 <- [1,4,null,{}]
10:17:46 INFO - 1499879866011 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}]
10:17:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499879866019 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:17:46 INFO - 1499879866021 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:46 INFO - 1499879866025 Marionette TRACE 366 <- [1,6,null,{}]
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:46 INFO - 1499879866031 Marionette DEBUG Closed connection 366
10:17:46 INFO - 1499879866033 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49815
10:17:46 INFO - 1499879866034 Marionette DEBUG Closed connection 367
10:17:46 INFO - 1499879866036 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49816
10:17:46 INFO - 1499879866038 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:46 INFO - 1499879866041 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:46 INFO - 1499879866068 Marionette DEBUG Register listener.js for window 15
10:17:46 INFO - 1499879866082 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ab62d09c-37b6-46fc-ae8d-a70f79c06054","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:46 INFO - 1499879866110 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:46 INFO - 1499879866115 Marionette TRACE 368 <- [1,2,null,{}]
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:46 INFO - 1499879866135 Marionette DEBUG Closed connection 368
10:17:46 INFO - 1499879866137 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49817
10:17:46 INFO - 1499879866141 Marionette DEBUG Closed connection 369
10:17:46 INFO - 1499879866142 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49818
10:17:46 INFO - 1499879866145 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:46 INFO - 1499879866148 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:46 INFO - 1499879866171 Marionette DEBUG Register listener.js for window 15
10:17:46 INFO - 1499879866181 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ebe25ec6-c627-46be-8846-53cf42184684","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:46 INFO - 1499879866191 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"}]
10:17:46 INFO - 1499879866195 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"
10:17:46 INFO - 1499879866202 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"
10:17:46 INFO - 1499879866203 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"
10:17:46 INFO - ++DOMWINDOW == 22 (000000FF50F07000) [pid = 3592] [serial = 43] [outer = 000000FF499DE800]
10:17:46 INFO - 1499879866259 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"
10:17:46 INFO - 1499879866264 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"
10:17:46 INFO - 1499879866283 Marionette TRACE 370 <- [1,2,null,{}]
10:17:46 INFO - 1499879866290 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:17:46 INFO - 1499879866297 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a91426eb-f2f2-45ca-81c9-8ab51f9da663","ELEMENT":"a91426eb-f2f2-45ca-81c9-8ab51f9da663"}}]
10:17:46 INFO - 1499879866299 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a91426eb-f2f2-45ca-81c9-8ab51f9da663"}]
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
10:17:46 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
10:17:47 INFO - 1499879867218 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:17:47 INFO - 1499879867220 Marionette TRACE 370 <- [1,4,null,{}]
10:17:47 INFO - 1499879867231 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a91426eb-f2f2-45ca-81c9-8ab51f9da663","name":"selected"}]
10:17:47 INFO - 1499879867254 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:17:47 INFO - 1499879867256 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}]
10:17:47 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499879867264 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:17:47 INFO - 1499879867266 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867270 Marionette TRACE 370 <- [1,7,null,{}]
10:17:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1351ms
10:17:47 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867277 Marionette DEBUG Closed connection 370
10:17:47 INFO - 1499879867278 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49819
10:17:47 INFO - 1499879867280 Marionette DEBUG Closed connection 371
10:17:47 INFO - 1499879867281 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49820
10:17:47 INFO - 1499879867307 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:47 INFO - 1499879867309 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:47 INFO - 1499879867332 Marionette DEBUG Register listener.js for window 15
10:17:47 INFO - 1499879867343 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b8e4cda6-84ec-4c0d-af6a-eeada90c770b","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:47 INFO - 1499879867352 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:17:47 INFO - 1499879867353 Marionette TRACE 372 <- [1,2,null,{}]
10:17:47 INFO - 1499879867355 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:47 INFO - 1499879867356 Marionette TRACE 372 <- [1,3,null,{}]
10:17:47 INFO - 1499879867358 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:47 INFO - 1499879867360 Marionette TRACE 372 <- [1,4,null,{}]
10:17:47 INFO - 1499879867362 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}]
10:17:47 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499879867370 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:17:47 INFO - 1499879867372 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867376 Marionette TRACE 372 <- [1,6,null,{}]
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867382 Marionette DEBUG Closed connection 372
10:17:47 INFO - 1499879867384 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49821
10:17:47 INFO - 1499879867385 Marionette DEBUG Closed connection 373
10:17:47 INFO - 1499879867387 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49822
10:17:47 INFO - 1499879867389 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:47 INFO - 1499879867391 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:47 INFO - 1499879867414 Marionette DEBUG Register listener.js for window 15
10:17:47 INFO - 1499879867435 Marionette TRACE 374 <- [1,1,null,{"sessionId":"477c704b-5fe5-4cdb-9c41-75dbd508ca72","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:47 INFO - 1499879867441 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867445 Marionette TRACE 374 <- [1,2,null,{}]
10:17:47 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:47 INFO - 1499879867451 Marionette DEBUG Closed connection 374
10:17:47 INFO - 1499879867455 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49823
10:17:47 INFO - 1499879867456 Marionette DEBUG Closed connection 375
10:17:47 INFO - 1499879867458 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49824
10:17:47 INFO - 1499879867460 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:47 INFO - 1499879867463 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:47 INFO - 1499879867487 Marionette DEBUG Register listener.js for window 15
10:17:47 INFO - 1499879867541 Marionette TRACE 376 <- [1,1,null,{"sessionId":"db2ac0bd-ea46-45cf-926c-e679d9625b97","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:47 INFO - 1499879867548 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"}]
10:17:47 INFO - 1499879867552 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"
10:17:47 INFO - 1499879867559 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"
10:17:47 INFO - 1499879867560 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"
10:17:47 INFO - ++DOMWINDOW == 23 (000000FF50EC0800) [pid = 3592] [serial = 44] [outer = 000000FF499DE800]
10:17:47 INFO - 1499879867610 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"
10:17:47 INFO - 1499879867616 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"
10:17:47 INFO - 1499879867632 Marionette TRACE 376 <- [1,2,null,{}]
10:17:47 INFO - 1499879867641 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:17:47 INFO - 1499879867648 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1592dba-351e-4cc5-b727-a9648d600689","ELEMENT":"b1592dba-351e-4cc5-b727-a9648d600689"}}]
10:17:47 INFO - 1499879867650 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b1592dba-351e-4cc5-b727-a9648d600689"}]
10:17:48 INFO - 1499879868045 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:17:48 INFO - 1499879868047 Marionette TRACE 376 <- [1,4,null,{}]
10:17:48 INFO - 1499879868050 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}]
10:17:48 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499879868060 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:17:48 INFO - 1499879868062 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868067 Marionette TRACE 376 <- [1,6,null,{}]
10:17:48 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 797ms
10:17:48 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868091 Marionette DEBUG Closed connection 376
10:17:48 INFO - 1499879868092 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49825
10:17:48 INFO - 1499879868096 Marionette DEBUG Closed connection 377
10:17:48 INFO - 1499879868098 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49826
10:17:48 INFO - 1499879868100 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:48 INFO - 1499879868102 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:48 INFO - 1499879868125 Marionette DEBUG Register listener.js for window 15
10:17:48 INFO - 1499879868136 Marionette TRACE 378 <- [1,1,null,{"sessionId":"22ca3104-b9a6-4b1b-9c7d-6b4ed86dd515","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:48 INFO - 1499879868145 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:17:48 INFO - 1499879868147 Marionette TRACE 378 <- [1,2,null,{}]
10:17:48 INFO - 1499879868151 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:48 INFO - 1499879868152 Marionette TRACE 378 <- [1,3,null,{}]
10:17:48 INFO - 1499879868154 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:48 INFO - 1499879868156 Marionette TRACE 378 <- [1,4,null,{}]
10:17:48 INFO - 1499879868159 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}]
10:17:48 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499879868166 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:17:48 INFO - 1499879868168 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868172 Marionette TRACE 378 <- [1,6,null,{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868178 Marionette DEBUG Closed connection 378
10:17:48 INFO - 1499879868180 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49827
10:17:48 INFO - 1499879868181 Marionette DEBUG Closed connection 379
10:17:48 INFO - 1499879868183 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49828
10:17:48 INFO - 1499879868185 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:48 INFO - 1499879868187 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:48 INFO - 1499879868210 Marionette DEBUG Register listener.js for window 15
10:17:48 INFO - 1499879868221 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8daaa21a-a740-4023-a6c3-002bae77cee8","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:48 INFO - 1499879868250 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868254 Marionette TRACE 380 <- [1,2,null,{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868275 Marionette DEBUG Closed connection 380
10:17:48 INFO - 1499879868277 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49829
10:17:48 INFO - 1499879868280 Marionette DEBUG Closed connection 381
10:17:48 INFO - 1499879868285 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49830
10:17:48 INFO - 1499879868287 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:48 INFO - 1499879868289 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:48 INFO - 1499879868313 Marionette DEBUG Register listener.js for window 15
10:17:48 INFO - 1499879868325 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c52185b4-326c-4c6a-9cbd-ef5ab216de3f","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:48 INFO - 1499879868338 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"}]
10:17:48 INFO - 1499879868342 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"
10:17:48 INFO - 1499879868348 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"
10:17:48 INFO - 1499879868349 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"
10:17:48 INFO - ++DOMWINDOW == 24 (000000FF52395800) [pid = 3592] [serial = 45] [outer = 000000FF499DE800]
10:17:48 INFO - 1499879868396 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"
10:17:48 INFO - 1499879868405 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"
10:17:48 INFO - 1499879868422 Marionette TRACE 382 <- [1,2,null,{}]
10:17:48 INFO - 1499879868428 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:17:48 INFO - 1499879868436 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21d8b29d-fa95-46fa-9327-8dff4598d2a6","ELEMENT":"21d8b29d-fa95-46fa-9327-8dff4598d2a6"}}]
10:17:48 INFO - 1499879868440 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"21d8b29d-fa95-46fa-9327-8dff4598d2a6"}]
10:17:48 INFO - 1499879868897 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:17:48 INFO - 1499879868902 Marionette TRACE 382 <- [1,4,null,{}]
10:17:48 INFO - 1499879868956 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}]
10:17:48 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499879868971 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:17:48 INFO - 1499879868978 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:17:48 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:48 INFO - 1499879868988 Marionette TRACE 382 <- [1,6,null,{}]
10:17:48 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 922ms
10:17:48 INFO - TEST-START | test_click.py TestClick.test_input_file
10:17:49 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:49 INFO - 1499879868999 Marionette DEBUG Closed connection 382
10:17:49 INFO - 1499879869002 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49831
10:17:49 INFO - 1499879869007 Marionette DEBUG Closed connection 383
10:17:49 INFO - 1499879869010 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49832
10:17:49 INFO - 1499879869016 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:49 INFO - 1499879869027 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:49 INFO - 1499879869080 Marionette DEBUG Register listener.js for window 15
10:17:49 INFO - 1499879869114 Marionette TRACE 384 <- [1,1,null,{"sessionId":"baebc7ae-9759-4b31-b0e2-6d0ef42ec253","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879869129 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:17:50 INFO - 1499879869133 Marionette TRACE 384 <- [1,2,null,{}]
10:17:50 INFO - 1499879869158 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:50 INFO - 1499879869162 Marionette TRACE 384 <- [1,3,null,{}]
10:17:50 INFO - 1499879869165 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:50 INFO - 1499879869168 Marionette TRACE 384 <- [1,4,null,{}]
10:17:50 INFO - 1499879869171 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}]
10:17:50 INFO - TEST-START: test_click.py TestClick.test_input_file1499879869185 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:17:50 INFO - 1499879869188 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869194 Marionette TRACE 384 <- [1,6,null,{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869206 Marionette DEBUG Closed connection 384
10:17:50 INFO - 1499879869209 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49833
10:17:50 INFO - 1499879869242 Marionette DEBUG Closed connection 385
10:17:50 INFO - 1499879869245 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49834
10:17:50 INFO - 1499879869248 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:50 INFO - 1499879869252 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:50 INFO - 1499879869305 Marionette DEBUG Register listener.js for window 15
10:17:50 INFO - 1499879869322 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b3c8e595-a612-468d-968a-a7393235b1f2","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879869347 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869355 Marionette TRACE 386 <- [1,2,null,{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869382 Marionette DEBUG Closed connection 386
10:17:50 INFO - 1499879869384 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49835
10:17:50 INFO - 1499879869389 Marionette DEBUG Closed connection 387
10:17:50 INFO - 1499879869391 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49836
10:17:50 INFO - 1499879869418 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:50 INFO - 1499879869424 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:50 INFO - 1499879869477 Marionette DEBUG Register listener.js for window 15
10:17:50 INFO - 1499879869494 Marionette TRACE 388 <- [1,1,null,{"sessionId":"66ab397c-524f-4b5f-84ee-522213974f5c","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879869506 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:17:50 INFO - 1499879869512 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"
10:17:50 INFO - 1499879869524 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"
10:17:50 INFO - 1499879869527 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"
10:17:50 INFO - ++DOMWINDOW == 25 (000000FF499D5800) [pid = 3592] [serial = 46] [outer = 000000FF499DE800]
10:17:50 INFO - 1499879869595 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:17:50 INFO - 1499879869604 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:17:50 INFO - 1499879869637 Marionette TRACE 388 <- [1,2,null,{}]
10:17:50 INFO - 1499879869645 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:17:50 INFO - 1499879869656 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2230eddd-5e31-4c5b-b7d2-b76334760697","ELEMENT":"2230eddd-5e31-4c5b-b7d2-b76334760697"}}]
10:17:50 INFO - 1499879869659 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2230eddd-5e31-4c5b-b7d2-b76334760697"}]
10:17:50 INFO - 1499879869895 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:17:50 INFO - 1499879869912 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}]
10:17:50 INFO - TEST-END: test_click.py TestClick.test_input_file1499879869922 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:17:50 INFO - 1499879869926 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869931 Marionette TRACE 388 <- [1,6,null,{}]
10:17:50 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 942ms
10:17:50 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879869988 Marionette DEBUG Closed connection 388
10:17:50 INFO - 1499879869990 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49837
10:17:50 INFO - 1499879870010 Marionette DEBUG Closed connection 389
10:17:50 INFO - 1499879870013 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49838
10:17:50 INFO - 1499879870016 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:50 INFO - 1499879870019 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:50 INFO - 1499879870074 Marionette DEBUG Register listener.js for window 15
10:17:50 INFO - 1499879870089 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d58c5b4c-0fc9-4cdb-aabb-cb2e5ad8457d","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879870115 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:17:50 INFO - 1499879870117 Marionette TRACE 390 <- [1,2,null,{}]
10:17:50 INFO - 1499879870124 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:50 INFO - 1499879870126 Marionette TRACE 390 <- [1,3,null,{}]
10:17:50 INFO - 1499879870129 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:50 INFO - 1499879870132 Marionette TRACE 390 <- [1,4,null,{}]
10:17:50 INFO - 1499879870148 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}]
10:17:50 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499879870161 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:17:50 INFO - 1499879870163 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879870168 Marionette TRACE 390 <- [1,6,null,{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879870177 Marionette DEBUG Closed connection 390
10:17:50 INFO - 1499879870179 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49839
10:17:50 INFO - 1499879870182 Marionette DEBUG Closed connection 391
10:17:50 INFO - 1499879870184 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49840
10:17:50 INFO - 1499879870186 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:50 INFO - 1499879870193 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:50 INFO - 1499879870248 Marionette DEBUG Register listener.js for window 15
10:17:50 INFO - 1499879870266 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3f8173b9-3b2e-443f-9bc3-10de73104ca7","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879870284 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879870291 Marionette TRACE 392 <- [1,2,null,{}]
10:17:50 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:50 INFO - 1499879870342 Marionette DEBUG Closed connection 392
10:17:50 INFO - 1499879870348 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49841
10:17:50 INFO - 1499879870354 Marionette DEBUG Closed connection 393
10:17:50 INFO - 1499879870356 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49842
10:17:50 INFO - 1499879870377 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:50 INFO - 1499879870381 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:50 INFO - 1499879870440 Marionette DEBUG Register listener.js for window 15
10:17:50 INFO - 1499879870457 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1b408c05-9024-48e9-a92e-cec76ce94ec8","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:50 INFO - 1499879870470 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"}]
10:17:50 INFO - 1499879870475 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:17:50 INFO - 1499879870485 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:17:50 INFO - 1499879870486 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:17:50 INFO - ++DOMWINDOW == 26 (000000FF50EC2800) [pid = 3592] [serial = 47] [outer = 000000FF499DE800]
10:17:50 INFO - 1499879870796 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"
10:17:51 INFO - 1499879870805 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"
10:17:51 INFO - 1499879870824 Marionette TRACE 394 <- [1,2,null,{}]
10:17:51 INFO - 1499879870833 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:17:51 INFO - 1499879870845 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6593eb5b-3128-4992-8450-c902b94d2512","ELEMENT":"6593eb5b-3128-4992-8450-c902b94d2512"}}]
10:17:51 INFO - 1499879870850 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:17:51 INFO - 1499879870861 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c675955e-74fe-4894-be67-aea2bdcc7183","ELEMENT":"c675955e-74fe-4894-be67-aea2bdcc7183"}}]
10:17:51 INFO - 1499879870865 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"6593eb5b-3128-4992-8450-c902b94d2512"}]
10:17:51 INFO - 1499879871301 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:17:51 INFO - 1499879871304 Marionette TRACE 394 <- [1,5,null,{}]
10:17:51 INFO - 1499879871310 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c675955e-74fe-4894-be67-aea2bdcc7183"}]
10:17:51 INFO - 1499879871441 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:17:51 INFO - 1499879871453 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:17:51 INFO - 1499879871470 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:17:51 INFO - 1499879871484 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}]
10:17:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499879871499 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:17:51 INFO - 1499879871511 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871518 Marionette TRACE 394 <- [1,9,null,{}]
10:17:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1586ms
10:17:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871529 Marionette DEBUG Closed connection 394
10:17:51 INFO - 1499879871531 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49843
10:17:51 INFO - 1499879871555 Marionette DEBUG Closed connection 395
10:17:51 INFO - 1499879871574 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49844
10:17:51 INFO - 1499879871580 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:51 INFO - 1499879871589 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:51 INFO - 1499879871615 Marionette DEBUG Register listener.js for window 15
10:17:51 INFO - 1499879871633 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4cadbbdd-7c8c-472e-8288-4523ee8bbfcd","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:51 INFO - 1499879871647 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:17:51 INFO - 1499879871649 Marionette TRACE 396 <- [1,2,null,{}]
10:17:51 INFO - 1499879871704 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:17:51 INFO - 1499879871708 Marionette TRACE 396 <- [1,3,null,{}]
10:17:51 INFO - 1499879871716 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:17:51 INFO - 1499879871721 Marionette TRACE 396 <- [1,4,null,{}]
10:17:51 INFO - 1499879871729 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}]
10:17:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499879871740 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:17:51 INFO - --DOMWINDOW == 25 (000000FF4A118000) [pid = 3592] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%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]
10:17:51 INFO - --DOMWINDOW == 24 (000000FF499C3800) [pid = 3592] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%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]
10:17:51 INFO - --DOMWINDOW == 23 (000000FF3E75A800) [pid = 3592] [serial = 32] [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]
10:17:51 INFO - --DOMWINDOW == 22 (000000FF4D123800) [pid = 3592] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
10:17:51 INFO - 1499879871749 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871755 Marionette TRACE 396 <- [1,6,null,{}]
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871773 Marionette DEBUG Closed connection 396
10:17:51 INFO - 1499879871776 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49845
10:17:51 INFO - 1499879871800 Marionette DEBUG Closed connection 397
10:17:51 INFO - 1499879871802 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49846
10:17:51 INFO - 1499879871829 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:17:51 INFO - 1499879871832 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:51 INFO - 1499879871860 Marionette DEBUG Register listener.js for window 15
10:17:51 INFO - 1499879871876 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7250a00f-1b54-4bcf-bfc1-458a4b25982e","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:51 INFO - 1499879871886 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871892 Marionette TRACE 398 <- [1,2,null,{}]
10:17:51 INFO - [Parent 3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:17:51 INFO - 1499879871920 Marionette DEBUG Closed connection 398
10:17:51 INFO - 1499879871922 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49847
10:17:52 INFO - 1499879872046 Marionette DEBUG Closed connection 399
10:17:52 INFO - 1499879872048 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49848
10:17:52 INFO - 1499879872054 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:17:52 INFO - 1499879872058 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}
10:17:52 INFO - 1499879872087 Marionette DEBUG Register listener.js for window 15
10:17:52 INFO - 1499879872102 Marionette TRACE 400 <- [1,1,null,{"sessionId":"61b98623-cdfb-4c90-93d8-653d9503bae0","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-299.001\\AppData\\Local\\Temp\\tmplzbmdo.mozrunner","moz:accessibilityChecks":false}}]
10:17:52 INFO - 1499879872112 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"}]
10:17:52 INFO - 1499879872118 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"
10:17:52 INFO - 1499879872127 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"
10:17:52 INFO - 1499879872128 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"
10:17:52 INFO - ++DOMWINDOW == 23 (000000FF454E8800) [pid = 3592] [serial = 48] [outer = 000000FF499DE800]
10:17:52 INFO - 1499879872192 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"
10:17:52 INFO - 1499879872198 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"
10:17:52 INFO - 1499879872214 Marionette TRACE 400 <- [1,2,null,{}]
10:17:52 INFO - 1499879872219 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:17:52 INFO - 1499879872227 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6e97c91-e3ca-4edc-9347-36b8728892d9","ELEMENT":"b6e97c91-e3ca-4edc-9347-36b8728892d9"}}]
10:17:52 INFO - 1499879872229 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b6e97c91-e3ca-4edc-9347-36b8728892d9"}]
10:17:52 INFO - 1499879872351 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:17:52 INFO - 1499879872370 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b6e97c91-e3ca-4edc-9347-36b8728892d9"}]
10:17:52 INFO - 1499879872493 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element