could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:30:16 INFO - 1499535016846 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
10:30:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499535016855 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:30:16 INFO - 1499535016857 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:30:16 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:16 INFO - 1499535016861 Marionette TRACE 352 <- [1,6,null,{}]
10:30:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1576ms
10:30:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:30:16 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:16 INFO - 1499535016867 Marionette DEBUG Closed connection 352
10:30:16 INFO - 1499535016869 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49775
10:30:16 INFO - 1499535016871 Marionette DEBUG Closed connection 353
10:30:16 INFO - 1499535016873 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49776
10:30:16 INFO - 1499535016875 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:16 INFO - 1499535016880 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:16 INFO - 1499535016902 Marionette DEBUG Register listener.js for window 12
10:30:16 INFO - 1499535016967 Marionette TRACE 354 <- [1,1,null,{"sessionId":"0501ead1-dd69-4e1c-8604-4cf30453abb1","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:17 INFO - 1499535017009 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:30:17 INFO - 1499535017010 Marionette TRACE 354 <- [1,2,null,{}]
10:30:17 INFO - 1499535017024 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:17 INFO - 1499535017026 Marionette TRACE 354 <- [1,3,null,{}]
10:30:17 INFO - 1499535017029 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:17 INFO - 1499535017030 Marionette TRACE 354 <- [1,4,null,{}]
10:30:17 INFO - 1499535017033 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
10:30:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499535017045 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:30:17 INFO - 1499535017048 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017054 Marionette TRACE 354 <- [1,6,null,{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017077 Marionette DEBUG Closed connection 354
10:30:17 INFO - 1499535017082 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49777
10:30:17 INFO - 1499535017086 Marionette DEBUG Closed connection 355
10:30:17 INFO - 1499535017088 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49778
10:30:17 INFO - 1499535017090 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:17 INFO - 1499535017093 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:17 INFO - 1499535017114 Marionette DEBUG Register listener.js for window 12
10:30:17 INFO - 1499535017128 Marionette TRACE 356 <- [1,1,null,{"sessionId":"0f6a7bc2-e4ea-406c-880e-d83e235e9ee2","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:17 INFO - 1499535017134 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017138 Marionette TRACE 356 <- [1,2,null,{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017144 Marionette DEBUG Closed connection 356
10:30:17 INFO - 1499535017146 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49779
10:30:17 INFO - 1499535017147 Marionette DEBUG Closed connection 357
10:30:17 INFO - 1499535017149 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49780
10:30:17 INFO - 1499535017151 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:17 INFO - 1499535017154 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:17 INFO - 1499535017178 Marionette DEBUG Register listener.js for window 12
10:30:17 INFO - 1499535017190 Marionette TRACE 358 <- [1,1,null,{"sessionId":"49956dfe-8322-4f37-9449-19eb55a39b64","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:17 INFO - 1499535017224 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
10:30:17 INFO - 1499535017228 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
10:30:17 INFO - 1499535017241 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
10:30:17 INFO - 1499535017242 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
10:30:17 INFO - ++DOMWINDOW == 22 (000000388E284800) [pid = 3436] [serial = 44] [outer = 00000038857E5000]
10:30:17 INFO - 1499535017313 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
10:30:17 INFO - 1499535017318 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
10:30:17 INFO - 1499535017341 Marionette TRACE 358 <- [1,2,null,{}]
10:30:17 INFO - 1499535017344 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:30:17 INFO - 1499535017355 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"578369db-d7e5-400f-ad26-1343144deeb7","ELEMENT":"578369db-d7e5-400f-ad26-1343144deeb7"}}]
10:30:17 INFO - 1499535017357 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"578369db-d7e5-400f-ad26-1343144deeb7"}]
10:30:17 INFO - 1499535017626 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
10:30:17 INFO - [3436] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
10:30:17 INFO - 1499535017645 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
10:30:17 INFO - 1499535017647 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
10:30:17 INFO - ++DOMWINDOW == 23 (0000003888343000) [pid = 3436] [serial = 45] [outer = 00000038857E5000]
10:30:17 INFO - 1499535017708 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
10:30:17 INFO - 1499535017713 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
10:30:17 INFO - 1499535017730 Marionette TRACE 358 <- [1,4,null,{}]
10:30:17 INFO - 1499535017737 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:30:17 INFO - 1499535017745 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8da13243-858f-4ad6-98b4-95f1e95d30f6","ELEMENT":"8da13243-858f-4ad6-98b4-95f1e95d30f6"}}]
10:30:17 INFO - 1499535017749 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:30:17 INFO - 1499535017751 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:30:17 INFO - 1499535017753 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
10:30:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499535017761 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:30:17 INFO - 1499535017763 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017767 Marionette TRACE 358 <- [1,8,null,{}]
10:30:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 907ms
10:30:17 INFO - TEST-START | test_click.py TestClick.test_container_element
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017774 Marionette DEBUG Closed connection 358
10:30:17 INFO - 1499535017775 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49783
10:30:17 INFO - 1499535017777 Marionette DEBUG Closed connection 359
10:30:17 INFO - 1499535017779 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49784
10:30:17 INFO - 1499535017782 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:17 INFO - 1499535017784 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:17 INFO - 1499535017806 Marionette DEBUG Register listener.js for window 12
10:30:17 INFO - 1499535017818 Marionette TRACE 360 <- [1,1,null,{"sessionId":"065dc459-a142-4551-9a1b-a306c4ad290c","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:17 INFO - 1499535017847 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:30:17 INFO - 1499535017848 Marionette TRACE 360 <- [1,2,null,{}]
10:30:17 INFO - 1499535017851 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:17 INFO - 1499535017853 Marionette TRACE 360 <- [1,3,null,{}]
10:30:17 INFO - 1499535017855 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:17 INFO - 1499535017856 Marionette TRACE 360 <- [1,4,null,{}]
10:30:17 INFO - 1499535017859 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
10:30:17 INFO - TEST-START: test_click.py TestClick.test_container_element1499535017869 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:30:17 INFO - 1499535017871 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017876 Marionette TRACE 360 <- [1,6,null,{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017900 Marionette DEBUG Closed connection 360
10:30:17 INFO - 1499535017901 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49785
10:30:17 INFO - 1499535017905 Marionette DEBUG Closed connection 361
10:30:17 INFO - 1499535017906 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49786
10:30:17 INFO - 1499535017909 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:17 INFO - 1499535017911 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:17 INFO - 1499535017932 Marionette DEBUG Register listener.js for window 12
10:30:17 INFO - 1499535017944 Marionette TRACE 362 <- [1,1,null,{"sessionId":"40464784-d8df-4c20-bb5d-000d8edd12cd","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:17 INFO - 1499535017954 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017958 Marionette TRACE 362 <- [1,2,null,{}]
10:30:17 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:17 INFO - 1499535017979 Marionette DEBUG Closed connection 362
10:30:17 INFO - 1499535017980 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49787
10:30:17 INFO - 1499535017984 Marionette DEBUG Closed connection 363
10:30:17 INFO - 1499535017985 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49788
10:30:17 INFO - 1499535017988 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:17 INFO - 1499535017990 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:18 INFO - 1499535018012 Marionette DEBUG Register listener.js for window 12
10:30:18 INFO - 1499535018023 Marionette TRACE 364 <- [1,1,null,{"sessionId":"480fa005-59d0-420d-a631-c3ef35d2790e","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:18 INFO - 1499535018033 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:30:18 INFO - 1499535018037 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
10:30:18 INFO - 1499535018046 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
10:30:18 INFO - 1499535018046 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
10:30:18 INFO - ++DOMWINDOW == 24 (000000388D8D9800) [pid = 3436] [serial = 46] [outer = 00000038857E5000]
10:30:18 INFO - 1499535018110 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - 1499535018117 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - 1499535018134 Marionette TRACE 364 <- [1,2,null,{}]
10:30:18 INFO - 1499535018140 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:30:18 INFO - 1499535018149 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa349dab-d585-4fa4-9b49-8072a7b17d82","ELEMENT":"fa349dab-d585-4fa4-9b49-8072a7b17d82"}}]
10:30:18 INFO - 1499535018154 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fa349dab-d585-4fa4-9b49-8072a7b17d82"}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
10:30:18 INFO - 1499535018600 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:30:18 INFO - 1499535018602 Marionette TRACE 364 <- [1,4,null,{}]
10:30:18 INFO - 1499535018604 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fa349dab-d585-4fa4-9b49-8072a7b17d82","name":"selected"}]
10:30:18 INFO - 1499535018631 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:30:18 INFO - 1499535018634 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
10:30:18 INFO - TEST-END: test_click.py TestClick.test_container_element1499535018641 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:30:18 INFO - 1499535018643 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018647 Marionette TRACE 364 <- [1,7,null,{}]
10:30:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 880ms
10:30:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018654 Marionette DEBUG Closed connection 364
10:30:18 INFO - 1499535018655 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49789
10:30:18 INFO - 1499535018656 Marionette DEBUG Closed connection 365
10:30:18 INFO - 1499535018658 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49790
10:30:18 INFO - 1499535018660 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:18 INFO - 1499535018663 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:18 INFO - 1499535018700 Marionette DEBUG Register listener.js for window 12
10:30:18 INFO - 1499535018743 Marionette TRACE 366 <- [1,1,null,{"sessionId":"293f53a7-96c3-45a5-9079-26f0f38edcad","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:18 INFO - 1499535018752 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:30:18 INFO - 1499535018754 Marionette TRACE 366 <- [1,2,null,{}]
10:30:18 INFO - 1499535018756 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:18 INFO - 1499535018758 Marionette TRACE 366 <- [1,3,null,{}]
10:30:18 INFO - 1499535018760 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:18 INFO - 1499535018761 Marionette TRACE 366 <- [1,4,null,{}]
10:30:18 INFO - 1499535018764 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
10:30:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499535018773 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:30:18 INFO - 1499535018775 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018779 Marionette TRACE 366 <- [1,6,null,{}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018803 Marionette DEBUG Closed connection 366
10:30:18 INFO - 1499535018805 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49791
10:30:18 INFO - 1499535018806 Marionette DEBUG Closed connection 367
10:30:18 INFO - 1499535018808 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49792
10:30:18 INFO - 1499535018810 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:18 INFO - 1499535018812 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:18 INFO - 1499535018833 Marionette DEBUG Register listener.js for window 12
10:30:18 INFO - 1499535018844 Marionette TRACE 368 <- [1,1,null,{"sessionId":"93bc637e-5b1b-45a0-bb68-0944adaf5e3a","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:18 INFO - 1499535018856 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018860 Marionette TRACE 368 <- [1,2,null,{}]
10:30:18 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:18 INFO - 1499535018865 Marionette DEBUG Closed connection 368
10:30:18 INFO - 1499535018867 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49793
10:30:18 INFO - 1499535018868 Marionette DEBUG Closed connection 369
10:30:18 INFO - 1499535018870 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49794
10:30:18 INFO - 1499535018872 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:18 INFO - 1499535018874 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:18 INFO - 1499535018896 Marionette DEBUG Register listener.js for window 12
10:30:18 INFO - 1499535018907 Marionette TRACE 370 <- [1,1,null,{"sessionId":"67b3ab10-2ea4-4c36-a47b-3b3d9370bb0d","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:18 INFO - 1499535018913 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:30:18 INFO - 1499535018917 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - 1499535018923 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - 1499535018924 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - ++DOMWINDOW == 25 (000000388F1BD000) [pid = 3436] [serial = 47] [outer = 00000038857E5000]
10:30:18 INFO - 1499535018975 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:18 INFO - 1499535018980 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:19 INFO - 1499535018998 Marionette TRACE 370 <- [1,2,null,{}]
10:30:19 INFO - 1499535019026 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:30:19 INFO - 1499535019033 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fd23c007-da92-4ed5-bc5e-dba0fc7c3769","ELEMENT":"fd23c007-da92-4ed5-bc5e-dba0fc7c3769"}}]
10:30:19 INFO - 1499535019035 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fd23c007-da92-4ed5-bc5e-dba0fc7c3769"}]
10:30:19 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
10:30:19 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
10:30:19 INFO - [3436] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
10:30:20 INFO - 1499535020049 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:30:20 INFO - 1499535020062 Marionette TRACE 370 <- [1,4,null,{}]
10:30:20 INFO - 1499535020100 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fd23c007-da92-4ed5-bc5e-dba0fc7c3769","name":"selected"}]
10:30:20 INFO - 1499535020126 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:30:20 INFO - 1499535020129 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
10:30:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499535020136 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:30:20 INFO - 1499535020138 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020142 Marionette TRACE 370 <- [1,7,null,{}]
10:30:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1495ms
10:30:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020148 Marionette DEBUG Closed connection 370
10:30:20 INFO - 1499535020149 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49795
10:30:20 INFO - 1499535020153 Marionette DEBUG Closed connection 371
10:30:20 INFO - 1499535020155 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49796
10:30:20 INFO - 1499535020195 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:20 INFO - 1499535020198 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:20 INFO - 1499535020219 Marionette DEBUG Register listener.js for window 12
10:30:20 INFO - 1499535020278 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7359d3f8-62e5-427b-96f7-722c7db933dd","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:20 INFO - 1499535020296 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:30:20 INFO - 1499535020301 Marionette TRACE 372 <- [1,2,null,{}]
10:30:20 INFO - 1499535020354 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:20 INFO - 1499535020358 Marionette TRACE 372 <- [1,3,null,{}]
10:30:20 INFO - 1499535020363 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:20 INFO - 1499535020366 Marionette TRACE 372 <- [1,4,null,{}]
10:30:20 INFO - 1499535020420 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
10:30:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499535020434 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:30:20 INFO - 1499535020439 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020448 Marionette TRACE 372 <- [1,6,null,{}]
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020511 Marionette DEBUG Closed connection 372
10:30:20 INFO - 1499535020514 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49797
10:30:20 INFO - 1499535020534 Marionette DEBUG Closed connection 373
10:30:20 INFO - 1499535020537 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49798
10:30:20 INFO - 1499535020540 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:20 INFO - 1499535020544 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:20 INFO - 1499535020590 Marionette DEBUG Register listener.js for window 12
10:30:20 INFO - 1499535020610 Marionette TRACE 374 <- [1,1,null,{"sessionId":"34514cbb-f0db-430c-a88d-7e288d078644","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:20 INFO - 1499535020639 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020646 Marionette TRACE 374 <- [1,2,null,{}]
10:30:20 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:20 INFO - 1499535020672 Marionette DEBUG Closed connection 374
10:30:20 INFO - 1499535020675 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49799
10:30:20 INFO - 1499535020679 Marionette DEBUG Closed connection 375
10:30:20 INFO - 1499535020681 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49800
10:30:20 INFO - 1499535020687 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:20 INFO - 1499535020691 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:20 INFO - 1499535020737 Marionette DEBUG Register listener.js for window 12
10:30:20 INFO - 1499535020754 Marionette TRACE 376 <- [1,1,null,{"sessionId":"43072478-8cf0-4830-9e9a-c93410d8d780","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:20 INFO - 1499535020778 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
10:30:20 INFO - 1499535020784 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:20 INFO - 1499535020797 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:20 INFO - 1499535020799 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:20 INFO - ++DOMWINDOW == 26 (000000388602A000) [pid = 3436] [serial = 48] [outer = 00000038857E5000]
10:30:20 INFO - 1499535020886 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:30:20 INFO - 1499535020895 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:30:20 INFO - 1499535020916 Marionette TRACE 376 <- [1,2,null,{}]
10:30:20 INFO - 1499535020928 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:30:20 INFO - 1499535020939 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da16cd55-0155-480f-b2fa-791cf083e86c","ELEMENT":"da16cd55-0155-480f-b2fa-791cf083e86c"}}]
10:30:20 INFO - 1499535020947 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"da16cd55-0155-480f-b2fa-791cf083e86c"}]
10:30:21 INFO - 1499535021557 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:30:21 INFO - 1499535021562 Marionette TRACE 376 <- [1,4,null,{}]
10:30:21 INFO - 1499535021566 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
10:30:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499535021578 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:30:21 INFO - 1499535021590 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:30:21 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:21 INFO - 1499535021597 Marionette TRACE 376 <- [1,6,null,{}]
10:30:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1454ms
10:30:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:30:21 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:21 INFO - 1499535021604 Marionette DEBUG Closed connection 376
10:30:21 INFO - 1499535021606 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49801
10:30:21 INFO - 1499535021609 Marionette DEBUG Closed connection 377
10:30:21 INFO - 1499535021611 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49802
10:30:21 INFO - 1499535021614 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:21 INFO - 1499535021618 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:21 INFO - 1499535021664 Marionette DEBUG Register listener.js for window 12
10:30:21 INFO - 1499535021681 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cfa58f14-0e31-40cf-988c-c6975c6e6ac6","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:21 INFO - 1499535021722 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:30:21 INFO - 1499535021725 Marionette TRACE 378 <- [1,2,null,{}]
10:30:21 INFO - 1499535021729 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:21 INFO - 1499535021731 Marionette TRACE 378 <- [1,3,null,{}]
10:30:21 INFO - 1499535021733 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:21 INFO - 1499535021735 Marionette TRACE 378 <- [1,4,null,{}]
10:30:21 INFO - 1499535021738 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
10:30:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499535021749 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:30:21 INFO - 1499535021775 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:30:21 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:21 INFO - 1499535021780 Marionette TRACE 378 <- [1,6,null,{}]
10:30:21 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:21 INFO - 1499535021804 Marionette DEBUG Closed connection 378
10:30:21 INFO - 1499535021806 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49803
10:30:21 INFO - 1499535021831 Marionette DEBUG Closed connection 379
10:30:21 INFO - 1499535021833 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49804
10:30:21 INFO - 1499535021839 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:21 INFO - 1499535021842 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:21 INFO - 1499535021889 Marionette DEBUG Register listener.js for window 12
10:30:21 INFO - 1499535021908 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f6cd8d2e-7b36-4f3c-9c9c-262c8404050f","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:22 INFO - 1499535022090 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:30:22 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:22 INFO - 1499535022100 Marionette TRACE 380 <- [1,2,null,{}]
10:30:22 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:22 INFO - 1499535022127 Marionette DEBUG Closed connection 380
10:30:22 INFO - 1499535022130 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49805
10:30:22 INFO - 1499535022135 Marionette DEBUG Closed connection 381
10:30:22 INFO - 1499535022138 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49806
10:30:22 INFO - 1499535022194 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:22 INFO - 1499535022204 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:22 INFO - 1499535022229 Marionette DEBUG Register listener.js for window 12
10:30:22 INFO - 1499535022248 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9940c236-b4d8-4662-9c0e-a89521f40501","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:22 INFO - 1499535022268 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:30:22 INFO - 1499535022275 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:30:22 INFO - 1499535022285 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:30:22 INFO - 1499535022286 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:30:22 INFO - ++DOMWINDOW == 27 (000000388602F000) [pid = 3436] [serial = 49] [outer = 00000038857E5000]
10:30:22 INFO - 1499535022339 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:22 INFO - 1499535022353 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:22 INFO - 1499535022378 Marionette TRACE 382 <- [1,2,null,{}]
10:30:22 INFO - 1499535022391 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:30:22 INFO - 1499535022404 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2852bc4b-72b4-4d64-be81-04b567c77796","ELEMENT":"2852bc4b-72b4-4d64-be81-04b567c77796"}}]
10:30:22 INFO - 1499535022407 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2852bc4b-72b4-4d64-be81-04b567c77796"}]
10:30:22 INFO - --DOMWINDOW == 26 (00000038FA361800) [pid = 3436] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:30:22 INFO - --DOMWINDOW == 25 (00000038FE8A3000) [pid = 3436] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
10:30:22 INFO - --DOMWINDOW == 24 (000000388945B800) [pid = 3436] [serial = 38] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
10:30:22 INFO - --DOMWINDOW == 23 (00000038875E6000) [pid = 3436] [serial = 37] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
10:30:22 INFO - --DOMWINDOW == 22 (0000003884F88800) [pid = 3436] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
10:30:22 INFO - 1499535022867 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:30:22 INFO - 1499535022870 Marionette TRACE 382 <- [1,4,null,{}]
10:30:22 INFO - 1499535022873 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
10:30:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499535022883 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:30:22 INFO - 1499535022886 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:30:22 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:22 INFO - 1499535022891 Marionette TRACE 382 <- [1,6,null,{}]
10:30:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1294ms
10:30:22 INFO - TEST-START | test_click.py TestClick.test_input_file
10:30:22 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:22 INFO - 1499535022899 Marionette DEBUG Closed connection 382
10:30:22 INFO - 1499535022902 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49807
10:30:22 INFO - 1499535022904 Marionette DEBUG Closed connection 383
10:30:22 INFO - 1499535022907 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49808
10:30:22 INFO - 1499535022911 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:22 INFO - 1499535022914 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:22 INFO - 1499535022937 Marionette DEBUG Register listener.js for window 12
10:30:22 INFO - 1499535022952 Marionette TRACE 384 <- [1,1,null,{"sessionId":"cf5018f8-133b-40bc-ae6b-1a63c55f5b32","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:22 INFO - 1499535022962 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:30:22 INFO - 1499535022964 Marionette TRACE 384 <- [1,2,null,{}]
10:30:22 INFO - 1499535022966 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:22 INFO - 1499535022968 Marionette TRACE 384 <- [1,3,null,{}]
10:30:22 INFO - 1499535022975 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:22 INFO - 1499535022981 Marionette TRACE 384 <- [1,4,null,{}]
10:30:22 INFO - 1499535022983 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
10:30:22 INFO - TEST-START: test_click.py TestClick.test_input_file1499535022993 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:30:22 INFO - 1499535022995 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023001 Marionette TRACE 384 <- [1,6,null,{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023007 Marionette DEBUG Closed connection 384
10:30:23 INFO - 1499535023009 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49809
10:30:23 INFO - 1499535023012 Marionette DEBUG Closed connection 385
10:30:23 INFO - 1499535023014 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49810
10:30:23 INFO - 1499535023016 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:23 INFO - 1499535023019 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:23 INFO - 1499535023042 Marionette DEBUG Register listener.js for window 12
10:30:23 INFO - 1499535023057 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6de61f5e-4c0a-4124-8447-43400b7d8aea","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:23 INFO - 1499535023065 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023069 Marionette TRACE 386 <- [1,2,null,{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023075 Marionette DEBUG Closed connection 386
10:30:23 INFO - 1499535023077 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49811
10:30:23 INFO - 1499535023079 Marionette DEBUG Closed connection 387
10:30:23 INFO - 1499535023081 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49812
10:30:23 INFO - 1499535023084 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:23 INFO - 1499535023087 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:23 INFO - 1499535023112 Marionette DEBUG Register listener.js for window 12
10:30:23 INFO - 1499535023125 Marionette TRACE 388 <- [1,1,null,{"sessionId":"34cd78cd-60eb-4e72-8ebe-71c8824b381a","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:23 INFO - 1499535023133 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:30:23 INFO - 1499535023138 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:23 INFO - 1499535023147 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:23 INFO - 1499535023149 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:30:23 INFO - ++DOMWINDOW == 23 (0000003886CA5800) [pid = 3436] [serial = 50] [outer = 00000038857E5000]
10:30:23 INFO - 1499535023206 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:30:23 INFO - 1499535023222 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:30:23 INFO - 1499535023233 Marionette TRACE 388 <- [1,2,null,{}]
10:30:23 INFO - 1499535023245 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:30:23 INFO - 1499535023254 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3638c66c-59ff-419e-9a00-0b76730e63b4","ELEMENT":"3638c66c-59ff-419e-9a00-0b76730e63b4"}}]
10:30:23 INFO - 1499535023256 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3638c66c-59ff-419e-9a00-0b76730e63b4"}]
10:30:23 INFO - 1499535023430 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:30:23 INFO - 1499535023436 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
10:30:23 INFO - TEST-END: test_click.py TestClick.test_input_file1499535023445 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:30:23 INFO - 1499535023447 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023451 Marionette TRACE 388 <- [1,6,null,{}]
10:30:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 559ms
10:30:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023457 Marionette DEBUG Closed connection 388
10:30:23 INFO - 1499535023459 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49813
10:30:23 INFO - 1499535023460 Marionette DEBUG Closed connection 389
10:30:23 INFO - 1499535023462 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49814
10:30:23 INFO - 1499535023464 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:23 INFO - 1499535023469 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:23 INFO - 1499535023492 Marionette DEBUG Register listener.js for window 12
10:30:23 INFO - 1499535023506 Marionette TRACE 390 <- [1,1,null,{"sessionId":"2f9c2e05-c71c-4753-a838-99a399fa3f02","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:23 INFO - 1499535023513 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:30:23 INFO - 1499535023515 Marionette TRACE 390 <- [1,2,null,{}]
10:30:23 INFO - 1499535023517 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:23 INFO - 1499535023518 Marionette TRACE 390 <- [1,3,null,{}]
10:30:23 INFO - 1499535023520 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:23 INFO - 1499535023522 Marionette TRACE 390 <- [1,4,null,{}]
10:30:23 INFO - 1499535023524 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
10:30:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499535023533 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:30:23 INFO - 1499535023535 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023540 Marionette TRACE 390 <- [1,6,null,{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023548 Marionette DEBUG Closed connection 390
10:30:23 INFO - 1499535023553 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49815
10:30:23 INFO - 1499535023555 Marionette DEBUG Closed connection 391
10:30:23 INFO - 1499535023557 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49816
10:30:23 INFO - 1499535023559 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:23 INFO - 1499535023562 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:23 INFO - 1499535023584 Marionette DEBUG Register listener.js for window 12
10:30:23 INFO - 1499535023598 Marionette TRACE 392 <- [1,1,null,{"sessionId":"b71a5c68-e11a-4002-8708-632212071540","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:23 INFO - 1499535023604 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023608 Marionette TRACE 392 <- [1,2,null,{}]
10:30:23 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:23 INFO - 1499535023614 Marionette DEBUG Closed connection 392
10:30:23 INFO - 1499535023616 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49817
10:30:23 INFO - 1499535023617 Marionette DEBUG Closed connection 393
10:30:23 INFO - 1499535023619 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49818
10:30:23 INFO - 1499535023621 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:23 INFO - 1499535023624 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:23 INFO - 1499535023646 Marionette DEBUG Register listener.js for window 12
10:30:23 INFO - 1499535023657 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9c7f999a-a657-430c-a11e-aa39bcadab52","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:23 INFO - 1499535023664 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
10:30:23 INFO - 1499535023670 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:30:23 INFO - 1499535023677 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:30:23 INFO - 1499535023678 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:30:23 INFO - ++DOMWINDOW == 24 (000000388809A800) [pid = 3436] [serial = 51] [outer = 00000038857E5000]
10:30:23 INFO - 1499535023737 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:30:23 INFO - 1499535023745 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:30:23 INFO - 1499535023762 Marionette TRACE 394 <- [1,2,null,{}]
10:30:23 INFO - 1499535023765 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:30:23 INFO - 1499535023773 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"139c6b54-c122-4ff3-bb57-6809a64e2407","ELEMENT":"139c6b54-c122-4ff3-bb57-6809a64e2407"}}]
10:30:23 INFO - 1499535023775 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:30:23 INFO - 1499535023783 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb1945b2-997d-4967-888b-80cbe5432110","ELEMENT":"fb1945b2-997d-4967-888b-80cbe5432110"}}]
10:30:23 INFO - 1499535023785 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"139c6b54-c122-4ff3-bb57-6809a64e2407"}]
10:30:24 INFO - 1499535024226 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:30:24 INFO - 1499535024227 Marionette TRACE 394 <- [1,5,null,{}]
10:30:24 INFO - 1499535024229 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"fb1945b2-997d-4967-888b-80cbe5432110"}]
10:30:24 INFO - 1499535024362 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:30:24 INFO - 1499535024365 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:30:24 INFO - 1499535024373 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:30:24 INFO - 1499535024375 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
10:30:24 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499535024384 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:30:24 INFO - 1499535024386 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024390 Marionette TRACE 394 <- [1,9,null,{}]
10:30:24 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 938ms
10:30:24 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024396 Marionette DEBUG Closed connection 394
10:30:24 INFO - 1499535024397 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49819
10:30:24 INFO - 1499535024399 Marionette DEBUG Closed connection 395
10:30:24 INFO - 1499535024401 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49820
10:30:24 INFO - 1499535024404 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:24 INFO - 1499535024407 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:24 INFO - 1499535024430 Marionette DEBUG Register listener.js for window 12
10:30:24 INFO - 1499535024442 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fa6111e4-664d-410b-9915-8c9d87ccb9ee","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:24 INFO - 1499535024449 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:30:24 INFO - 1499535024450 Marionette TRACE 396 <- [1,2,null,{}]
10:30:24 INFO - 1499535024452 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:30:24 INFO - 1499535024454 Marionette TRACE 396 <- [1,3,null,{}]
10:30:24 INFO - 1499535024456 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:30:24 INFO - 1499535024458 Marionette TRACE 396 <- [1,4,null,{}]
10:30:24 INFO - 1499535024461 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
10:30:24 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499535024469 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:30:24 INFO - 1499535024470 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024475 Marionette TRACE 396 <- [1,6,null,{}]
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024481 Marionette DEBUG Closed connection 396
10:30:24 INFO - 1499535024482 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49821
10:30:24 INFO - 1499535024484 Marionette DEBUG Closed connection 397
10:30:24 INFO - 1499535024485 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49822
10:30:24 INFO - 1499535024487 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:30:24 INFO - 1499535024490 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:24 INFO - 1499535024513 Marionette DEBUG Register listener.js for window 12
10:30:24 INFO - 1499535024524 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4c287a7d-b335-4ce8-aa38-1464933f750b","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:24 INFO - 1499535024530 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024534 Marionette TRACE 398 <- [1,2,null,{}]
10:30:24 INFO - [3436] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
10:30:24 INFO - 1499535024540 Marionette DEBUG Closed connection 398
10:30:24 INFO - 1499535024542 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49823
10:30:24 INFO - 1499535024543 Marionette DEBUG Closed connection 399
10:30:24 INFO - 1499535024545 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49824
10:30:24 INFO - 1499535024547 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:30:24 INFO - 1499535024550 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}
10:30:24 INFO - 1499535024573 Marionette DEBUG Register listener.js for window 12
10:30:24 INFO - 1499535024589 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b53c7a63-68f4-4d92-a881-bb0debef2058","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-256.001\\AppData\\Local\\Temp\\tmpic_og2.mozrunner","moz:accessibilityChecks":false}}]
10:30:24 INFO - 1499535024607 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
10:30:24 INFO - 1499535024611 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:30:24 INFO - 1499535024627 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:30:24 INFO - 1499535024627 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:30:24 INFO - ++DOMWINDOW == 25 (00000038F86CE000) [pid = 3436] [serial = 52] [outer = 00000038857E5000]
10:30:24 INFO - 1499535024705 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:30:24 INFO - 1499535024712 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:30:24 INFO - 1499535024735 Marionette TRACE 400 <- [1,2,null,{}]
10:30:24 INFO - 1499535024748 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:30:24 INFO - 1499535024757 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2139f313-2d8e-49de-ba65-379cc6aa9f3f","ELEMENT":"2139f313-2d8e-49de-ba65-379cc6aa9f3f"}}]
10:30:24 INFO - 1499535024763 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2139f313-2d8e-49de-ba65-379cc6aa9f3f"}]
10:30:24 INFO - 1499535024898 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:30:24 INFO - 1499535024902 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2139f313-2d8e-49de-ba65-379cc6aa9f3f"}]
10:30:25 INFO - 1499535025037 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element