could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:43:50 INFO - 1500407030967 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}]
12:43:50 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500407030974 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:43:50 INFO - 1500407030976 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:43:50 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:50 INFO - 1500407030981 Marionette TRACE 352 <- [1,6,null,{}]
12:43:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1327ms
12:43:50 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:43:50 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:50 INFO - 1500407030989 Marionette DEBUG Closed connection 352
12:43:50 INFO - 1500407030990 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49783
12:43:50 INFO - 1500407030992 Marionette DEBUG Closed connection 353
12:43:50 INFO - 1500407030993 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49784
12:43:51 INFO - 1500407030995 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:51 INFO - 1500407030997 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:51 INFO - 1500407031020 Marionette DEBUG Register listener.js for window 15
12:43:51 INFO - 1500407031031 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9ac8e9dd-38d5-4d42-a807-b7e38d9618d4","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:51 INFO - 1500407031038 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:43:51 INFO - 1500407031040 Marionette TRACE 354 <- [1,2,null,{}]
12:43:51 INFO - 1500407031042 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:51 INFO - 1500407031043 Marionette TRACE 354 <- [1,3,null,{}]
12:43:51 INFO - 1500407031070 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:51 INFO - 1500407031072 Marionette TRACE 354 <- [1,4,null,{}]
12:43:51 INFO - 1500407031074 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}]
12:43:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500407031081 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:43:51 INFO - 1500407031083 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031088 Marionette TRACE 354 <- [1,6,null,{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031094 Marionette DEBUG Closed connection 354
12:43:51 INFO - 1500407031095 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49785
12:43:51 INFO - 1500407031097 Marionette DEBUG Closed connection 355
12:43:51 INFO - 1500407031098 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49786
12:43:51 INFO - 1500407031100 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:51 INFO - 1500407031103 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:51 INFO - 1500407031124 Marionette DEBUG Register listener.js for window 15
12:43:51 INFO - 1500407031134 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ab18e3ec-ae29-4ac4-85ce-47b47e488785","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:51 INFO - 1500407031179 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031183 Marionette TRACE 356 <- [1,2,null,{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031206 Marionette DEBUG Closed connection 356
12:43:51 INFO - 1500407031208 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49787
12:43:51 INFO - 1500407031210 Marionette DEBUG Closed connection 357
12:43:51 INFO - 1500407031211 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49788
12:43:51 INFO - 1500407031213 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:51 INFO - 1500407031215 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:51 INFO - 1500407031237 Marionette DEBUG Register listener.js for window 15
12:43:51 INFO - 1500407031248 Marionette TRACE 358 <- [1,1,null,{"sessionId":"608cdf52-1b19-4f8f-84dc-a94282171171","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:51 INFO - 1500407031261 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
12:43:51 INFO - 1500407031265 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
12:43:51 INFO - 1500407031282 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
12:43:51 INFO - 1500407031283 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
12:43:51 INFO - ++DOMWINDOW == 23 (0000003021090000) [pid = 4032] [serial = 40] [outer = 00000030258CF000]
12:43:51 INFO - 1500407031338 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
12:43:51 INFO - 1500407031342 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
12:43:51 INFO - 1500407031360 Marionette TRACE 358 <- [1,2,null,{}]
12:43:51 INFO - 1500407031367 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:43:51 INFO - 1500407031379 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81077bbf-1151-4e6c-bf7b-1355dbac9f14","ELEMENT":"81077bbf-1151-4e6c-bf7b-1355dbac9f14"}}]
12:43:51 INFO - 1500407031381 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"81077bbf-1151-4e6c-bf7b-1355dbac9f14"}]
12:43:51 INFO - 1500407031648 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
12:43:51 INFO - [Parent 4032] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
12:43:51 INFO - 1500407031670 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
12:43:51 INFO - 1500407031671 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
12:43:51 INFO - ++DOMWINDOW == 24 (000000302DD4F000) [pid = 4032] [serial = 41] [outer = 00000030258CF000]
12:43:51 INFO - 1500407031735 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
12:43:51 INFO - 1500407031739 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
12:43:51 INFO - 1500407031756 Marionette TRACE 358 <- [1,4,null,{}]
12:43:51 INFO - 1500407031765 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:43:51 INFO - 1500407031777 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d81cf166-7390-4aa0-a95e-bc30bdcf2ab7","ELEMENT":"d81cf166-7390-4aa0-a95e-bc30bdcf2ab7"}}]
12:43:51 INFO - 1500407031779 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:43:51 INFO - 1500407031780 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:43:51 INFO - 1500407031783 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}]
12:43:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500407031790 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:43:51 INFO - 1500407031792 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031796 Marionette TRACE 358 <- [1,8,null,{}]
12:43:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 813ms
12:43:51 INFO - TEST-START | test_click.py TestClick.test_container_element
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031802 Marionette DEBUG Closed connection 358
12:43:51 INFO - 1500407031804 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49791
12:43:51 INFO - 1500407031805 Marionette DEBUG Closed connection 359
12:43:51 INFO - 1500407031807 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49792
12:43:51 INFO - 1500407031809 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:51 INFO - 1500407031811 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:51 INFO - 1500407031833 Marionette DEBUG Register listener.js for window 15
12:43:51 INFO - 1500407031844 Marionette TRACE 360 <- [1,1,null,{"sessionId":"912c7fb7-e7d0-46d6-af62-c97be0affbd2","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:51 INFO - 1500407031876 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:43:51 INFO - 1500407031878 Marionette TRACE 360 <- [1,2,null,{}]
12:43:51 INFO - 1500407031881 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:51 INFO - 1500407031882 Marionette TRACE 360 <- [1,3,null,{}]
12:43:51 INFO - 1500407031885 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:51 INFO - 1500407031886 Marionette TRACE 360 <- [1,4,null,{}]
12:43:51 INFO - 1500407031889 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}]
12:43:51 INFO - TEST-START: test_click.py TestClick.test_container_element1500407031896 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:43:51 INFO - 1500407031898 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031902 Marionette TRACE 360 <- [1,6,null,{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031908 Marionette DEBUG Closed connection 360
12:43:51 INFO - 1500407031909 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49793
12:43:51 INFO - 1500407031911 Marionette DEBUG Closed connection 361
12:43:51 INFO - 1500407031912 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49794
12:43:51 INFO - 1500407031915 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:51 INFO - 1500407031916 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:51 INFO - 1500407031938 Marionette DEBUG Register listener.js for window 15
12:43:51 INFO - 1500407031949 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4c4a04b7-03d7-408e-b0ae-bfe548d6157a","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:51 INFO - 1500407031980 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:43:51 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:51 INFO - 1500407031985 Marionette TRACE 362 <- [1,2,null,{}]
12:43:52 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:52 INFO - 1500407032010 Marionette DEBUG Closed connection 362
12:43:52 INFO - 1500407032012 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49795
12:43:52 INFO - 1500407032018 Marionette DEBUG Closed connection 363
12:43:52 INFO - 1500407032020 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49796
12:43:52 INFO - 1500407032023 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:52 INFO - 1500407032025 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:52 INFO - 1500407032047 Marionette DEBUG Register listener.js for window 15
12:43:52 INFO - 1500407032058 Marionette TRACE 364 <- [1,1,null,{"sessionId":"659eddda-4192-4f3c-9470-b11d020260d6","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:52 INFO - 1500407032068 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"}]
12:43:52 INFO - 1500407032072 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
12:43:52 INFO - 1500407032081 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
12:43:52 INFO - 1500407032082 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
12:43:52 INFO - ++DOMWINDOW == 25 (0000003021926000) [pid = 4032] [serial = 42] [outer = 00000030258CF000]
12:43:52 INFO - 1500407032154 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"
12:43:52 INFO - 1500407032165 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"
12:43:52 INFO - 1500407032183 Marionette TRACE 364 <- [1,2,null,{}]
12:43:52 INFO - 1500407032194 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:43:52 INFO - 1500407032201 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50c19bdc-40d3-4b0c-8c85-43bce5f029b9","ELEMENT":"50c19bdc-40d3-4b0c-8c85-43bce5f029b9"}}]
12:43:52 INFO - 1500407032203 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"50c19bdc-40d3-4b0c-8c85-43bce5f029b9"}]
12:43:52 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:43:52 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:43:52 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:43:53 INFO - 1500407033333 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:53 INFO - 1500407033339 Marionette TRACE 364 <- [1,4,null,{}]
12:43:53 INFO - 1500407033385 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"50c19bdc-40d3-4b0c-8c85-43bce5f029b9","name":"selected"}]
12:43:53 INFO - 1500407033414 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:43:53 INFO - 1500407033416 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}]
12:43:53 INFO - TEST-END: test_click.py TestClick.test_container_element1500407033424 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:43:53 INFO - 1500407033426 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033430 Marionette TRACE 364 <- [1,7,null,{}]
12:43:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1634ms
12:43:53 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033436 Marionette DEBUG Closed connection 364
12:43:53 INFO - 1500407033437 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49797
12:43:53 INFO - 1500407033439 Marionette DEBUG Closed connection 365
12:43:53 INFO - 1500407033440 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49798
12:43:53 INFO - 1500407033442 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:53 INFO - 1500407033444 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:53 INFO - 1500407033467 Marionette DEBUG Register listener.js for window 15
12:43:53 INFO - 1500407033524 Marionette TRACE 366 <- [1,1,null,{"sessionId":"84e13055-0dba-4de3-ad89-3840bde67a88","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:53 INFO - 1500407033607 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:43:53 INFO - 1500407033608 Marionette TRACE 366 <- [1,2,null,{}]
12:43:53 INFO - 1500407033611 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:53 INFO - 1500407033613 Marionette TRACE 366 <- [1,3,null,{}]
12:43:53 INFO - 1500407033615 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:53 INFO - 1500407033617 Marionette TRACE 366 <- [1,4,null,{}]
12:43:53 INFO - 1500407033668 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}]
12:43:53 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500407033676 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:43:53 INFO - 1500407033680 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033684 Marionette TRACE 366 <- [1,6,null,{}]
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033708 Marionette DEBUG Closed connection 366
12:43:53 INFO - 1500407033709 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49799
12:43:53 INFO - 1500407033808 Marionette DEBUG Closed connection 367
12:43:53 INFO - 1500407033809 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49800
12:43:53 INFO - 1500407033815 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:53 INFO - 1500407033817 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:53 INFO - 1500407033840 Marionette DEBUG Register listener.js for window 15
12:43:53 INFO - 1500407033852 Marionette TRACE 368 <- [1,1,null,{"sessionId":"931ba994-5b04-4721-9709-a2c2359b14fc","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:53 INFO - 1500407033861 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033866 Marionette TRACE 368 <- [1,2,null,{}]
12:43:53 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:53 INFO - 1500407033910 Marionette DEBUG Closed connection 368
12:43:53 INFO - 1500407033912 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49801
12:43:53 INFO - 1500407033936 Marionette DEBUG Closed connection 369
12:43:53 INFO - 1500407033938 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49802
12:43:53 INFO - 1500407033941 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:53 INFO - 1500407033943 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:53 INFO - 1500407033966 Marionette DEBUG Register listener.js for window 15
12:43:53 INFO - 1500407033976 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7d589c01-fb52-4916-bed8-e660793032e3","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:53 INFO - 1500407033988 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"}]
12:43:53 INFO - 1500407033993 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"
12:43:54 INFO - 1500407033999 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"
12:43:54 INFO - 1500407034000 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"
12:43:54 INFO - ++DOMWINDOW == 26 (0000003018D30800) [pid = 4032] [serial = 43] [outer = 00000030258CF000]
12:43:54 INFO - 1500407034055 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"
12:43:54 INFO - 1500407034072 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"
12:43:54 INFO - 1500407034091 Marionette TRACE 370 <- [1,2,null,{}]
12:43:54 INFO - 1500407034102 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:43:54 INFO - 1500407034109 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dba620d4-cbc6-4a1f-8e01-4dfdd38badaa","ELEMENT":"dba620d4-cbc6-4a1f-8e01-4dfdd38badaa"}}]
12:43:54 INFO - 1500407034111 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"dba620d4-cbc6-4a1f-8e01-4dfdd38badaa"}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:43:54 INFO - --DOMWINDOW == 25 (000000301D41C000) [pid = 4032] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
12:43:54 INFO - --DOMWINDOW == 24 (000000301F74A800) [pid = 4032] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
12:43:54 INFO - --DOMWINDOW == 23 (00000030258D5000) [pid = 4032] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
12:43:54 INFO - --DOMWINDOW == 22 (0000003025377000) [pid = 4032] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
12:43:54 INFO - 1500407034565 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:54 INFO - 1500407034567 Marionette TRACE 370 <- [1,4,null,{}]
12:43:54 INFO - 1500407034569 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"dba620d4-cbc6-4a1f-8e01-4dfdd38badaa","name":"selected"}]
12:43:54 INFO - 1500407034598 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:43:54 INFO - 1500407034601 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}]
12:43:54 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500407034608 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:43:54 INFO - 1500407034610 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034614 Marionette TRACE 370 <- [1,7,null,{}]
12:43:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1184ms
12:43:54 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034620 Marionette DEBUG Closed connection 370
12:43:54 INFO - 1500407034622 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49803
12:43:54 INFO - 1500407034623 Marionette DEBUG Closed connection 371
12:43:54 INFO - 1500407034625 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49804
12:43:54 INFO - 1500407034627 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:54 INFO - 1500407034629 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:54 INFO - 1500407034651 Marionette DEBUG Register listener.js for window 15
12:43:54 INFO - 1500407034662 Marionette TRACE 372 <- [1,1,null,{"sessionId":"aa1a6d0e-d0be-49fa-bfef-31410e0128fb","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:54 INFO - 1500407034668 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:43:54 INFO - 1500407034669 Marionette TRACE 372 <- [1,2,null,{}]
12:43:54 INFO - 1500407034674 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:54 INFO - 1500407034676 Marionette TRACE 372 <- [1,3,null,{}]
12:43:54 INFO - 1500407034678 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:54 INFO - 1500407034679 Marionette TRACE 372 <- [1,4,null,{}]
12:43:54 INFO - 1500407034682 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}]
12:43:54 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500407034689 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:43:54 INFO - 1500407034691 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034695 Marionette TRACE 372 <- [1,6,null,{}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034701 Marionette DEBUG Closed connection 372
12:43:54 INFO - 1500407034702 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49805
12:43:54 INFO - 1500407034704 Marionette DEBUG Closed connection 373
12:43:54 INFO - 1500407034706 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49806
12:43:54 INFO - 1500407034709 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:54 INFO - 1500407034711 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:54 INFO - 1500407034733 Marionette DEBUG Register listener.js for window 15
12:43:54 INFO - 1500407034744 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f0ff2d1b-bb51-4eb5-8c5a-a1c75c00b33c","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:54 INFO - 1500407034750 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034754 Marionette TRACE 374 <- [1,2,null,{}]
12:43:54 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:54 INFO - 1500407034760 Marionette DEBUG Closed connection 374
12:43:54 INFO - 1500407034761 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49807
12:43:54 INFO - 1500407034763 Marionette DEBUG Closed connection 375
12:43:54 INFO - 1500407034764 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49808
12:43:54 INFO - 1500407034766 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:54 INFO - 1500407034769 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:54 INFO - 1500407034791 Marionette DEBUG Register listener.js for window 15
12:43:54 INFO - 1500407034802 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0f2c1c62-b8be-4061-9a5b-7cb0e8ddc396","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:54 INFO - 1500407034808 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"}]
12:43:54 INFO - 1500407034813 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"
12:43:54 INFO - 1500407034819 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"
12:43:54 INFO - 1500407034821 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"
12:43:54 INFO - ++DOMWINDOW == 23 (000000302193A000) [pid = 4032] [serial = 44] [outer = 00000030258CF000]
12:43:54 INFO - 1500407034869 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"
12:43:54 INFO - 1500407034876 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"
12:43:54 INFO - 1500407034891 Marionette TRACE 376 <- [1,2,null,{}]
12:43:54 INFO - 1500407034895 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:43:54 INFO - 1500407034901 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"775cf0cd-f95a-43a3-a2cd-ada0ab9391a9","ELEMENT":"775cf0cd-f95a-43a3-a2cd-ada0ab9391a9"}}]
12:43:54 INFO - 1500407034903 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"775cf0cd-f95a-43a3-a2cd-ada0ab9391a9"}]
12:43:55 INFO - 1500407035342 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:55 INFO - 1500407035344 Marionette TRACE 376 <- [1,4,null,{}]
12:43:55 INFO - 1500407035347 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}]
12:43:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500407035354 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:43:55 INFO - 1500407035356 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035360 Marionette TRACE 376 <- [1,6,null,{}]
12:43:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 747ms
12:43:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035366 Marionette DEBUG Closed connection 376
12:43:55 INFO - 1500407035368 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49809
12:43:55 INFO - 1500407035370 Marionette DEBUG Closed connection 377
12:43:55 INFO - 1500407035371 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49810
12:43:55 INFO - 1500407035373 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:55 INFO - 1500407035375 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:55 INFO - 1500407035397 Marionette DEBUG Register listener.js for window 15
12:43:55 INFO - 1500407035410 Marionette TRACE 378 <- [1,1,null,{"sessionId":"18c0b670-d82a-4ade-a661-a8af4353aac5","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:55 INFO - 1500407035416 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:43:55 INFO - 1500407035417 Marionette TRACE 378 <- [1,2,null,{}]
12:43:55 INFO - 1500407035419 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:55 INFO - 1500407035421 Marionette TRACE 378 <- [1,3,null,{}]
12:43:55 INFO - 1500407035422 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:55 INFO - 1500407035424 Marionette TRACE 378 <- [1,4,null,{}]
12:43:55 INFO - 1500407035427 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}]
12:43:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500407035434 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:43:55 INFO - 1500407035435 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035440 Marionette TRACE 378 <- [1,6,null,{}]
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035448 Marionette DEBUG Closed connection 378
12:43:55 INFO - 1500407035449 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49811
12:43:55 INFO - 1500407035451 Marionette DEBUG Closed connection 379
12:43:55 INFO - 1500407035452 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49812
12:43:55 INFO - 1500407035454 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:55 INFO - 1500407035456 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:55 INFO - 1500407035479 Marionette DEBUG Register listener.js for window 15
12:43:55 INFO - 1500407035491 Marionette TRACE 380 <- [1,1,null,{"sessionId":"186e43a8-ce2a-4f7b-bb46-01a83098f94d","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:55 INFO - 1500407035497 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035501 Marionette TRACE 380 <- [1,2,null,{}]
12:43:55 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:55 INFO - 1500407035507 Marionette DEBUG Closed connection 380
12:43:55 INFO - 1500407035508 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49813
12:43:55 INFO - 1500407035510 Marionette DEBUG Closed connection 381
12:43:55 INFO - 1500407035511 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49814
12:43:55 INFO - 1500407035513 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:55 INFO - 1500407035516 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:55 INFO - 1500407035538 Marionette DEBUG Register listener.js for window 15
12:43:55 INFO - 1500407035549 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ede9f41c-1358-463c-a405-e8b88ff474dd","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:55 INFO - 1500407035555 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"}]
12:43:55 INFO - 1500407035561 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"
12:43:55 INFO - 1500407035567 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"
12:43:55 INFO - 1500407035568 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"
12:43:55 INFO - ++DOMWINDOW == 24 (000000302C530000) [pid = 4032] [serial = 45] [outer = 00000030258CF000]
12:43:55 INFO - 1500407035620 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"
12:43:55 INFO - 1500407035625 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"
12:43:55 INFO - 1500407035643 Marionette TRACE 382 <- [1,2,null,{}]
12:43:55 INFO - 1500407035646 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:43:55 INFO - 1500407035652 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00f75b41-d284-441b-af98-4b9e0f159276","ELEMENT":"00f75b41-d284-441b-af98-4b9e0f159276"}}]
12:43:55 INFO - 1500407035654 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"00f75b41-d284-441b-af98-4b9e0f159276"}]
12:43:56 INFO - 1500407036101 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:56 INFO - 1500407036103 Marionette TRACE 382 <- [1,4,null,{}]
12:43:56 INFO - 1500407036106 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}]
12:43:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500407036113 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:43:56 INFO - 1500407036115 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036119 Marionette TRACE 382 <- [1,6,null,{}]
12:43:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 758ms
12:43:56 INFO - TEST-START | test_click.py TestClick.test_input_file
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036125 Marionette DEBUG Closed connection 382
12:43:56 INFO - 1500407036126 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49815
12:43:56 INFO - 1500407036127 Marionette DEBUG Closed connection 383
12:43:56 INFO - 1500407036129 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49816
12:43:56 INFO - 1500407036131 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:56 INFO - 1500407036133 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036156 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036166 Marionette TRACE 384 <- [1,1,null,{"sessionId":"648fcfc8-22d6-4688-a5a8-7baefd769bac","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036172 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:43:56 INFO - 1500407036174 Marionette TRACE 384 <- [1,2,null,{}]
12:43:56 INFO - 1500407036176 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:56 INFO - 1500407036177 Marionette TRACE 384 <- [1,3,null,{}]
12:43:56 INFO - 1500407036179 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:56 INFO - 1500407036180 Marionette TRACE 384 <- [1,4,null,{}]
12:43:56 INFO - 1500407036183 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}]
12:43:56 INFO - TEST-START: test_click.py TestClick.test_input_file1500407036190 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:43:56 INFO - 1500407036192 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036196 Marionette TRACE 384 <- [1,6,null,{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036202 Marionette DEBUG Closed connection 384
12:43:56 INFO - 1500407036203 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49817
12:43:56 INFO - 1500407036207 Marionette DEBUG Closed connection 385
12:43:56 INFO - 1500407036208 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49818
12:43:56 INFO - 1500407036211 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:56 INFO - 1500407036213 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036237 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036248 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fe018533-470b-4e35-b7f8-d57d1d21cb27","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036254 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036258 Marionette TRACE 386 <- [1,2,null,{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036263 Marionette DEBUG Closed connection 386
12:43:56 INFO - 1500407036265 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49819
12:43:56 INFO - 1500407036266 Marionette DEBUG Closed connection 387
12:43:56 INFO - 1500407036267 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49820
12:43:56 INFO - 1500407036270 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:56 INFO - 1500407036272 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036296 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036309 Marionette TRACE 388 <- [1,1,null,{"sessionId":"aaf9f2b3-bc02-4623-937f-2dfc64543457","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036314 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:43:56 INFO - 1500407036318 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"
12:43:56 INFO - 1500407036323 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"
12:43:56 INFO - 1500407036325 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"
12:43:56 INFO - ++DOMWINDOW == 25 (000000302D87C000) [pid = 4032] [serial = 46] [outer = 00000030258CF000]
12:43:56 INFO - 1500407036368 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:56 INFO - 1500407036377 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:56 INFO - 1500407036391 Marionette TRACE 388 <- [1,2,null,{}]
12:43:56 INFO - 1500407036394 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:43:56 INFO - 1500407036400 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ae5e0ce9-cf26-4175-bc14-35df3ea909a8","ELEMENT":"ae5e0ce9-cf26-4175-bc14-35df3ea909a8"}}]
12:43:56 INFO - 1500407036402 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ae5e0ce9-cf26-4175-bc14-35df3ea909a8"}]
12:43:56 INFO - 1500407036555 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:43:56 INFO - 1500407036558 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}]
12:43:56 INFO - TEST-END: test_click.py TestClick.test_input_file1500407036576 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:43:56 INFO - 1500407036579 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036583 Marionette TRACE 388 <- [1,6,null,{}]
12:43:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 464ms
12:43:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036589 Marionette DEBUG Closed connection 388
12:43:56 INFO - 1500407036591 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49821
12:43:56 INFO - 1500407036592 Marionette DEBUG Closed connection 389
12:43:56 INFO - 1500407036594 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49822
12:43:56 INFO - 1500407036596 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:56 INFO - 1500407036598 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036621 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036642 Marionette TRACE 390 <- [1,1,null,{"sessionId":"606921cd-be94-40a3-bb63-dc497ba156d0","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036649 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:43:56 INFO - 1500407036650 Marionette TRACE 390 <- [1,2,null,{}]
12:43:56 INFO - 1500407036652 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:56 INFO - 1500407036653 Marionette TRACE 390 <- [1,3,null,{}]
12:43:56 INFO - 1500407036655 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:56 INFO - 1500407036656 Marionette TRACE 390 <- [1,4,null,{}]
12:43:56 INFO - 1500407036659 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}]
12:43:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500407036666 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:43:56 INFO - 1500407036668 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036672 Marionette TRACE 390 <- [1,6,null,{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036678 Marionette DEBUG Closed connection 390
12:43:56 INFO - 1500407036679 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49823
12:43:56 INFO - 1500407036681 Marionette DEBUG Closed connection 391
12:43:56 INFO - 1500407036682 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49824
12:43:56 INFO - 1500407036685 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:56 INFO - 1500407036687 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036711 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036721 Marionette TRACE 392 <- [1,1,null,{"sessionId":"732efe19-bcde-4d4f-98a1-4fea59f5b773","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036727 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036731 Marionette TRACE 392 <- [1,2,null,{}]
12:43:56 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:56 INFO - 1500407036737 Marionette DEBUG Closed connection 392
12:43:56 INFO - 1500407036739 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49825
12:43:56 INFO - 1500407036740 Marionette DEBUG Closed connection 393
12:43:56 INFO - 1500407036742 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49826
12:43:56 INFO - 1500407036744 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:56 INFO - 1500407036746 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:56 INFO - 1500407036769 Marionette DEBUG Register listener.js for window 15
12:43:56 INFO - 1500407036779 Marionette TRACE 394 <- [1,1,null,{"sessionId":"111d781f-1ed0-4935-b359-fdf938310cf5","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:56 INFO - 1500407036786 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"}]
12:43:56 INFO - 1500407036789 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:56 INFO - 1500407036794 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:56 INFO - 1500407036795 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:56 INFO - ++DOMWINDOW == 26 (000000302DCE2000) [pid = 4032] [serial = 47] [outer = 00000030258CF000]
12:43:56 INFO - 1500407036846 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"
12:43:56 INFO - 1500407036851 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"
12:43:56 INFO - 1500407036858 Marionette TRACE 394 <- [1,2,null,{}]
12:43:56 INFO - 1500407036869 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:43:56 INFO - 1500407036876 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f18eb7b0-f117-4618-b716-27976627b66b","ELEMENT":"f18eb7b0-f117-4618-b716-27976627b66b"}}]
12:43:56 INFO - 1500407036878 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:43:56 INFO - 1500407036883 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e40d086f-c493-4317-94c6-6bb8481f1ac6","ELEMENT":"e40d086f-c493-4317-94c6-6bb8481f1ac6"}}]
12:43:56 INFO - 1500407036885 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f18eb7b0-f117-4618-b716-27976627b66b"}]
12:43:57 INFO - 1500407037315 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:57 INFO - 1500407037316 Marionette TRACE 394 <- [1,5,null,{}]
12:43:57 INFO - 1500407037318 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"e40d086f-c493-4317-94c6-6bb8481f1ac6"}]
12:43:57 INFO - 1500407037450 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:43:57 INFO - 1500407037453 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
12:43:57 INFO - 1500407037460 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:43:57 INFO - 1500407037462 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}]
12:43:57 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500407037469 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:43:57 INFO - 1500407037471 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037475 Marionette TRACE 394 <- [1,9,null,{}]
12:43:57 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 891ms
12:43:57 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037481 Marionette DEBUG Closed connection 394
12:43:57 INFO - 1500407037482 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49827
12:43:57 INFO - 1500407037483 Marionette DEBUG Closed connection 395
12:43:57 INFO - 1500407037485 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49828
12:43:57 INFO - 1500407037487 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:57 INFO - 1500407037489 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:57 INFO - 1500407037512 Marionette DEBUG Register listener.js for window 15
12:43:57 INFO - 1500407037523 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5dd5d8f6-a19a-4c8e-b970-d1a2fa5e4185","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:57 INFO - 1500407037528 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:43:57 INFO - 1500407037530 Marionette TRACE 396 <- [1,2,null,{}]
12:43:57 INFO - 1500407037531 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:57 INFO - 1500407037533 Marionette TRACE 396 <- [1,3,null,{}]
12:43:57 INFO - 1500407037534 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:57 INFO - 1500407037536 Marionette TRACE 396 <- [1,4,null,{}]
12:43:57 INFO - 1500407037538 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}]
12:43:57 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500407037545 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:43:57 INFO - 1500407037547 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037551 Marionette TRACE 396 <- [1,6,null,{}]
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037557 Marionette DEBUG Closed connection 396
12:43:57 INFO - 1500407037559 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49829
12:43:57 INFO - 1500407037560 Marionette DEBUG Closed connection 397
12:43:57 INFO - 1500407037562 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49830
12:43:57 INFO - 1500407037564 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:57 INFO - 1500407037566 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:57 INFO - 1500407037590 Marionette DEBUG Register listener.js for window 15
12:43:57 INFO - 1500407037600 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cbd4a866-dca1-44dc-8fbd-419cb25e9cbd","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:57 INFO - 1500407037606 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037610 Marionette TRACE 398 <- [1,2,null,{}]
12:43:57 INFO - [Parent 4032] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:43:57 INFO - 1500407037616 Marionette DEBUG Closed connection 398
12:43:57 INFO - 1500407037617 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49831
12:43:57 INFO - 1500407037618 Marionette DEBUG Closed connection 399
12:43:57 INFO - 1500407037620 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49832
12:43:57 INFO - 1500407037622 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:57 INFO - 1500407037624 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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}
12:43:57 INFO - 1500407037648 Marionette DEBUG Register listener.js for window 15
12:43:57 INFO - 1500407037658 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e9d268ad-efef-48a2-aa9d-7382889f8521","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":4032,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-355.001\\AppData\\Local\\Temp\\tmpvpyaqx.mozrunner","moz:accessibilityChecks":false}}]
12:43:57 INFO - 1500407037664 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"}]
12:43:57 INFO - 1500407037668 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"
12:43:57 INFO - 1500407037677 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"
12:43:57 INFO - 1500407037677 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"
12:43:57 INFO - ++DOMWINDOW == 27 (000000302DEF3800) [pid = 4032] [serial = 48] [outer = 00000030258CF000]
12:43:57 INFO - 1500407037738 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"
12:43:57 INFO - 1500407037743 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"
12:43:57 INFO - 1500407037759 Marionette TRACE 400 <- [1,2,null,{}]
12:43:57 INFO - 1500407037761 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:43:57 INFO - 1500407037768 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9a49f11-5f0c-4fce-ae23-bc3f0a0f2a7f","ELEMENT":"d9a49f11-5f0c-4fce-ae23-bc3f0a0f2a7f"}}]
12:43:57 INFO - 1500407037769 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d9a49f11-5f0c-4fce-ae23-bc3f0a0f2a7f"}]
12:43:57 INFO - 1500407037917 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:43:57 INFO - 1500407037921 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d9a49f11-5f0c-4fce-ae23-bc3f0a0f2a7f"}]
12:43:58 INFO - 1500407038059 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element