could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:38:16 INFO - 1499427496970 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}]
04:38:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499427496981 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:38:16 INFO - 1499427496984 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:38:16 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:16 INFO - 1499427496990 Marionette TRACE 352 <- [1,6,null,{}]
04:38:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1020ms
04:38:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427496997 Marionette DEBUG Closed connection 352
04:38:17 INFO - 1499427497001 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49744
04:38:17 INFO - 1499427497003 Marionette DEBUG Closed connection 353
04:38:17 INFO - 1499427497005 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49745
04:38:17 INFO - 1499427497009 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:17 INFO - 1499427497012 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:17 INFO - 1499427497036 Marionette DEBUG Register listener.js for window 12
04:38:17 INFO - 1499427497052 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1d6e3f28-28ef-4029-8e31-c847f461b2f6","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:17 INFO - 1499427497061 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:38:17 INFO - 1499427497063 Marionette TRACE 354 <- [1,2,null,{}]
04:38:17 INFO - 1499427497133 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:17 INFO - 1499427497135 Marionette TRACE 354 <- [1,3,null,{}]
04:38:17 INFO - 1499427497137 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:17 INFO - 1499427497140 Marionette TRACE 354 <- [1,4,null,{}]
04:38:17 INFO - 1499427497148 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}]
04:38:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499427497160 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:38:17 INFO - 1499427497164 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427497170 Marionette TRACE 354 <- [1,6,null,{}]
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427497178 Marionette DEBUG Closed connection 354
04:38:17 INFO - 1499427497180 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49746
04:38:17 INFO - 1499427497182 Marionette DEBUG Closed connection 355
04:38:17 INFO - 1499427497184 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49747
04:38:17 INFO - 1499427497187 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:17 INFO - 1499427497190 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:17 INFO - 1499427497215 Marionette DEBUG Register listener.js for window 12
04:38:17 INFO - 1499427497230 Marionette TRACE 356 <- [1,1,null,{"sessionId":"86fc3568-35ca-4647-9f68-d6392820c5bc","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:17 INFO - 1499427497272 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427497277 Marionette TRACE 356 <- [1,2,null,{}]
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427497300 Marionette DEBUG Closed connection 356
04:38:17 INFO - 1499427497302 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49748
04:38:17 INFO - 1499427497306 Marionette DEBUG Closed connection 357
04:38:17 INFO - 1499427497309 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49749
04:38:17 INFO - 1499427497339 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:17 INFO - 1499427497342 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:17 INFO - 1499427497365 Marionette DEBUG Register listener.js for window 12
04:38:17 INFO - 1499427497380 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b98fed26-2e13-41fb-bdb9-0f1177892a20","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:17 INFO - 1499427497393 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49299/clicks.html"}]
04:38:17 INFO - 1499427497398 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49299/hidden.html"
04:38:17 INFO - 1499427497419 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49299/hidden.html"
04:38:17 INFO - 1499427497420 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49299/hidden.html"
04:38:17 INFO - ++DOMWINDOW == 28 (000000C2E07C2800) [pid = 1312] [serial = 44] [outer = 000000C2EC312800]
04:38:17 INFO - 1499427497488 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49299/clicks.html"
04:38:17 INFO - 1499427497494 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49299/clicks.html"
04:38:17 INFO - 1499427497516 Marionette TRACE 358 <- [1,2,null,{}]
04:38:17 INFO - 1499427497519 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:38:17 INFO - 1499427497554 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1c659bed-79ee-47b1-9546-d382fe14d386","ELEMENT":"1c659bed-79ee-47b1-9546-d382fe14d386"}}]
04:38:17 INFO - 1499427497556 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1c659bed-79ee-47b1-9546-d382fe14d386"}]
04:38:17 INFO - 1499427497800 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49299/clicks.html"
04:38:17 INFO - [1312] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
04:38:17 INFO - 1499427497824 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49299/clicks.html"
04:38:17 INFO - 1499427497826 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49299/clicks.html"
04:38:17 INFO - ++DOMWINDOW == 29 (000000C2EB0F0000) [pid = 1312] [serial = 45] [outer = 000000C2EC312800]
04:38:17 INFO - 1499427497896 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49299/test.html"
04:38:17 INFO - 1499427497902 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49299/test.html"
04:38:17 INFO - 1499427497911 Marionette TRACE 358 <- [1,4,null,{}]
04:38:17 INFO - 1499427497922 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:38:17 INFO - 1499427497931 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8c55afe4-2644-4089-8539-0aa1d2612408","ELEMENT":"8c55afe4-2644-4089-8539-0aa1d2612408"}}]
04:38:17 INFO - --DOMWINDOW == 28 (000000C2F5667000) [pid = 1312] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 27 (000000C2F3D82000) [pid = 1312] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 26 (000000C2F31C1000) [pid = 1312] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 25 (000000C2F0987800) [pid = 1312] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 24 (000000C2E610F000) [pid = 1312] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 23 (000000C2F0FE3800) [pid = 1312] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - --DOMWINDOW == 22 (000000C2E0E07800) [pid = 1312] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49299/test_accessibility.html]
04:38:17 INFO - 1499427497974 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:38:17 INFO - 1499427497976 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:38:17 INFO - 1499427497979 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}]
04:38:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499427497988 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:38:17 INFO - 1499427497990 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:38:17 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:17 INFO - 1499427497995 Marionette TRACE 358 <- [1,8,null,{}]
04:38:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1004ms
04:38:17 INFO - TEST-START | test_click.py TestClick.test_container_element
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498001 Marionette DEBUG Closed connection 358
04:38:18 INFO - 1499427498003 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49752
04:38:18 INFO - 1499427498005 Marionette DEBUG Closed connection 359
04:38:18 INFO - 1499427498007 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49753
04:38:18 INFO - 1499427498009 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:18 INFO - 1499427498015 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:18 INFO - 1499427498038 Marionette DEBUG Register listener.js for window 12
04:38:18 INFO - 1499427498052 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8c145ade-0090-436c-a2f5-122a5e1ab879","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:18 INFO - 1499427498059 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:38:18 INFO - 1499427498061 Marionette TRACE 360 <- [1,2,null,{}]
04:38:18 INFO - 1499427498062 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:18 INFO - 1499427498064 Marionette TRACE 360 <- [1,3,null,{}]
04:38:18 INFO - 1499427498066 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:18 INFO - 1499427498068 Marionette TRACE 360 <- [1,4,null,{}]
04:38:18 INFO - 1499427498070 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}]
04:38:18 INFO - TEST-START: test_click.py TestClick.test_container_element1499427498082 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:38:18 INFO - 1499427498084 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498088 Marionette TRACE 360 <- [1,6,null,{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498097 Marionette DEBUG Closed connection 360
04:38:18 INFO - 1499427498102 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49754
04:38:18 INFO - 1499427498104 Marionette DEBUG Closed connection 361
04:38:18 INFO - 1499427498106 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49755
04:38:18 INFO - 1499427498108 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:18 INFO - 1499427498110 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:18 INFO - 1499427498133 Marionette DEBUG Register listener.js for window 12
04:38:18 INFO - 1499427498147 Marionette TRACE 362 <- [1,1,null,{"sessionId":"63c05d1f-7bf2-4add-a084-c2b78afbf44b","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:18 INFO - 1499427498153 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498157 Marionette TRACE 362 <- [1,2,null,{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498163 Marionette DEBUG Closed connection 362
04:38:18 INFO - 1499427498164 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49756
04:38:18 INFO - 1499427498166 Marionette DEBUG Closed connection 363
04:38:18 INFO - 1499427498167 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49757
04:38:18 INFO - 1499427498170 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:18 INFO - 1499427498172 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:18 INFO - 1499427498195 Marionette DEBUG Register listener.js for window 12
04:38:18 INFO - 1499427498206 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6599dc13-7da5-4e01-a145-9329c5bcdf14","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:18 INFO - 1499427498212 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"}]
04:38:18 INFO - 1499427498216 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49299/test.html"
04:38:18 INFO - 1499427498222 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49299/test.html"
04:38:18 INFO - 1499427498223 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49299/test.html"
04:38:18 INFO - ++DOMWINDOW == 23 (000000C2EFEB0000) [pid = 1312] [serial = 46] [outer = 000000C2EC312800]
04:38:18 INFO - 1499427498283 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"
04:38:18 INFO - 1499427498289 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"
04:38:18 INFO - 1499427498310 Marionette TRACE 364 <- [1,2,null,{}]
04:38:18 INFO - 1499427498312 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:38:18 INFO - 1499427498322 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d71b8080-aa00-4bc9-a4c3-4a2d49733d1b","ELEMENT":"d71b8080-aa00-4bc9-a4c3-4a2d49733d1b"}}]
04:38:18 INFO - 1499427498324 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d71b8080-aa00-4bc9-a4c3-4a2d49733d1b"}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
04:38:18 INFO - 1499427498756 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:38:18 INFO - 1499427498758 Marionette TRACE 364 <- [1,4,null,{}]
04:38:18 INFO - 1499427498760 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d71b8080-aa00-4bc9-a4c3-4a2d49733d1b","name":"selected"}]
04:38:18 INFO - 1499427498787 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:38:18 INFO - 1499427498790 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}]
04:38:18 INFO - TEST-END: test_click.py TestClick.test_container_element1499427498798 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:38:18 INFO - 1499427498800 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498804 Marionette TRACE 364 <- [1,7,null,{}]
04:38:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 828ms
04:38:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498832 Marionette DEBUG Closed connection 364
04:38:18 INFO - 1499427498833 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49758
04:38:18 INFO - 1499427498835 Marionette DEBUG Closed connection 365
04:38:18 INFO - 1499427498836 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49759
04:38:18 INFO - 1499427498838 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:18 INFO - 1499427498842 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:18 INFO - 1499427498864 Marionette DEBUG Register listener.js for window 12
04:38:18 INFO - 1499427498876 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8c079ba7-8c93-4399-8373-0af24428b5e2","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:18 INFO - 1499427498885 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:38:18 INFO - 1499427498887 Marionette TRACE 366 <- [1,2,null,{}]
04:38:18 INFO - 1499427498889 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:18 INFO - 1499427498891 Marionette TRACE 366 <- [1,3,null,{}]
04:38:18 INFO - 1499427498893 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:18 INFO - 1499427498894 Marionette TRACE 366 <- [1,4,null,{}]
04:38:18 INFO - 1499427498897 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}]
04:38:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499427498907 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:38:18 INFO - 1499427498909 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498914 Marionette TRACE 366 <- [1,6,null,{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498920 Marionette DEBUG Closed connection 366
04:38:18 INFO - 1499427498921 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49760
04:38:18 INFO - 1499427498923 Marionette DEBUG Closed connection 367
04:38:18 INFO - 1499427498924 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49761
04:38:18 INFO - 1499427498927 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:18 INFO - 1499427498929 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:18 INFO - 1499427498952 Marionette DEBUG Register listener.js for window 12
04:38:18 INFO - 1499427498964 Marionette TRACE 368 <- [1,1,null,{"sessionId":"11fe1600-181c-4eac-a7d8-35f57ecce2ed","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:18 INFO - 1499427498970 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498974 Marionette TRACE 368 <- [1,2,null,{}]
04:38:18 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:18 INFO - 1499427498980 Marionette DEBUG Closed connection 368
04:38:18 INFO - 1499427498982 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49762
04:38:18 INFO - 1499427498983 Marionette DEBUG Closed connection 369
04:38:18 INFO - 1499427498985 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49763
04:38:18 INFO - 1499427498987 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:18 INFO - 1499427498990 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:19 INFO - 1499427499012 Marionette DEBUG Register listener.js for window 12
04:38:19 INFO - 1499427499024 Marionette TRACE 370 <- [1,1,null,{"sessionId":"91542e2a-d4c4-4324-8c3f-fc7a2a1ea7bd","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:19 INFO - 1499427499030 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"}]
04:38:19 INFO - 1499427499034 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"
04:38:19 INFO - 1499427499041 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"
04:38:19 INFO - 1499427499042 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"
04:38:19 INFO - ++DOMWINDOW == 24 (000000C2DEE63800) [pid = 1312] [serial = 47] [outer = 000000C2EC312800]
04:38:19 INFO - 1499427499088 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"
04:38:19 INFO - 1499427499102 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"
04:38:19 INFO - 1499427499121 Marionette TRACE 370 <- [1,2,null,{}]
04:38:19 INFO - 1499427499125 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:38:19 INFO - 1499427499132 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a927179b-6318-464b-9042-fa05071fbd12","ELEMENT":"a927179b-6318-464b-9042-fa05071fbd12"}}]
04:38:19 INFO - 1499427499134 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a927179b-6318-464b-9042-fa05071fbd12"}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
04:38:19 INFO - 1499427499578 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:38:19 INFO - 1499427499580 Marionette TRACE 370 <- [1,4,null,{}]
04:38:19 INFO - 1499427499582 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a927179b-6318-464b-9042-fa05071fbd12","name":"selected"}]
04:38:19 INFO - 1499427499610 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:38:19 INFO - 1499427499613 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}]
04:38:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499427499620 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:38:19 INFO - 1499427499622 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499626 Marionette TRACE 370 <- [1,7,null,{}]
04:38:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 802ms
04:38:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499632 Marionette DEBUG Closed connection 370
04:38:19 INFO - 1499427499634 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49764
04:38:19 INFO - 1499427499635 Marionette DEBUG Closed connection 371
04:38:19 INFO - 1499427499636 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49765
04:38:19 INFO - 1499427499639 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:19 INFO - 1499427499642 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:19 INFO - 1499427499670 Marionette DEBUG Register listener.js for window 12
04:38:19 INFO - 1499427499684 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d50060d2-c6f6-44f0-8486-63cad46177dd","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:19 INFO - 1499427499694 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:38:19 INFO - 1499427499695 Marionette TRACE 372 <- [1,2,null,{}]
04:38:19 INFO - 1499427499697 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:19 INFO - 1499427499699 Marionette TRACE 372 <- [1,3,null,{}]
04:38:19 INFO - 1499427499701 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:19 INFO - 1499427499703 Marionette TRACE 372 <- [1,4,null,{}]
04:38:19 INFO - 1499427499705 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}]
04:38:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499427499713 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:38:19 INFO - 1499427499715 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499719 Marionette TRACE 372 <- [1,6,null,{}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499725 Marionette DEBUG Closed connection 372
04:38:19 INFO - 1499427499727 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49766
04:38:19 INFO - 1499427499729 Marionette DEBUG Closed connection 373
04:38:19 INFO - 1499427499730 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49767
04:38:19 INFO - 1499427499732 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:19 INFO - 1499427499735 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:19 INFO - 1499427499758 Marionette DEBUG Register listener.js for window 12
04:38:19 INFO - 1499427499769 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d4296dc0-7691-4772-a760-d2bdea680607","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:19 INFO - 1499427499775 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499779 Marionette TRACE 374 <- [1,2,null,{}]
04:38:19 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:19 INFO - 1499427499785 Marionette DEBUG Closed connection 374
04:38:19 INFO - 1499427499787 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49768
04:38:19 INFO - 1499427499788 Marionette DEBUG Closed connection 375
04:38:19 INFO - 1499427499790 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49769
04:38:19 INFO - 1499427499792 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:19 INFO - 1499427499795 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:19 INFO - 1499427499819 Marionette DEBUG Register listener.js for window 12
04:38:19 INFO - 1499427499830 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6b8d2cda-f8e6-4c03-a393-ccd00e36c472","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:19 INFO - 1499427499836 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"}]
04:38:19 INFO - 1499427499840 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"
04:38:19 INFO - 1499427499849 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"
04:38:19 INFO - 1499427499850 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"
04:38:19 INFO - ++DOMWINDOW == 25 (000000C2F0B21000) [pid = 1312] [serial = 48] [outer = 000000C2EC312800]
04:38:19 INFO - 1499427499911 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"
04:38:19 INFO - 1499427499920 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"
04:38:19 INFO - 1499427499936 Marionette TRACE 376 <- [1,2,null,{}]
04:38:19 INFO - 1499427499941 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:38:19 INFO - 1499427499948 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd0986c0-1f1c-4372-af5c-623dc3a4af56","ELEMENT":"bd0986c0-1f1c-4372-af5c-623dc3a4af56"}}]
04:38:19 INFO - 1499427499950 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"bd0986c0-1f1c-4372-af5c-623dc3a4af56"}]
04:38:20 INFO - 1499427500388 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:38:20 INFO - 1499427500390 Marionette TRACE 376 <- [1,4,null,{}]
04:38:20 INFO - 1499427500392 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}]
04:38:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499427500400 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:38:20 INFO - 1499427500402 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500406 Marionette TRACE 376 <- [1,6,null,{}]
04:38:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 780ms
04:38:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500412 Marionette DEBUG Closed connection 376
04:38:20 INFO - 1499427500413 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49770
04:38:20 INFO - 1499427500415 Marionette DEBUG Closed connection 377
04:38:20 INFO - 1499427500416 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49771
04:38:20 INFO - 1499427500418 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:20 INFO - 1499427500420 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:20 INFO - 1499427500443 Marionette DEBUG Register listener.js for window 12
04:38:20 INFO - 1499427500463 Marionette TRACE 378 <- [1,1,null,{"sessionId":"91de4710-4c91-41fd-9584-4827c18cc678","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:20 INFO - 1499427500469 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:38:20 INFO - 1499427500471 Marionette TRACE 378 <- [1,2,null,{}]
04:38:20 INFO - 1499427500475 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:20 INFO - 1499427500476 Marionette TRACE 378 <- [1,3,null,{}]
04:38:20 INFO - 1499427500478 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:20 INFO - 1499427500479 Marionette TRACE 378 <- [1,4,null,{}]
04:38:20 INFO - 1499427500482 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}]
04:38:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499427500489 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:38:20 INFO - 1499427500491 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500495 Marionette TRACE 378 <- [1,6,null,{}]
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500501 Marionette DEBUG Closed connection 378
04:38:20 INFO - 1499427500503 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49772
04:38:20 INFO - 1499427500504 Marionette DEBUG Closed connection 379
04:38:20 INFO - 1499427500506 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49773
04:38:20 INFO - 1499427500508 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:20 INFO - 1499427500510 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:20 INFO - 1499427500533 Marionette DEBUG Register listener.js for window 12
04:38:20 INFO - 1499427500543 Marionette TRACE 380 <- [1,1,null,{"sessionId":"eb00e4e2-e056-411d-8bcf-218512dfc8ca","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:20 INFO - 1499427500549 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500553 Marionette TRACE 380 <- [1,2,null,{}]
04:38:20 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:20 INFO - 1499427500559 Marionette DEBUG Closed connection 380
04:38:20 INFO - 1499427500560 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49774
04:38:20 INFO - 1499427500562 Marionette DEBUG Closed connection 381
04:38:20 INFO - 1499427500563 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49775
04:38:20 INFO - 1499427500566 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:20 INFO - 1499427500568 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:20 INFO - 1499427500591 Marionette DEBUG Register listener.js for window 12
04:38:20 INFO - 1499427500602 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b58a3179-0c5c-4609-8714-9f4005ea0402","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:21 INFO - 1499427501000 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"}]
04:38:21 INFO - 1499427501004 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"
04:38:21 INFO - 1499427501010 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"
04:38:21 INFO - 1499427501011 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"
04:38:21 INFO - ++DOMWINDOW == 26 (000000C2F52C4800) [pid = 1312] [serial = 49] [outer = 000000C2EC312800]
04:38:21 INFO - 1499427501065 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"
04:38:21 INFO - 1499427501070 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"
04:38:21 INFO - 1499427501087 Marionette TRACE 382 <- [1,2,null,{}]
04:38:21 INFO - 1499427501095 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:38:21 INFO - 1499427501103 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b42e953-adfc-4ff3-9255-40ff6efe2347","ELEMENT":"6b42e953-adfc-4ff3-9255-40ff6efe2347"}}]
04:38:21 INFO - 1499427501105 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6b42e953-adfc-4ff3-9255-40ff6efe2347"}]
04:38:21 INFO - 1499427501544 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:38:21 INFO - 1499427501546 Marionette TRACE 382 <- [1,4,null,{}]
04:38:21 INFO - 1499427501549 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}]
04:38:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499427501560 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:38:21 INFO - 1499427501561 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501566 Marionette TRACE 382 <- [1,6,null,{}]
04:38:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1159ms
04:38:21 INFO - TEST-START | test_click.py TestClick.test_input_file
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501572 Marionette DEBUG Closed connection 382
04:38:21 INFO - 1499427501573 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49776
04:38:21 INFO - 1499427501575 Marionette DEBUG Closed connection 383
04:38:21 INFO - 1499427501576 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49777
04:38:21 INFO - 1499427501578 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:21 INFO - 1499427501580 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:21 INFO - 1499427501603 Marionette DEBUG Register listener.js for window 12
04:38:21 INFO - 1499427501614 Marionette TRACE 384 <- [1,1,null,{"sessionId":"47fd33e6-4e73-4f21-beb2-f65d556294f4","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:21 INFO - 1499427501647 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:38:21 INFO - 1499427501649 Marionette TRACE 384 <- [1,2,null,{}]
04:38:21 INFO - 1499427501653 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:21 INFO - 1499427501654 Marionette TRACE 384 <- [1,3,null,{}]
04:38:21 INFO - 1499427501657 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:21 INFO - 1499427501658 Marionette TRACE 384 <- [1,4,null,{}]
04:38:21 INFO - 1499427501661 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}]
04:38:21 INFO - TEST-START: test_click.py TestClick.test_input_file1499427501668 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:38:21 INFO - 1499427501670 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501674 Marionette TRACE 384 <- [1,6,null,{}]
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501680 Marionette DEBUG Closed connection 384
04:38:21 INFO - 1499427501681 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49778
04:38:21 INFO - 1499427501683 Marionette DEBUG Closed connection 385
04:38:21 INFO - 1499427501684 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49779
04:38:21 INFO - 1499427501686 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:21 INFO - 1499427501688 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:21 INFO - 1499427501712 Marionette DEBUG Register listener.js for window 12
04:38:21 INFO - 1499427501722 Marionette TRACE 386 <- [1,1,null,{"sessionId":"422b8a05-62a0-4a2d-bba8-f72f970424a4","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:21 INFO - 1499427501758 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501765 Marionette TRACE 386 <- [1,2,null,{}]
04:38:21 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:21 INFO - 1499427501771 Marionette DEBUG Closed connection 386
04:38:21 INFO - 1499427501773 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49780
04:38:21 INFO - 1499427501795 Marionette DEBUG Closed connection 387
04:38:21 INFO - 1499427501797 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49781
04:38:21 INFO - 1499427501799 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:21 INFO - 1499427501804 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:21 INFO - 1499427501827 Marionette DEBUG Register listener.js for window 12
04:38:21 INFO - 1499427501851 Marionette TRACE 388 <- [1,1,null,{"sessionId":"23f0dfd8-8f4a-4481-a2c1-ddc5118d6ed1","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:21 INFO - 1499427501859 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:38:21 INFO - 1499427501863 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"
04:38:21 INFO - 1499427501869 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"
04:38:21 INFO - 1499427501872 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"
04:38:21 INFO - ++DOMWINDOW == 27 (000000C2F5666000) [pid = 1312] [serial = 50] [outer = 000000C2EC312800]
04:38:21 INFO - 1499427501918 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:38:21 INFO - 1499427501932 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:38:21 INFO - 1499427501939 Marionette TRACE 388 <- [1,2,null,{}]
04:38:21 INFO - 1499427501949 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:38:21 INFO - 1499427501957 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c81df0a-f02d-4e2f-8ff0-cc7ec9c3cdd0","ELEMENT":"3c81df0a-f02d-4e2f-8ff0-cc7ec9c3cdd0"}}]
04:38:21 INFO - 1499427501969 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3c81df0a-f02d-4e2f-8ff0-cc7ec9c3cdd0"}]
04:38:22 INFO - 1499427502156 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:38:22 INFO - 1499427502161 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}]
04:38:22 INFO - TEST-END: test_click.py TestClick.test_input_file1499427502168 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:38:22 INFO - 1499427502170 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502174 Marionette TRACE 388 <- [1,6,null,{}]
04:38:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 609ms
04:38:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502180 Marionette DEBUG Closed connection 388
04:38:22 INFO - 1499427502182 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49782
04:38:22 INFO - 1499427502183 Marionette DEBUG Closed connection 389
04:38:22 INFO - 1499427502185 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49783
04:38:22 INFO - 1499427502187 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:22 INFO - 1499427502189 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:22 INFO - 1499427502213 Marionette DEBUG Register listener.js for window 12
04:38:22 INFO - 1499427502224 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b48e5f93-9a15-46d4-ab6e-838e87ce92c0","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:22 INFO - 1499427502255 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:38:22 INFO - 1499427502257 Marionette TRACE 390 <- [1,2,null,{}]
04:38:22 INFO - 1499427502259 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:22 INFO - 1499427502260 Marionette TRACE 390 <- [1,3,null,{}]
04:38:22 INFO - 1499427502262 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:22 INFO - 1499427502263 Marionette TRACE 390 <- [1,4,null,{}]
04:38:22 INFO - 1499427502266 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}]
04:38:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499427502274 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:38:22 INFO - 1499427502275 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502280 Marionette TRACE 390 <- [1,6,null,{}]
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502305 Marionette DEBUG Closed connection 390
04:38:22 INFO - 1499427502307 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49784
04:38:22 INFO - 1499427502311 Marionette DEBUG Closed connection 391
04:38:22 INFO - 1499427502312 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49785
04:38:22 INFO - 1499427502314 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:22 INFO - 1499427502316 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:22 INFO - 1499427502341 Marionette DEBUG Register listener.js for window 12
04:38:22 INFO - 1499427502351 Marionette TRACE 392 <- [1,1,null,{"sessionId":"db2d5580-5282-4dc8-942e-6b4728c044a8","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:22 INFO - 1499427502363 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502367 Marionette TRACE 392 <- [1,2,null,{}]
04:38:22 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:22 INFO - 1499427502389 Marionette DEBUG Closed connection 392
04:38:22 INFO - 1499427502391 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49786
04:38:22 INFO - 1499427502396 Marionette DEBUG Closed connection 393
04:38:22 INFO - 1499427502397 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49787
04:38:22 INFO - 1499427502400 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:22 INFO - 1499427502402 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:22 INFO - 1499427502426 Marionette DEBUG Register listener.js for window 12
04:38:22 INFO - 1499427502436 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5f40ba40-8369-4673-84f7-0e1ae34cceb1","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:22 INFO - 1499427502446 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"}]
04:38:22 INFO - 1499427502450 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:38:22 INFO - 1499427502456 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:38:22 INFO - 1499427502457 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:38:22 INFO - ++DOMWINDOW == 28 (000000C2F5A28000) [pid = 1312] [serial = 51] [outer = 000000C2EC312800]
04:38:22 INFO - 1499427502510 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"
04:38:22 INFO - 1499427502516 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"
04:38:22 INFO - 1499427502522 Marionette TRACE 394 <- [1,2,null,{}]
04:38:22 INFO - 1499427502535 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:38:22 INFO - 1499427502543 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c3f06498-deb6-43e4-887f-75b6507771c6","ELEMENT":"c3f06498-deb6-43e4-887f-75b6507771c6"}}]
04:38:22 INFO - 1499427502548 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:38:22 INFO - 1499427502554 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"609ad3d8-a470-48f2-b666-6b4c838aa512","ELEMENT":"609ad3d8-a470-48f2-b666-6b4c838aa512"}}]
04:38:22 INFO - 1499427502556 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c3f06498-deb6-43e4-887f-75b6507771c6"}]
04:38:23 INFO - 1499427503688 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:38:23 INFO - 1499427503703 Marionette TRACE 394 <- [1,5,null,{}]
04:38:23 INFO - 1499427503750 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"609ad3d8-a470-48f2-b666-6b4c838aa512"}]
04:38:23 INFO - 1499427503936 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
04:38:23 INFO - 1499427503989 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:38:24 INFO - 1499427503998 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:38:24 INFO - 1499427504017 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}]
04:38:24 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499427504024 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:38:24 INFO - 1499427504026 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504030 Marionette TRACE 394 <- [1,9,null,{}]
04:38:24 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1855ms
04:38:24 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504036 Marionette DEBUG Closed connection 394
04:38:24 INFO - 1499427504038 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49788
04:38:24 INFO - 1499427504039 Marionette DEBUG Closed connection 395
04:38:24 INFO - 1499427504040 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49789
04:38:24 INFO - 1499427504042 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:24 INFO - 1499427504045 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:24 INFO - 1499427504068 Marionette DEBUG Register listener.js for window 12
04:38:24 INFO - 1499427504079 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ea921fff-0003-400a-a219-a3ae29d6d303","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:24 INFO - 1499427504118 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:38:24 INFO - 1499427504120 Marionette TRACE 396 <- [1,2,null,{}]
04:38:24 INFO - 1499427504123 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:38:24 INFO - 1499427504125 Marionette TRACE 396 <- [1,3,null,{}]
04:38:24 INFO - 1499427504129 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:38:24 INFO - 1499427504131 Marionette TRACE 396 <- [1,4,null,{}]
04:38:24 INFO - 1499427504133 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}]
04:38:24 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499427504140 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:38:24 INFO - 1499427504142 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504146 Marionette TRACE 396 <- [1,6,null,{}]
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504171 Marionette DEBUG Closed connection 396
04:38:24 INFO - 1499427504172 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49790
04:38:24 INFO - 1499427504176 Marionette DEBUG Closed connection 397
04:38:24 INFO - 1499427504178 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49791
04:38:24 INFO - 1499427504180 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:38:24 INFO - 1499427504191 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:24 INFO - 1499427504215 Marionette DEBUG Register listener.js for window 12
04:38:24 INFO - 1499427504226 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d5b12280-5d56-4351-a1cf-914acfe239d2","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:24 INFO - 1499427504237 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504241 Marionette TRACE 398 <- [1,2,null,{}]
04:38:24 INFO - [1312] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
04:38:24 INFO - 1499427504263 Marionette DEBUG Closed connection 398
04:38:24 INFO - 1499427504265 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49792
04:38:24 INFO - 1499427504269 Marionette DEBUG Closed connection 399
04:38:24 INFO - 1499427504271 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49793
04:38:24 INFO - 1499427504273 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:38:24 INFO - 1499427504276 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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}
04:38:24 INFO - 1499427504300 Marionette DEBUG Register listener.js for window 12
04:38:24 INFO - 1499427504311 Marionette TRACE 400 <- [1,1,null,{"sessionId":"31240d22-7411-4a41-a64d-a4354efbd17c","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":1312,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-132\\AppData\\Local\\Temp\\tmpdxfzot.mozrunner","moz:accessibilityChecks":false}}]
04:38:24 INFO - 1499427504321 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"}]
04:38:24 INFO - 1499427504324 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"
04:38:24 INFO - 1499427504330 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"
04:38:24 INFO - 1499427504331 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"
04:38:24 INFO - ++DOMWINDOW == 29 (000000C2F5E91800) [pid = 1312] [serial = 52] [outer = 000000C2EC312800]
04:38:24 INFO - 1499427504384 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"
04:38:24 INFO - 1499427504389 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"
04:38:24 INFO - 1499427504405 Marionette TRACE 400 <- [1,2,null,{}]
04:38:24 INFO - 1499427504413 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:38:24 INFO - 1499427504426 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c49e1c93-dadf-4594-bf94-e12e80964960","ELEMENT":"c49e1c93-dadf-4594-bf94-e12e80964960"}}]
04:38:24 INFO - 1499427504428 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c49e1c93-dadf-4594-bf94-e12e80964960"}]
04:38:24 INFO - 1499427504550 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:38:24 INFO - 1499427504560 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c49e1c93-dadf-4594-bf94-e12e80964960"}]
04:38:24 INFO - 1499427504699 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element