could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
17:28:13 INFO - 1500942493249 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}]
17:28:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 920ms
17:28:13 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:28:13 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500942493260 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:28:13 INFO - 1500942493262 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493266 Marionette TRACE 352 <- [1,6,null,{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493270 Marionette DEBUG Closed connection 352
17:28:13 INFO - 1500942493272 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
17:28:13 INFO - 1500942493274 Marionette DEBUG Closed connection 353
17:28:13 INFO - 1500942493275 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
17:28:13 INFO - 1500942493277 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:13 INFO - 1500942493280 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:13 INFO - 1500942493301 Marionette DEBUG Register listener.js for window 4294967297
17:28:13 INFO - 1500942493313 Marionette TRACE 354 <- [1,1,null,{"sessionId":"27a89ab0-ef44-450d-a6fa-bb624a107d75","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:13 INFO - 1500942493316 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:28:13 INFO - 1500942493317 Marionette TRACE 354 <- [1,2,null,{}]
17:28:13 INFO - 1500942493319 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:13 INFO - 1500942493320 Marionette TRACE 354 <- [1,3,null,{}]
17:28:13 INFO - 1500942493322 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:13 INFO - 1500942493323 Marionette TRACE 354 <- [1,4,null,{}]
17:28:13 INFO - 1500942493333 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}]
17:28:13 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500942493341 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:28:13 INFO - 1500942493343 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493347 Marionette TRACE 354 <- [1,6,null,{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493351 Marionette DEBUG Closed connection 354
17:28:13 INFO - 1500942493352 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
17:28:13 INFO - 1500942493354 Marionette DEBUG Closed connection 355
17:28:13 INFO - 1500942493355 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
17:28:13 INFO - 1500942493362 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:13 INFO - 1500942493364 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:13 INFO - 1500942493385 Marionette DEBUG Register listener.js for window 4294967297
17:28:13 INFO - 1500942493396 Marionette TRACE 356 <- [1,1,null,{"sessionId":"dc37b306-e798-437d-97f7-bbfb62f57d1b","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:13 INFO - 1500942493400 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493404 Marionette TRACE 356 <- [1,2,null,{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493409 Marionette DEBUG Closed connection 356
17:28:13 INFO - 1500942493411 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
17:28:13 INFO - 1500942493413 Marionette DEBUG Closed connection 357
17:28:13 INFO - 1500942493414 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
17:28:13 INFO - 1500942493416 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:13 INFO - 1500942493420 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:13 INFO - 1500942493442 Marionette DEBUG Register listener.js for window 4294967297
17:28:13 INFO - 1500942493457 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a6eb63eb-5f84-49b6-9dde-6b8e007fa331","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:13 INFO - --DOMWINDOW == 17 (0000006A38450000) [pid = 3720] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
17:28:13 INFO - --DOMWINDOW == 16 (0000006A34311800) [pid = 3720] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
17:28:13 INFO - --DOMWINDOW == 15 (0000006A386D2000) [pid = 3720] [serial = 8] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
17:28:13 INFO - --DOMWINDOW == 14 (0000006A3843F800) [pid = 3720] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
17:28:13 INFO - 1500942493460 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49337/clicks.html"}]
17:28:13 INFO - 1500942493464 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/hidden.html"
17:28:13 INFO - 1500942493486 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/hidden.html"
17:28:13 INFO - 1500942493487 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/hidden.html"
17:28:13 INFO - ++DOMWINDOW == 15 (0000006A34305000) [pid = 3720] [serial = 25] [outer = 0000006A2D22F800]
17:28:13 INFO - 1500942493512 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49337/clicks.html"
17:28:13 INFO - 1500942493523 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49337/clicks.html"
17:28:13 INFO - 1500942493528 Marionette TRACE 358 <- [1,2,null,{}]
17:28:13 INFO - 1500942493544 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:28:13 INFO - 1500942493552 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff3ed258-2733-4def-aaed-f9d936b345bc","ELEMENT":"ff3ed258-2733-4def-aaed-f9d936b345bc"}}]
17:28:13 INFO - 1500942493555 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"ff3ed258-2733-4def-aaed-f9d936b345bc"}]
17:28:13 INFO - 1500942493773 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/clicks.html"
17:28:13 INFO - 1500942493790 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/clicks.html"
17:28:13 INFO - 1500942493791 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/clicks.html"
17:28:13 INFO - ++DOMWINDOW == 16 (0000006A38441000) [pid = 3720] [serial = 26] [outer = 0000006A2D22F800]
17:28:13 INFO - 1500942493814 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49337/test.html"
17:28:13 INFO - 1500942493822 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49337/test.html"
17:28:13 INFO - 1500942493834 Marionette TRACE 358 <- [1,4,null,{}]
17:28:13 INFO - 1500942493850 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:28:13 INFO - 1500942493857 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"57820987-8ebb-4738-8805-e30a05ec328c","ELEMENT":"57820987-8ebb-4738-8805-e30a05ec328c"}}]
17:28:13 INFO - 1500942493859 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:28:13 INFO - 1500942493860 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:28:13 INFO - 1500942493862 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}]
17:28:13 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500942493869 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:28:13 INFO - 1500942493871 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493875 Marionette TRACE 358 <- [1,8,null,{}]
17:28:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 609ms
17:28:13 INFO - TEST-START | test_click.py TestClick.test_container_element
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493879 Marionette DEBUG Closed connection 358
17:28:13 INFO - 1500942493881 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
17:28:13 INFO - 1500942493883 Marionette DEBUG Closed connection 359
17:28:13 INFO - 1500942493884 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
17:28:13 INFO - 1500942493886 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:13 INFO - 1500942493888 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:13 INFO - 1500942493910 Marionette DEBUG Register listener.js for window 4294967297
17:28:13 INFO - 1500942493920 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cdad71c4-e5de-4c4b-af91-bf24b75f182a","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:13 INFO - 1500942493923 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:28:13 INFO - 1500942493925 Marionette TRACE 360 <- [1,2,null,{}]
17:28:13 INFO - 1500942493927 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:13 INFO - 1500942493928 Marionette TRACE 360 <- [1,3,null,{}]
17:28:13 INFO - 1500942493930 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:13 INFO - 1500942493932 Marionette TRACE 360 <- [1,4,null,{}]
17:28:13 INFO - 1500942493935 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}]
17:28:13 INFO - TEST-START: test_click.py TestClick.test_container_element1500942493942 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:28:13 INFO - 1500942493944 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493948 Marionette TRACE 360 <- [1,6,null,{}]
17:28:13 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:13 INFO - 1500942493952 Marionette DEBUG Closed connection 360
17:28:13 INFO - 1500942493954 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
17:28:13 INFO - 1500942493956 Marionette DEBUG Closed connection 361
17:28:13 INFO - 1500942493957 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
17:28:13 INFO - 1500942493959 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:13 INFO - 1500942493961 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:13 INFO - 1500942493983 Marionette DEBUG Register listener.js for window 4294967297
17:28:14 INFO - 1500942493993 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a9439ed9-9d6a-40cd-a9f5-9f9937fddc3a","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:14 INFO - 1500942493996 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494000 Marionette TRACE 362 <- [1,2,null,{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494004 Marionette DEBUG Closed connection 362
17:28:14 INFO - 1500942494006 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
17:28:14 INFO - 1500942494007 Marionette DEBUG Closed connection 363
17:28:14 INFO - 1500942494009 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
17:28:14 INFO - 1500942494011 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:14 INFO - 1500942494013 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:14 INFO - 1500942494035 Marionette DEBUG Register listener.js for window 4294967297
17:28:14 INFO - 1500942494046 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0bd40131-43ec-412e-bf66-c3556c7e1a66","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:14 INFO - 1500942494049 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"}]
17:28:14 INFO - 1500942494053 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/test.html"
17:28:14 INFO - 1500942494058 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/test.html"
17:28:14 INFO - 1500942494058 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/test.html"
17:28:14 INFO - ++DOMWINDOW == 17 (0000006A3B206800) [pid = 3720] [serial = 27] [outer = 0000006A2D22F800]
17:28:14 INFO - 1500942494081 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494088 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494112 Marionette TRACE 364 <- [1,2,null,{}]
17:28:14 INFO - 1500942494129 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:28:14 INFO - 1500942494135 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b53d6377-47ce-43c3-bf7b-4a57c0a93c84","ELEMENT":"b53d6377-47ce-43c3-bf7b-4a57c0a93c84"}}]
17:28:14 INFO - 1500942494162 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b53d6377-47ce-43c3-bf7b-4a57c0a93c84"}]
17:28:14 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
17:28:14 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
17:28:14 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
17:28:14 INFO - 1500942494591 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:28:14 INFO - 1500942494593 Marionette TRACE 364 <- [1,4,null,{}]
17:28:14 INFO - 1500942494595 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b53d6377-47ce-43c3-bf7b-4a57c0a93c84","name":"selected"}]
17:28:14 INFO - 1500942494622 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:28:14 INFO - 1500942494624 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}]
17:28:14 INFO - TEST-END: test_click.py TestClick.test_container_element1500942494632 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:28:14 INFO - 1500942494633 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494638 Marionette TRACE 364 <- [1,7,null,{}]
17:28:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 768ms
17:28:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494649 Marionette DEBUG Closed connection 364
17:28:14 INFO - 1500942494650 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
17:28:14 INFO - 1500942494654 Marionette DEBUG Closed connection 365
17:28:14 INFO - 1500942494655 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
17:28:14 INFO - 1500942494658 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:14 INFO - 1500942494660 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:14 INFO - 1500942494688 Marionette DEBUG Register listener.js for window 4294967297
17:28:14 INFO - 1500942494712 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1d007dd3-219c-45e6-b198-c5f9d7f74bce","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:14 INFO - 1500942494714 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:28:14 INFO - 1500942494716 Marionette TRACE 366 <- [1,2,null,{}]
17:28:14 INFO - 1500942494718 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:14 INFO - 1500942494719 Marionette TRACE 366 <- [1,3,null,{}]
17:28:14 INFO - 1500942494721 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:14 INFO - 1500942494722 Marionette TRACE 366 <- [1,4,null,{}]
17:28:14 INFO - 1500942494725 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}]
17:28:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500942494732 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:28:14 INFO - 1500942494734 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494739 Marionette TRACE 366 <- [1,6,null,{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494743 Marionette DEBUG Closed connection 366
17:28:14 INFO - 1500942494744 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
17:28:14 INFO - 1500942494746 Marionette DEBUG Closed connection 367
17:28:14 INFO - 1500942494747 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
17:28:14 INFO - 1500942494749 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:14 INFO - 1500942494752 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:14 INFO - 1500942494774 Marionette DEBUG Register listener.js for window 4294967297
17:28:14 INFO - 1500942494785 Marionette TRACE 368 <- [1,1,null,{"sessionId":"60ae20b7-1b53-4f66-9087-8c5b4d1a7620","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:14 INFO - 1500942494788 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494792 Marionette TRACE 368 <- [1,2,null,{}]
17:28:14 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:14 INFO - 1500942494797 Marionette DEBUG Closed connection 368
17:28:14 INFO - 1500942494798 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
17:28:14 INFO - 1500942494800 Marionette DEBUG Closed connection 369
17:28:14 INFO - 1500942494801 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
17:28:14 INFO - 1500942494804 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:14 INFO - 1500942494806 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:14 INFO - 1500942494828 Marionette DEBUG Register listener.js for window 4294967297
17:28:14 INFO - 1500942494840 Marionette TRACE 370 <- [1,1,null,{"sessionId":"96427802-b83d-493f-8a41-47043aaf44cd","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:14 INFO - 1500942494843 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"}]
17:28:14 INFO - 1500942494846 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494851 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494852 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - ++DOMWINDOW == 18 (0000006A3B220000) [pid = 3720] [serial = 28] [outer = 0000006A2D22F800]
17:28:14 INFO - 1500942494877 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494881 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:14 INFO - 1500942494889 Marionette TRACE 370 <- [1,2,null,{}]
17:28:14 INFO - 1500942494905 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:28:14 INFO - 1500942494911 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5efc7f76-e6de-49ab-8bd1-cb4547ee8497","ELEMENT":"5efc7f76-e6de-49ab-8bd1-cb4547ee8497"}}]
17:28:14 INFO - 1500942494913 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"5efc7f76-e6de-49ab-8bd1-cb4547ee8497"}]
17:28:15 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
17:28:15 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
17:28:15 INFO - [Child 3720] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
17:28:15 INFO - 1500942495765 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:28:15 INFO - 1500942495767 Marionette TRACE 370 <- [1,4,null,{}]
17:28:15 INFO - 1500942495769 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"5efc7f76-e6de-49ab-8bd1-cb4547ee8497","name":"selected"}]
17:28:15 INFO - 1500942495841 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:28:15 INFO - 1500942495845 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}]
17:28:15 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500942495892 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:28:15 INFO - 1500942495894 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:28:15 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:15 INFO - 1500942495898 Marionette TRACE 370 <- [1,7,null,{}]
17:28:15 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1254ms
17:28:15 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:28:15 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:15 INFO - 1500942495903 Marionette DEBUG Closed connection 370
17:28:15 INFO - 1500942495905 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
17:28:15 INFO - 1500942495906 Marionette DEBUG Closed connection 371
17:28:15 INFO - 1500942495908 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
17:28:15 INFO - 1500942495910 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:15 INFO - 1500942495912 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:15 INFO - 1500942495948 Marionette DEBUG Register listener.js for window 4294967297
17:28:15 INFO - 1500942495959 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d79faf84-4119-4172-8613-d5d677da5de8","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:15 INFO - 1500942495962 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:28:15 INFO - 1500942495963 Marionette TRACE 372 <- [1,2,null,{}]
17:28:15 INFO - 1500942495965 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:15 INFO - 1500942495966 Marionette TRACE 372 <- [1,3,null,{}]
17:28:15 INFO - 1500942495968 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:15 INFO - 1500942495970 Marionette TRACE 372 <- [1,4,null,{}]
17:28:15 INFO - 1500942495973 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}]
17:28:15 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500942495982 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:28:15 INFO - 1500942495984 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:28:15 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:15 INFO - 1500942495988 Marionette TRACE 372 <- [1,6,null,{}]
17:28:15 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:15 INFO - 1500942495993 Marionette DEBUG Closed connection 372
17:28:15 INFO - 1500942495994 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
17:28:15 INFO - 1500942495996 Marionette DEBUG Closed connection 373
17:28:15 INFO - 1500942495998 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
17:28:16 INFO - 1500942496000 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:16 INFO - 1500942496002 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:16 INFO - 1500942496027 Marionette DEBUG Register listener.js for window 4294967297
17:28:16 INFO - 1500942496038 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5ba74916-d348-48d9-b339-1cba20708a05","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:16 INFO - 1500942496041 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496045 Marionette TRACE 374 <- [1,2,null,{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496049 Marionette DEBUG Closed connection 374
17:28:16 INFO - 1500942496051 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
17:28:16 INFO - 1500942496052 Marionette DEBUG Closed connection 375
17:28:16 INFO - 1500942496053 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
17:28:16 INFO - 1500942496056 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:16 INFO - 1500942496058 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:16 INFO - 1500942496080 Marionette DEBUG Register listener.js for window 4294967297
17:28:16 INFO - 1500942496091 Marionette TRACE 376 <- [1,1,null,{"sessionId":"13403008-b24c-42a2-aa8f-c05c11da9cd1","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:16 INFO - 1500942496094 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"}]
17:28:16 INFO - 1500942496098 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:16 INFO - 1500942496103 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:16 INFO - 1500942496104 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:16 INFO - ++DOMWINDOW == 19 (0000006A39143000) [pid = 3720] [serial = 29] [outer = 0000006A2D22F800]
17:28:16 INFO - 1500942496128 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:28:16 INFO - 1500942496133 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:28:16 INFO - 1500942496145 Marionette TRACE 376 <- [1,2,null,{}]
17:28:16 INFO - 1500942496161 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:28:16 INFO - 1500942496167 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f3359835-82e0-4de2-9b86-59bcfa7a4654","ELEMENT":"f3359835-82e0-4de2-9b86-59bcfa7a4654"}}]
17:28:16 INFO - 1500942496169 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f3359835-82e0-4de2-9b86-59bcfa7a4654"}]
17:28:16 INFO - 1500942496592 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:28:16 INFO - 1500942496593 Marionette TRACE 376 <- [1,4,null,{}]
17:28:16 INFO - 1500942496596 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}]
17:28:16 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500942496603 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:28:16 INFO - 1500942496605 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496610 Marionette TRACE 376 <- [1,6,null,{}]
17:28:16 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 711ms
17:28:16 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496614 Marionette DEBUG Closed connection 376
17:28:16 INFO - 1500942496615 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
17:28:16 INFO - 1500942496617 Marionette DEBUG Closed connection 377
17:28:16 INFO - 1500942496618 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
17:28:16 INFO - 1500942496622 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:16 INFO - 1500942496624 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:16 INFO - 1500942496645 Marionette DEBUG Register listener.js for window 4294967297
17:28:16 INFO - 1500942496656 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f84abf2a-3c20-4a67-bc0f-afdddc17992a","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:16 INFO - 1500942496660 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:28:16 INFO - 1500942496661 Marionette TRACE 378 <- [1,2,null,{}]
17:28:16 INFO - 1500942496663 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:16 INFO - 1500942496665 Marionette TRACE 378 <- [1,3,null,{}]
17:28:16 INFO - 1500942496666 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:16 INFO - 1500942496668 Marionette TRACE 378 <- [1,4,null,{}]
17:28:16 INFO - 1500942496671 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}]
17:28:16 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500942496678 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:28:16 INFO - 1500942496680 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496684 Marionette TRACE 378 <- [1,6,null,{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496689 Marionette DEBUG Closed connection 378
17:28:16 INFO - 1500942496691 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
17:28:16 INFO - 1500942496693 Marionette DEBUG Closed connection 379
17:28:16 INFO - 1500942496695 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
17:28:16 INFO - 1500942496697 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:16 INFO - 1500942496699 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:16 INFO - 1500942496721 Marionette DEBUG Register listener.js for window 4294967297
17:28:16 INFO - 1500942496732 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3a122b00-7bfe-4cf1-be89-ac68b7c058ce","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:16 INFO - 1500942496735 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496739 Marionette TRACE 380 <- [1,2,null,{}]
17:28:16 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:16 INFO - 1500942496743 Marionette DEBUG Closed connection 380
17:28:16 INFO - 1500942496745 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
17:28:16 INFO - 1500942496746 Marionette DEBUG Closed connection 381
17:28:16 INFO - 1500942496748 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
17:28:16 INFO - 1500942496750 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:16 INFO - 1500942496752 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:16 INFO - 1500942496774 Marionette DEBUG Register listener.js for window 4294967297
17:28:16 INFO - 1500942496785 Marionette TRACE 382 <- [1,1,null,{"sessionId":"149588f4-351c-4ad3-a28a-ddde6bb39bdc","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:16 INFO - 1500942496788 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"}]
17:28:16 INFO - 1500942496803 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:28:16 INFO - 1500942496807 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:28:16 INFO - 1500942496808 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
17:28:16 INFO - ++DOMWINDOW == 20 (0000006A3B095000) [pid = 3720] [serial = 30] [outer = 0000006A2D22F800]
17:28:16 INFO - 1500942496830 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:16 INFO - 1500942496837 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:16 INFO - 1500942496848 Marionette TRACE 382 <- [1,2,null,{}]
17:28:16 INFO - 1500942496862 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:28:16 INFO - 1500942496869 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"700855a0-88ad-40b3-8fbf-4c2ca1cbe669","ELEMENT":"700855a0-88ad-40b3-8fbf-4c2ca1cbe669"}}]
17:28:16 INFO - 1500942496872 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"700855a0-88ad-40b3-8fbf-4c2ca1cbe669"}]
17:28:17 INFO - 1500942497307 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:28:17 INFO - 1500942497309 Marionette TRACE 382 <- [1,4,null,{}]
17:28:17 INFO - 1500942497311 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}]
17:28:17 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500942497318 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:28:17 INFO - 1500942497320 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 713ms
17:28:17 INFO - 1500942497324 Marionette TRACE 382 <- [1,6,null,{}]
17:28:17 INFO - TEST-START | test_click.py TestClick.test_input_file
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - 1500942497328 Marionette DEBUG Closed connection 382
17:28:17 INFO - 1500942497330 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
17:28:17 INFO - 1500942497331 Marionette DEBUG Closed connection 383
17:28:17 INFO - 1500942497332 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
17:28:17 INFO - 1500942497335 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:17 INFO - 1500942497337 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:17 INFO - 1500942497358 Marionette DEBUG Register listener.js for window 4294967297
17:28:17 INFO - 1500942497369 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d58d43ba-235e-49d0-9f45-9235fa2999c7","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:17 INFO - 1500942497372 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:28:17 INFO - 1500942497373 Marionette TRACE 384 <- [1,2,null,{}]
17:28:17 INFO - 1500942497376 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:17 INFO - 1500942497377 Marionette TRACE 384 <- [1,3,null,{}]
17:28:17 INFO - 1500942497379 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:17 INFO - 1500942497381 Marionette TRACE 384 <- [1,4,null,{}]
17:28:17 INFO - 1500942497383 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}]
17:28:17 INFO - TEST-START: test_click.py TestClick.test_input_file1500942497390 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:28:17 INFO - 1500942497392 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - 1500942497396 Marionette TRACE 384 <- [1,6,null,{}]
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - 1500942497400 Marionette DEBUG Closed connection 384
17:28:17 INFO - 1500942497402 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
17:28:17 INFO - 1500942497404 Marionette DEBUG Closed connection 385
17:28:17 INFO - 1500942497405 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
17:28:17 INFO - 1500942497407 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:17 INFO - 1500942497409 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:17 INFO - 1500942497718 Marionette DEBUG Register listener.js for window 4294967297
17:28:17 INFO - 1500942497729 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67df66bc-85f9-4053-b036-dc18495b32d0","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:17 INFO - 1500942497732 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - 1500942497736 Marionette TRACE 386 <- [1,2,null,{}]
17:28:17 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:17 INFO - 1500942497740 Marionette DEBUG Closed connection 386
17:28:17 INFO - 1500942497741 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
17:28:17 INFO - 1500942497743 Marionette DEBUG Closed connection 387
17:28:17 INFO - 1500942497744 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
17:28:17 INFO - 1500942497747 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:17 INFO - 1500942497749 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:17 INFO - 1500942497786 Marionette DEBUG Register listener.js for window 4294967297
17:28:17 INFO - 1500942497797 Marionette TRACE 388 <- [1,1,null,{"sessionId":"381b5fb9-93c5-450c-8515-2dbf0cdf6eeb","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:17 INFO - 1500942497800 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:28:17 INFO - 1500942497846 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:17 INFO - 1500942497851 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:17 INFO - 1500942497852 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
17:28:17 INFO - ++DOMWINDOW == 21 (0000006A3B0A1000) [pid = 3720] [serial = 31] [outer = 0000006A2D22F800]
17:28:17 INFO - 1500942497879 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:28:17 INFO - 1500942497883 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:28:17 INFO - 1500942497888 Marionette TRACE 388 <- [1,2,null,{}]
17:28:17 INFO - 1500942497896 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:28:17 INFO - 1500942497925 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"de34b505-5687-421e-aa02-b0deffb40b55","ELEMENT":"de34b505-5687-421e-aa02-b0deffb40b55"}}]
17:28:17 INFO - 1500942497928 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"de34b505-5687-421e-aa02-b0deffb40b55"}]
17:28:18 INFO - 1500942498077 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
17:28:18 INFO - 1500942498079 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}]
17:28:18 INFO - TEST-END: test_click.py TestClick.test_input_file1500942498114 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:28:18 INFO - 1500942498116 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498121 Marionette TRACE 388 <- [1,6,null,{}]
17:28:18 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 796ms
17:28:18 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498125 Marionette DEBUG Closed connection 388
17:28:18 INFO - 1500942498126 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
17:28:18 INFO - 1500942498128 Marionette DEBUG Closed connection 389
17:28:18 INFO - 1500942498129 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
17:28:18 INFO - 1500942498132 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:18 INFO - 1500942498134 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:18 INFO - 1500942498157 Marionette DEBUG Register listener.js for window 4294967297
17:28:18 INFO - 1500942498167 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fbab7d29-3dd1-48d2-9332-9c18dce40548","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:18 INFO - 1500942498170 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:28:18 INFO - 1500942498172 Marionette TRACE 390 <- [1,2,null,{}]
17:28:18 INFO - 1500942498173 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:18 INFO - 1500942498175 Marionette TRACE 390 <- [1,3,null,{}]
17:28:18 INFO - 1500942498176 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:18 INFO - 1500942498178 Marionette TRACE 390 <- [1,4,null,{}]
17:28:18 INFO - 1500942498180 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}]
17:28:18 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500942498188 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:28:18 INFO - 1500942498189 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498194 Marionette TRACE 390 <- [1,6,null,{}]
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498198 Marionette DEBUG Closed connection 390
17:28:18 INFO - 1500942498199 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
17:28:18 INFO - 1500942498201 Marionette DEBUG Closed connection 391
17:28:18 INFO - 1500942498202 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
17:28:18 INFO - 1500942498204 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:18 INFO - 1500942498206 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:18 INFO - 1500942498228 Marionette DEBUG Register listener.js for window 4294967297
17:28:18 INFO - 1500942498239 Marionette TRACE 392 <- [1,1,null,{"sessionId":"29ea6306-7f39-4612-80da-fd1495f40a5a","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:18 INFO - 1500942498243 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498247 Marionette TRACE 392 <- [1,2,null,{}]
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498251 Marionette DEBUG Closed connection 392
17:28:18 INFO - 1500942498253 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
17:28:18 INFO - 1500942498254 Marionette DEBUG Closed connection 393
17:28:18 INFO - 1500942498256 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
17:28:18 INFO - 1500942498260 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:18 INFO - 1500942498262 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:18 INFO - 1500942498285 Marionette DEBUG Register listener.js for window 4294967297
17:28:18 INFO - 1500942498309 Marionette TRACE 394 <- [1,1,null,{"sessionId":"abaf0785-f067-4ef0-9155-693650d5a043","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:18 INFO - 1500942498313 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"}]
17:28:18 INFO - 1500942498317 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:28:18 INFO - 1500942498322 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:28:18 INFO - 1500942498323 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:28:18 INFO - ++DOMWINDOW == 22 (0000006A3B20E000) [pid = 3720] [serial = 32] [outer = 0000006A2D22F800]
17:28:18 INFO - 1500942498348 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:28:18 INFO - 1500942498352 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:28:18 INFO - 1500942498363 Marionette TRACE 394 <- [1,2,null,{}]
17:28:18 INFO - 1500942498378 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:28:18 INFO - 1500942498384 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9238457a-a99f-48f9-b6fc-6f3063fa7274","ELEMENT":"9238457a-a99f-48f9-b6fc-6f3063fa7274"}}]
17:28:18 INFO - 1500942498386 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:28:18 INFO - 1500942498391 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bd71f0d-0df7-417c-a4c0-36c1682c7db2","ELEMENT":"8bd71f0d-0df7-417c-a4c0-36c1682c7db2"}}]
17:28:18 INFO - 1500942498393 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9238457a-a99f-48f9-b6fc-6f3063fa7274"}]
17:28:18 INFO - 1500942498822 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:28:18 INFO - 1500942498824 Marionette TRACE 394 <- [1,5,null,{}]
17:28:18 INFO - 1500942498826 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"8bd71f0d-0df7-417c-a4c0-36c1682c7db2"}]
17:28:18 INFO - 1500942498952 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
17:28:18 INFO - 1500942498955 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:28:18 INFO - 1500942498981 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:28:18 INFO - 1500942498984 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}]
17:28:18 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500942498991 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:28:18 INFO - 1500942498992 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:28:18 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 876ms
17:28:18 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:28:18 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:18 INFO - 1500942498997 Marionette TRACE 394 <- [1,9,null,{}]
17:28:19 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:19 INFO - 1500942499001 Marionette DEBUG Closed connection 394
17:28:19 INFO - 1500942499002 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
17:28:19 INFO - 1500942499004 Marionette DEBUG Closed connection 395
17:28:19 INFO - 1500942499005 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
17:28:19 INFO - 1500942499007 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:19 INFO - 1500942499009 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:19 INFO - 1500942499032 Marionette DEBUG Register listener.js for window 4294967297
17:28:19 INFO - 1500942499043 Marionette TRACE 396 <- [1,1,null,{"sessionId":"935c500a-56c4-4bd2-a2ca-921238e38c86","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:19 INFO - 1500942499046 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:28:19 INFO - 1500942499047 Marionette TRACE 396 <- [1,2,null,{}]
17:28:19 INFO - 1500942499049 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:28:19 INFO - 1500942499051 Marionette TRACE 396 <- [1,3,null,{}]
17:28:19 INFO - 1500942499052 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:28:19 INFO - 1500942499054 Marionette TRACE 396 <- [1,4,null,{}]
17:28:19 INFO - 1500942499056 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}]
17:28:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500942499064 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:28:19 INFO - 1500942499066 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:28:19 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:19 INFO - 1500942499070 Marionette TRACE 396 <- [1,6,null,{}]
17:28:19 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:19 INFO - 1500942499074 Marionette DEBUG Closed connection 396
17:28:19 INFO - 1500942499075 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
17:28:19 INFO - 1500942499077 Marionette DEBUG Closed connection 397
17:28:19 INFO - 1500942499078 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
17:28:19 INFO - 1500942499081 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:28:19 INFO - 1500942499083 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:19 INFO - 1500942499110 Marionette DEBUG Register listener.js for window 4294967297
17:28:19 INFO - 1500942499123 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9c199a09-e9ae-498b-8f4d-4c56801c0fec","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:19 INFO - 1500942499126 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:28:19 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:19 INFO - 1500942499131 Marionette TRACE 398 <- [1,2,null,{}]
17:28:19 INFO - [Parent 2252] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:28:19 INFO - 1500942499135 Marionette DEBUG Closed connection 398
17:28:19 INFO - 1500942499136 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
17:28:19 INFO - 1500942499139 Marionette DEBUG Closed connection 399
17:28:19 INFO - 1500942499141 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
17:28:19 INFO - 1500942499143 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:28:19 INFO - 1500942499145 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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}
17:28:19 INFO - 1500942499171 Marionette DEBUG Register listener.js for window 4294967297
17:28:19 INFO - 1500942499182 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b51c448b-e248-4215-b0b6-d45bd1e30bdb","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":2252,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-290.000\\AppData\\Local\\Temp\\tmpi84ryd.mozrunner","moz:accessibilityChecks":false}}]
17:28:19 INFO - 1500942499184 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"}]
17:28:19 INFO - 1500942499191 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:28:19 INFO - 1500942499196 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:28:19 INFO - 1500942499197 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
17:28:19 INFO - ++DOMWINDOW == 23 (0000006A37ABA000) [pid = 3720] [serial = 33] [outer = 0000006A2D22F800]
17:28:19 INFO - 1500942499228 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
17:28:19 INFO - 1500942499233 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
17:28:19 INFO - 1500942499241 Marionette TRACE 400 <- [1,2,null,{}]
17:28:19 INFO - 1500942499249 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:28:19 INFO - 1500942499308 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9905c879-8206-4cce-94b4-b5df7371b96f","ELEMENT":"9905c879-8206-4cce-94b4-b5df7371b96f"}}]
17:28:19 INFO - 1500942499311 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9905c879-8206-4cce-94b4-b5df7371b96f"}]
17:28:19 INFO - 1500942499444 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:28:19 INFO - 1500942499447 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9905c879-8206-4cce-94b4-b5df7371b96f"}]
17:28:19 INFO - 1500942499614 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element