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]
14:13:34 INFO - 1499462014785 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}]
14:13:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499462014795 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:13:34 INFO - 1499462014798 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:13:34 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:34 INFO - 1499462014803 Marionette TRACE 352 <- [1,6,null,{}]
14:13:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 977ms
14:13:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:13:34 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:34 INFO - 1499462014810 Marionette DEBUG Closed connection 352
14:13:34 INFO - 1499462014812 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49777
14:13:34 INFO - 1499462014834 Marionette DEBUG Closed connection 353
14:13:34 INFO - 1499462014836 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49778
14:13:34 INFO - 1499462014844 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:34 INFO - 1499462014849 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:34 INFO - 1499462014874 Marionette DEBUG Register listener.js for window 12
14:13:34 INFO - 1499462014903 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f4c3bc4b-a27d-4b64-a3ae-97e5a3b6fa53","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:34 INFO - 1499462014915 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:13:34 INFO - 1499462014918 Marionette TRACE 354 <- [1,2,null,{}]
14:13:34 INFO - 1499462014920 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:34 INFO - 1499462014922 Marionette TRACE 354 <- [1,3,null,{}]
14:13:34 INFO - 1499462014942 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:34 INFO - 1499462014944 Marionette TRACE 354 <- [1,4,null,{}]
14:13:34 INFO - 1499462014948 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}]
14:13:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499462014958 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:13:34 INFO - 1499462014962 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:13:34 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:34 INFO - 1499462014967 Marionette TRACE 354 <- [1,6,null,{}]
14:13:34 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:34 INFO - 1499462014975 Marionette DEBUG Closed connection 354
14:13:34 INFO - 1499462014977 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49779
14:13:35 INFO - 1499462014997 Marionette DEBUG Closed connection 355
14:13:35 INFO - 1499462014999 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49780
14:13:35 INFO - 1499462015002 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:35 INFO - 1499462015005 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:35 INFO - 1499462015027 Marionette DEBUG Register listener.js for window 12
14:13:35 INFO - 1499462015041 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9729f826-da36-41b9-be9f-09dee78f7ffa","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:35 INFO - 1499462015050 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015056 Marionette TRACE 356 <- [1,2,null,{}]
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015080 Marionette DEBUG Closed connection 356
14:13:35 INFO - 1499462015082 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49781
14:13:35 INFO - 1499462015086 Marionette DEBUG Closed connection 357
14:13:35 INFO - 1499462015088 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49782
14:13:35 INFO - 1499462015094 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:35 INFO - 1499462015100 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:35 INFO - 1499462015122 Marionette DEBUG Register listener.js for window 12
14:13:35 INFO - 1499462015137 Marionette TRACE 358 <- [1,1,null,{"sessionId":"70fcaa40-551e-4524-8339-1398edd0a6d7","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:35 INFO - --DOMWINDOW == 26 (000000AF7A69D800) [pid = 1136] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 25 (000000AF6FA9C000) [pid = 1136] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 24 (000000AF799F1000) [pid = 1136] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 23 (000000AF7A8F0000) [pid = 1136] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 22 (000000AF7AA8D000) [pid = 1136] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 21 (000000AF7B3D1800) [pid = 1136] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - --DOMWINDOW == 20 (000000AF04A24800) [pid = 1136] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
14:13:35 INFO - 1499462015151 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
14:13:35 INFO - 1499462015156 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
14:13:35 INFO - 1499462015169 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
14:13:35 INFO - 1499462015170 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
14:13:35 INFO - ++DOMWINDOW == 21 (000000AF03D14800) [pid = 1136] [serial = 44] [outer = 000000AF7B014800]
14:13:35 INFO - 1499462015258 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
14:13:35 INFO - 1499462015263 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
14:13:35 INFO - 1499462015285 Marionette TRACE 358 <- [1,2,null,{}]
14:13:35 INFO - 1499462015293 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:13:35 INFO - 1499462015304 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5c421214-8749-4677-945a-fd3f929936c6","ELEMENT":"5c421214-8749-4677-945a-fd3f929936c6"}}]
14:13:35 INFO - 1499462015306 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5c421214-8749-4677-945a-fd3f929936c6"}]
14:13:35 INFO - 1499462015531 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
14:13:35 INFO - [1136] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
14:13:35 INFO - 1499462015551 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
14:13:35 INFO - 1499462015552 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
14:13:35 INFO - ++DOMWINDOW == 22 (000000AF7AA8F800) [pid = 1136] [serial = 45] [outer = 000000AF7B014800]
14:13:35 INFO - 1499462015623 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
14:13:35 INFO - 1499462015629 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
14:13:35 INFO - 1499462015646 Marionette TRACE 358 <- [1,4,null,{}]
14:13:35 INFO - 1499462015682 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:13:35 INFO - 1499462015690 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e74c399f-6c95-4d55-8548-36178feaf76f","ELEMENT":"e74c399f-6c95-4d55-8548-36178feaf76f"}}]
14:13:35 INFO - 1499462015692 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:13:35 INFO - 1499462015694 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:13:35 INFO - 1499462015696 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}]
14:13:35 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499462015707 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:13:35 INFO - 1499462015709 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015714 Marionette TRACE 358 <- [1,8,null,{}]
14:13:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 910ms
14:13:35 INFO - TEST-START | test_click.py TestClick.test_container_element
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015722 Marionette DEBUG Closed connection 358
14:13:35 INFO - 1499462015727 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49785
14:13:35 INFO - 1499462015730 Marionette DEBUG Closed connection 359
14:13:35 INFO - 1499462015731 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49786
14:13:35 INFO - 1499462015832 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:35 INFO - 1499462015835 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:35 INFO - 1499462015857 Marionette DEBUG Register listener.js for window 12
14:13:35 INFO - 1499462015871 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0aa989bc-5d15-4121-987f-6a9734eee72a","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:35 INFO - 1499462015879 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:13:35 INFO - 1499462015881 Marionette TRACE 360 <- [1,2,null,{}]
14:13:35 INFO - 1499462015883 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:35 INFO - 1499462015885 Marionette TRACE 360 <- [1,3,null,{}]
14:13:35 INFO - 1499462015913 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:35 INFO - 1499462015914 Marionette TRACE 360 <- [1,4,null,{}]
14:13:35 INFO - 1499462015918 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}]
14:13:35 INFO - TEST-START: test_click.py TestClick.test_container_element1499462015926 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:13:35 INFO - 1499462015930 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015934 Marionette TRACE 360 <- [1,6,null,{}]
14:13:35 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:35 INFO - 1499462015959 Marionette DEBUG Closed connection 360
14:13:35 INFO - 1499462015961 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49787
14:13:35 INFO - 1499462015965 Marionette DEBUG Closed connection 361
14:13:35 INFO - 1499462015966 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49788
14:13:36 INFO - 1499462016670 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:36 INFO - 1499462016672 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:36 INFO - 1499462016694 Marionette DEBUG Register listener.js for window 12
14:13:36 INFO - 1499462016712 Marionette TRACE 362 <- [1,1,null,{"sessionId":"372297c3-e0ed-498a-acf7-aec6a2b5ff7b","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:36 INFO - 1499462016718 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:13:36 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:36 INFO - 1499462016722 Marionette TRACE 362 <- [1,2,null,{}]
14:13:36 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:36 INFO - 1499462016818 Marionette DEBUG Closed connection 362
14:13:36 INFO - 1499462016819 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49789
14:13:36 INFO - 1499462016857 Marionette DEBUG Closed connection 363
14:13:36 INFO - 1499462016859 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49790
14:13:36 INFO - 1499462016870 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:36 INFO - 1499462016874 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:36 INFO - 1499462016896 Marionette DEBUG Register listener.js for window 12
14:13:36 INFO - 1499462016907 Marionette TRACE 364 <- [1,1,null,{"sessionId":"bda435b8-ab29-44c3-9dae-6d8b13987f59","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:36 INFO - 1499462016918 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"}]
14:13:36 INFO - 1499462016922 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
14:13:36 INFO - 1499462016929 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
14:13:36 INFO - 1499462016929 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
14:13:36 INFO - ++DOMWINDOW == 23 (000000AF026B9800) [pid = 1136] [serial = 46] [outer = 000000AF7B014800]
14:13:37 INFO - 1499462016989 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"
14:13:37 INFO - 1499462016994 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"
14:13:37 INFO - 1499462017015 Marionette TRACE 364 <- [1,2,null,{}]
14:13:37 INFO - 1499462017021 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:13:37 INFO - 1499462017031 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee571612-9640-47ff-9cba-0f5418f7b891","ELEMENT":"ee571612-9640-47ff-9cba-0f5418f7b891"}}]
14:13:37 INFO - 1499462017032 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ee571612-9640-47ff-9cba-0f5418f7b891"}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
14:13:37 INFO - 1499462017449 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:13:37 INFO - 1499462017451 Marionette TRACE 364 <- [1,4,null,{}]
14:13:37 INFO - 1499462017453 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"ee571612-9640-47ff-9cba-0f5418f7b891","name":"selected"}]
14:13:37 INFO - 1499462017476 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:13:37 INFO - 1499462017485 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}]
14:13:37 INFO - TEST-END: test_click.py TestClick.test_container_element1499462017494 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:13:37 INFO - 1499462017497 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017502 Marionette TRACE 364 <- [1,7,null,{}]
14:13:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1789ms
14:13:37 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017524 Marionette DEBUG Closed connection 364
14:13:37 INFO - 1499462017526 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49791
14:13:37 INFO - 1499462017529 Marionette DEBUG Closed connection 365
14:13:37 INFO - 1499462017531 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49792
14:13:37 INFO - 1499462017533 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:37 INFO - 1499462017535 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:37 INFO - 1499462017558 Marionette DEBUG Register listener.js for window 12
14:13:37 INFO - 1499462017569 Marionette TRACE 366 <- [1,1,null,{"sessionId":"19b16465-7480-4e18-9b51-1d32193c7ffe","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:37 INFO - 1499462017582 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:13:37 INFO - 1499462017583 Marionette TRACE 366 <- [1,2,null,{}]
14:13:37 INFO - 1499462017585 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:37 INFO - 1499462017587 Marionette TRACE 366 <- [1,3,null,{}]
14:13:37 INFO - 1499462017589 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:37 INFO - 1499462017590 Marionette TRACE 366 <- [1,4,null,{}]
14:13:37 INFO - 1499462017593 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}]
14:13:37 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499462017601 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:13:37 INFO - 1499462017602 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017606 Marionette TRACE 366 <- [1,6,null,{}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017612 Marionette DEBUG Closed connection 366
14:13:37 INFO - 1499462017614 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49793
14:13:37 INFO - 1499462017615 Marionette DEBUG Closed connection 367
14:13:37 INFO - 1499462017617 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49794
14:13:37 INFO - 1499462017619 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:37 INFO - 1499462017621 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:37 INFO - 1499462017644 Marionette DEBUG Register listener.js for window 12
14:13:37 INFO - 1499462017655 Marionette TRACE 368 <- [1,1,null,{"sessionId":"62ad96b8-2ece-48f6-b62b-13283d314cb6","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:37 INFO - 1499462017662 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017666 Marionette TRACE 368 <- [1,2,null,{}]
14:13:37 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:37 INFO - 1499462017671 Marionette DEBUG Closed connection 368
14:13:37 INFO - 1499462017673 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49795
14:13:37 INFO - 1499462017674 Marionette DEBUG Closed connection 369
14:13:37 INFO - 1499462017676 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49796
14:13:37 INFO - 1499462017678 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:37 INFO - 1499462017682 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:37 INFO - 1499462017710 Marionette DEBUG Register listener.js for window 12
14:13:37 INFO - 1499462017725 Marionette TRACE 370 <- [1,1,null,{"sessionId":"dea455df-391c-4aae-90e8-8bff031e74f8","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:37 INFO - 1499462017731 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"}]
14:13:37 INFO - 1499462017735 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"
14:13:37 INFO - 1499462017742 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"
14:13:37 INFO - 1499462017743 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"
14:13:37 INFO - ++DOMWINDOW == 24 (000000AF7ACE9000) [pid = 1136] [serial = 47] [outer = 000000AF7B014800]
14:13:37 INFO - 1499462017789 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"
14:13:37 INFO - 1499462017799 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"
14:13:37 INFO - 1499462017814 Marionette TRACE 370 <- [1,2,null,{}]
14:13:37 INFO - 1499462017868 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:13:37 INFO - 1499462017876 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6d5d975-1f5c-4f02-8976-60959bae6d41","ELEMENT":"a6d5d975-1f5c-4f02-8976-60959bae6d41"}}]
14:13:37 INFO - 1499462017878 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a6d5d975-1f5c-4f02-8976-60959bae6d41"}]
14:13:38 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
14:13:38 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
14:13:38 INFO - [1136] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
14:13:38 INFO - 1499462018301 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:13:38 INFO - 1499462018303 Marionette TRACE 370 <- [1,4,null,{}]
14:13:38 INFO - 1499462018305 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a6d5d975-1f5c-4f02-8976-60959bae6d41","name":"selected"}]
14:13:38 INFO - 1499462018330 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:13:38 INFO - 1499462018339 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}]
14:13:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499462018347 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:13:38 INFO - 1499462018350 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:13:38 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:38 INFO - 1499462018354 Marionette TRACE 370 <- [1,7,null,{}]
14:13:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 851ms
14:13:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:13:38 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:38 INFO - 1499462018360 Marionette DEBUG Closed connection 370
14:13:38 INFO - 1499462018361 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49797
14:13:38 INFO - 1499462018363 Marionette DEBUG Closed connection 371
14:13:38 INFO - 1499462018364 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49798
14:13:38 INFO - 1499462018367 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:38 INFO - 1499462018369 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:38 INFO - 1499462018391 Marionette DEBUG Register listener.js for window 12
14:13:38 INFO - 1499462018402 Marionette TRACE 372 <- [1,1,null,{"sessionId":"21cb2b17-1837-4500-994c-0727b324ec09","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:38 INFO - 1499462018431 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:13:38 INFO - 1499462018432 Marionette TRACE 372 <- [1,2,null,{}]
14:13:38 INFO - 1499462018434 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:38 INFO - 1499462018436 Marionette TRACE 372 <- [1,3,null,{}]
14:13:38 INFO - 1499462018440 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:38 INFO - 1499462018441 Marionette TRACE 372 <- [1,4,null,{}]
14:13:39 INFO - 1499462019092 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}]
14:13:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499462019101 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:13:39 INFO - 1499462019113 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019117 Marionette TRACE 372 <- [1,6,null,{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019139 Marionette DEBUG Closed connection 372
14:13:39 INFO - 1499462019140 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49799
14:13:39 INFO - 1499462019142 Marionette DEBUG Closed connection 373
14:13:39 INFO - 1499462019144 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49800
14:13:39 INFO - 1499462019146 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:39 INFO - 1499462019148 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:39 INFO - 1499462019170 Marionette DEBUG Register listener.js for window 12
14:13:39 INFO - 1499462019180 Marionette TRACE 374 <- [1,1,null,{"sessionId":"29ef56d9-2950-426d-91b6-063af0f8d3bf","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:39 INFO - 1499462019192 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019196 Marionette TRACE 374 <- [1,2,null,{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019202 Marionette DEBUG Closed connection 374
14:13:39 INFO - 1499462019203 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49801
14:13:39 INFO - 1499462019205 Marionette DEBUG Closed connection 375
14:13:39 INFO - 1499462019206 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49802
14:13:39 INFO - 1499462019208 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:39 INFO - 1499462019211 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:39 INFO - 1499462019242 Marionette DEBUG Register listener.js for window 12
14:13:39 INFO - 1499462019253 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5eaf24ae-e587-44ae-a398-192829de5e50","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:39 INFO - 1499462019259 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"}]
14:13:39 INFO - 1499462019263 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"
14:13:39 INFO - 1499462019272 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"
14:13:39 INFO - 1499462019273 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"
14:13:39 INFO - ++DOMWINDOW == 25 (000000AF7B00C800) [pid = 1136] [serial = 48] [outer = 000000AF7B014800]
14:13:39 INFO - 1499462019329 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"
14:13:39 INFO - 1499462019339 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"
14:13:39 INFO - 1499462019355 Marionette TRACE 376 <- [1,2,null,{}]
14:13:39 INFO - 1499462019358 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:13:39 INFO - 1499462019367 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d4d8094-e0a5-44e6-84ef-316fe550d1fb","ELEMENT":"8d4d8094-e0a5-44e6-84ef-316fe550d1fb"}}]
14:13:39 INFO - 1499462019369 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8d4d8094-e0a5-44e6-84ef-316fe550d1fb"}]
14:13:39 INFO - 1499462019767 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:13:39 INFO - 1499462019769 Marionette TRACE 376 <- [1,4,null,{}]
14:13:39 INFO - 1499462019772 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}]
14:13:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499462019779 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:13:39 INFO - 1499462019781 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019785 Marionette TRACE 376 <- [1,6,null,{}]
14:13:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1431ms
14:13:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019791 Marionette DEBUG Closed connection 376
14:13:39 INFO - 1499462019792 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49803
14:13:39 INFO - 1499462019794 Marionette DEBUG Closed connection 377
14:13:39 INFO - 1499462019795 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49804
14:13:39 INFO - 1499462019797 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:39 INFO - 1499462019799 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:39 INFO - 1499462019822 Marionette DEBUG Register listener.js for window 12
14:13:39 INFO - 1499462019833 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0033660d-5bbd-4771-b28e-ca7e8652edb8","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:39 INFO - 1499462019839 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:13:39 INFO - 1499462019841 Marionette TRACE 378 <- [1,2,null,{}]
14:13:39 INFO - 1499462019843 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:39 INFO - 1499462019844 Marionette TRACE 378 <- [1,3,null,{}]
14:13:39 INFO - 1499462019846 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:39 INFO - 1499462019847 Marionette TRACE 378 <- [1,4,null,{}]
14:13:39 INFO - 1499462019850 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}]
14:13:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499462019861 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:13:39 INFO - 1499462019863 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019867 Marionette TRACE 378 <- [1,6,null,{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019873 Marionette DEBUG Closed connection 378
14:13:39 INFO - 1499462019874 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49805
14:13:39 INFO - 1499462019878 Marionette DEBUG Closed connection 379
14:13:39 INFO - 1499462019879 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49806
14:13:39 INFO - 1499462019881 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:39 INFO - 1499462019883 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:39 INFO - 1499462019907 Marionette DEBUG Register listener.js for window 12
14:13:39 INFO - 1499462019918 Marionette TRACE 380 <- [1,1,null,{"sessionId":"6d90f6be-6f16-40e3-9446-6a353fa34725","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:39 INFO - 1499462019924 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019928 Marionette TRACE 380 <- [1,2,null,{}]
14:13:39 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:39 INFO - 1499462019934 Marionette DEBUG Closed connection 380
14:13:39 INFO - 1499462019936 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49807
14:13:39 INFO - 1499462019937 Marionette DEBUG Closed connection 381
14:13:39 INFO - 1499462019939 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49808
14:13:39 INFO - 1499462019941 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:39 INFO - 1499462019943 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:39 INFO - 1499462019967 Marionette DEBUG Register listener.js for window 12
14:13:39 INFO - 1499462019978 Marionette TRACE 382 <- [1,1,null,{"sessionId":"34eaa7d3-63f1-47b6-be01-2bea1b2eaee0","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:39 INFO - 1499462019984 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"}]
14:13:39 INFO - 1499462019988 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"
14:13:40 INFO - 1499462019994 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"
14:13:40 INFO - 1499462019995 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"
14:13:40 INFO - ++DOMWINDOW == 26 (000000AF7C6DB800) [pid = 1136] [serial = 49] [outer = 000000AF7B014800]
14:13:40 INFO - 1499462020059 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"
14:13:40 INFO - 1499462020075 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"
14:13:40 INFO - 1499462020090 Marionette TRACE 382 <- [1,2,null,{}]
14:13:40 INFO - 1499462020094 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:13:40 INFO - 1499462020101 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7b2c285-7f39-45ef-af26-054f619c617d","ELEMENT":"c7b2c285-7f39-45ef-af26-054f619c617d"}}]
14:13:40 INFO - 1499462020103 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c7b2c285-7f39-45ef-af26-054f619c617d"}]
14:13:40 INFO - 1499462020574 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:13:40 INFO - 1499462020577 Marionette TRACE 382 <- [1,4,null,{}]
14:13:40 INFO - 1499462020583 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}]
14:13:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499462020598 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:13:40 INFO - 1499462020604 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:13:40 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:40 INFO - 1499462020613 Marionette TRACE 382 <- [1,6,null,{}]
14:13:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 829ms
14:13:40 INFO - TEST-START | test_click.py TestClick.test_input_file
14:13:40 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:40 INFO - 1499462020672 Marionette DEBUG Closed connection 382
14:13:40 INFO - 1499462020675 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49809
14:13:40 INFO - 1499462020680 Marionette DEBUG Closed connection 383
14:13:40 INFO - 1499462020682 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49810
14:13:40 INFO - 1499462020707 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:40 INFO - 1499462020718 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:40 INFO - 1499462020764 Marionette DEBUG Register listener.js for window 12
14:13:40 INFO - 1499462020787 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5a8967fe-b842-4f3f-aa52-b6792798be50","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:40 INFO - 1499462020816 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:13:40 INFO - 1499462020820 Marionette TRACE 384 <- [1,2,null,{}]
14:13:40 INFO - 1499462020823 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:40 INFO - 1499462020827 Marionette TRACE 384 <- [1,3,null,{}]
14:13:40 INFO - 1499462020830 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:40 INFO - 1499462020833 Marionette TRACE 384 <- [1,4,null,{}]
14:13:40 INFO - 1499462020837 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}]
14:13:40 INFO - TEST-START: test_click.py TestClick.test_input_file1499462020850 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:13:40 INFO - 1499462020853 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:13:40 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:40 INFO - 1499462020859 Marionette TRACE 384 <- [1,6,null,{}]
14:13:40 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:40 INFO - 1499462020871 Marionette DEBUG Closed connection 384
14:13:40 INFO - 1499462020874 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49811
14:13:40 INFO - 1499462020876 Marionette DEBUG Closed connection 385
14:13:40 INFO - 1499462020879 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49812
14:13:40 INFO - 1499462020882 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:40 INFO - 1499462020885 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:40 INFO - 1499462020933 Marionette DEBUG Register listener.js for window 12
14:13:40 INFO - 1499462020960 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67445adf-0dfc-4206-bed0-ba4d62d6e720","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:40 INFO - 1499462020993 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021001 Marionette TRACE 386 <- [1,2,null,{}]
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021024 Marionette DEBUG Closed connection 386
14:13:41 INFO - 1499462021026 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49813
14:13:41 INFO - 1499462021079 Marionette DEBUG Closed connection 387
14:13:41 INFO - 1499462021081 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49814
14:13:41 INFO - 1499462021092 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:41 INFO - 1499462021098 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:41 INFO - 1499462021144 Marionette DEBUG Register listener.js for window 12
14:13:41 INFO - 1499462021160 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e917a87d-9ac9-4c55-ad10-cf9b3e45d3e2","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:41 INFO - 1499462021172 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:13:41 INFO - 1499462021177 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"
14:13:41 INFO - 1499462021188 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"
14:13:41 INFO - 1499462021191 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"
14:13:41 INFO - ++DOMWINDOW == 27 (000000AF6E557800) [pid = 1136] [serial = 50] [outer = 000000AF7B014800]
14:13:41 INFO - 1499462021257 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:13:41 INFO - 1499462021265 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:13:41 INFO - 1499462021295 Marionette TRACE 388 <- [1,2,null,{}]
14:13:41 INFO - 1499462021299 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:13:41 INFO - 1499462021311 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba9e6233-e222-43a6-b144-4298c4638bcd","ELEMENT":"ba9e6233-e222-43a6-b144-4298c4638bcd"}}]
14:13:41 INFO - 1499462021313 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ba9e6233-e222-43a6-b144-4298c4638bcd"}]
14:13:41 INFO - 1499462021538 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]
14:13:41 INFO - 1499462021558 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}]
14:13:41 INFO - TEST-END: test_click.py TestClick.test_input_file1499462021568 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:13:41 INFO - 1499462021571 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021576 Marionette TRACE 388 <- [1,6,null,{}]
14:13:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 962ms
14:13:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021633 Marionette DEBUG Closed connection 388
14:13:41 INFO - 1499462021635 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49815
14:13:41 INFO - 1499462021655 Marionette DEBUG Closed connection 389
14:13:41 INFO - 1499462021658 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49816
14:13:41 INFO - 1499462021661 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:41 INFO - 1499462021664 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:41 INFO - 1499462021711 Marionette DEBUG Register listener.js for window 12
14:13:41 INFO - 1499462021726 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8116e7bb-b6e0-469c-888e-fe8368aa7260","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:41 INFO - 1499462021760 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:13:41 INFO - 1499462021762 Marionette TRACE 390 <- [1,2,null,{}]
14:13:41 INFO - 1499462021768 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:41 INFO - 1499462021770 Marionette TRACE 390 <- [1,3,null,{}]
14:13:41 INFO - 1499462021772 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:41 INFO - 1499462021774 Marionette TRACE 390 <- [1,4,null,{}]
14:13:41 INFO - 1499462021818 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}]
14:13:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499462021829 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:13:41 INFO - 1499462021833 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021838 Marionette TRACE 390 <- [1,6,null,{}]
14:13:41 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:41 INFO - 1499462021861 Marionette DEBUG Closed connection 390
14:13:41 INFO - 1499462021863 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49817
14:13:41 INFO - 1499462021867 Marionette DEBUG Closed connection 391
14:13:41 INFO - 1499462021869 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49818
14:13:41 INFO - 1499462021872 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:41 INFO - 1499462021878 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:41 INFO - 1499462021925 Marionette DEBUG Register listener.js for window 12
14:13:41 INFO - 1499462021942 Marionette TRACE 392 <- [1,1,null,{"sessionId":"382c0142-53c6-4503-bad7-e6b1d2af0de5","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:42 INFO - 1499462022143 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:13:42 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:42 INFO - 1499462022153 Marionette TRACE 392 <- [1,2,null,{}]
14:13:42 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:42 INFO - 1499462022163 Marionette DEBUG Closed connection 392
14:13:42 INFO - 1499462022165 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49819
14:13:42 INFO - 1499462022168 Marionette DEBUG Closed connection 393
14:13:42 INFO - 1499462022171 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49820
14:13:42 INFO - 1499462022175 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:42 INFO - 1499462022186 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:42 INFO - 1499462022212 Marionette DEBUG Register listener.js for window 12
14:13:42 INFO - 1499462022250 Marionette TRACE 394 <- [1,1,null,{"sessionId":"99364a1a-0d75-4f21-8f7f-df07cb2cf5d1","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:42 INFO - 1499462022278 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"}]
14:13:42 INFO - 1499462022285 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:13:42 INFO - 1499462022296 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:13:42 INFO - 1499462022297 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:13:42 INFO - ++DOMWINDOW == 28 (000000AF054D2800) [pid = 1136] [serial = 51] [outer = 000000AF7B014800]
14:13:42 INFO - 1499462022359 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"
14:13:42 INFO - 1499462022367 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"
14:13:42 INFO - 1499462022386 Marionette TRACE 394 <- [1,2,null,{}]
14:13:42 INFO - 1499462022390 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:13:42 INFO - 1499462022403 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4875375-2493-4820-9c63-a66b22ad94be","ELEMENT":"a4875375-2493-4820-9c63-a66b22ad94be"}}]
14:13:42 INFO - 1499462022406 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:13:42 INFO - 1499462022416 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e8c7e9c-8ba3-4457-bf8a-90436713e9b3","ELEMENT":"1e8c7e9c-8ba3-4457-bf8a-90436713e9b3"}}]
14:13:42 INFO - 1499462022419 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a4875375-2493-4820-9c63-a66b22ad94be"}]
14:13:42 INFO - 1499462022847 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:13:42 INFO - 1499462022850 Marionette TRACE 394 <- [1,5,null,{}]
14:13:42 INFO - 1499462022854 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"1e8c7e9c-8ba3-4457-bf8a-90436713e9b3"}]
14:13:42 INFO - 1499462022976 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]
14:13:43 INFO - 1499462023006 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:13:43 INFO - 1499462023017 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:13:43 INFO - 1499462023020 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}]
14:13:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499462023030 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:13:43 INFO - 1499462023041 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023047 Marionette TRACE 394 <- [1,9,null,{}]
14:13:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1471ms
14:13:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023075 Marionette DEBUG Closed connection 394
14:13:43 INFO - 1499462023078 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49821
14:13:43 INFO - 1499462023083 Marionette DEBUG Closed connection 395
14:13:43 INFO - 1499462023085 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49822
14:13:43 INFO - 1499462023092 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:43 INFO - 1499462023097 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:43 INFO - 1499462023123 Marionette DEBUG Register listener.js for window 12
14:13:43 INFO - 1499462023140 Marionette TRACE 396 <- [1,1,null,{"sessionId":"797bd9de-4580-482b-ba44-d44cc31f25a4","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:43 INFO - 1499462023148 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:13:43 INFO - 1499462023150 Marionette TRACE 396 <- [1,2,null,{}]
14:13:43 INFO - 1499462023152 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:13:43 INFO - 1499462023155 Marionette TRACE 396 <- [1,3,null,{}]
14:13:43 INFO - 1499462023156 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:13:43 INFO - 1499462023158 Marionette TRACE 396 <- [1,4,null,{}]
14:13:43 INFO - 1499462023161 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}]
14:13:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499462023170 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:13:43 INFO - 1499462023172 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023177 Marionette TRACE 396 <- [1,6,null,{}]
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023197 Marionette DEBUG Closed connection 396
14:13:43 INFO - 1499462023199 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49823
14:13:43 INFO - 1499462023227 Marionette DEBUG Closed connection 397
14:13:43 INFO - 1499462023228 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49824
14:13:43 INFO - 1499462023231 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:13:43 INFO - 1499462023234 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:43 INFO - 1499462023261 Marionette DEBUG Register listener.js for window 12
14:13:43 INFO - 1499462023276 Marionette TRACE 398 <- [1,1,null,{"sessionId":"762e9452-5ae0-49b4-bfaf-f0c50afad3ab","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:43 INFO - 1499462023314 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023319 Marionette TRACE 398 <- [1,2,null,{}]
14:13:43 INFO - [1136] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:13:43 INFO - 1499462023350 Marionette DEBUG Closed connection 398
14:13:43 INFO - 1499462023352 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49825
14:13:43 INFO - 1499462023355 Marionette DEBUG Closed connection 399
14:13:43 INFO - 1499462023358 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49826
14:13:43 INFO - 1499462023364 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:13:43 INFO - 1499462023368 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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}
14:13:43 INFO - 1499462023392 Marionette DEBUG Register listener.js for window 12
14:13:43 INFO - 1499462023406 Marionette TRACE 400 <- [1,1,null,{"sessionId":"3c34fc90-475b-4308-aced-f008101bdb2d","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":1136,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmptiwa3a.mozrunner","moz:accessibilityChecks":false}}]
14:13:43 INFO - 1499462023416 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"}]
14:13:43 INFO - 1499462023420 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"
14:13:43 INFO - 1499462023429 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"
14:13:43 INFO - 1499462023430 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"
14:13:43 INFO - ++DOMWINDOW == 29 (000000AF03D21800) [pid = 1136] [serial = 52] [outer = 000000AF7B014800]
14:13:43 INFO - 1499462023493 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"
14:13:43 INFO - 1499462023498 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"
14:13:43 INFO - 1499462023516 Marionette TRACE 400 <- [1,2,null,{}]
14:13:43 INFO - 1499462023525 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:13:43 INFO - 1499462023533 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f1fab08-3c7a-4391-97b9-427b7e12ceb7","ELEMENT":"3f1fab08-3c7a-4391-97b9-427b7e12ceb7"}}]
14:13:43 INFO - 1499462023535 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3f1fab08-3c7a-4391-97b9-427b7e12ceb7"}]
14:13:43 INFO - 1499462023660 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:13:43 INFO - 1499462023662 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"3f1fab08-3c7a-4391-97b9-427b7e12ceb7"}]
14:13:43 INFO - 1499462023780 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element