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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
13:55:45 INFO - --DOMWINDOW == 27 (00000007668E9800) [pid = 1828] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:45 INFO - --DOMWINDOW == 26 (0000000761741000) [pid = 1828] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:45 INFO - --DOMWINDOW == 25 (0000000760864000) [pid = 1828] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:45 INFO - --DOMWINDOW == 24 (000000075E2A4000) [pid = 1828] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:45 INFO - --DOMWINDOW == 23 (000000076749A800) [pid = 1828] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:45 INFO - 1499288145800 Marionette TRACE 353 -> [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}]
13:55:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499288145811 Marionette TRACE 353 <- [1,5,null,{"value":null}]
13:55:45 INFO - 1499288145813 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
13:55:45 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:45 INFO - 1499288145818 Marionette TRACE 353 <- [1,6,null,{}]
13:55:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 775ms
13:55:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:55:45 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:45 INFO - 1499288145824 Marionette DEBUG Closed connection 353
13:55:45 INFO - 1499288145826 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49785
13:55:45 INFO - 1499288145828 Marionette DEBUG Closed connection 354
13:55:45 INFO - 1499288145830 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49786
13:55:45 INFO - 1499288145832 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:45 INFO - 1499288145835 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:45 INFO - 1499288145857 Marionette DEBUG Register listener.js for window 12
13:55:45 INFO - 1499288145873 Marionette TRACE 355 <- [1,1,null,{"sessionId":"130ec261-04f1-4e14-9a59-76d5fab43d4a","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:45 INFO - 1499288145978 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
13:55:45 INFO - 1499288145980 Marionette TRACE 355 <- [1,2,null,{}]
13:55:46 INFO - 1499288146007 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:46 INFO - 1499288146009 Marionette TRACE 355 <- [1,3,null,{}]
13:55:46 INFO - 1499288146012 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:46 INFO - 1499288146014 Marionette TRACE 355 <- [1,4,null,{}]
13:55:46 INFO - 1499288146017 Marionette TRACE 355 -> [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}]
13:55:46 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499288146027 Marionette TRACE 355 <- [1,5,null,{"value":null}]
13:55:46 INFO - 1499288146031 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
13:55:46 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:46 INFO - 1499288146035 Marionette TRACE 355 <- [1,6,null,{}]
13:55:46 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:46 INFO - 1499288146059 Marionette DEBUG Closed connection 355
13:55:46 INFO - 1499288146061 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49787
13:55:46 INFO - 1499288146066 Marionette DEBUG Closed connection 356
13:55:46 INFO - 1499288146068 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49788
13:55:46 INFO - 1499288146070 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:46 INFO - 1499288146073 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:46 INFO - 1499288146095 Marionette DEBUG Register listener.js for window 12
13:55:46 INFO - 1499288146111 Marionette TRACE 357 <- [1,1,null,{"sessionId":"cbde4b4c-25f0-45a2-aee6-dfb0cf594050","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:46 INFO - 1499288146123 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
13:55:46 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:46 INFO - 1499288146128 Marionette TRACE 357 <- [1,2,null,{}]
13:55:46 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:46 INFO - 1499288146153 Marionette DEBUG Closed connection 357
13:55:46 INFO - 1499288146158 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49789
13:55:46 INFO - 1499288146161 Marionette DEBUG Closed connection 358
13:55:46 INFO - 1499288146162 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49790
13:55:46 INFO - 1499288146165 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:46 INFO - 1499288146171 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:46 INFO - 1499288146193 Marionette DEBUG Register listener.js for window 12
13:55:46 INFO - 1499288146209 Marionette TRACE 359 <- [1,1,null,{"sessionId":"d1bf1cdc-b6a8-4bb6-867d-395acfd4cc1c","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:47 INFO - 1499288147005 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
13:55:47 INFO - 1499288147013 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
13:55:47 INFO - 1499288147033 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
13:55:47 INFO - 1499288147034 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
13:55:47 INFO - ++DOMWINDOW == 24 (0000000760AC1800) [pid = 1828] [serial = 44] [outer = 0000000761F32800]
13:55:47 INFO - 1499288147156 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
13:55:47 INFO - 1499288147162 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
13:55:47 INFO - 1499288147181 Marionette TRACE 359 <- [1,2,null,{}]
13:55:47 INFO - 1499288147234 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:55:47 INFO - 1499288147242 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"709b91f2-956d-43b6-94e0-12bc8ec66479","ELEMENT":"709b91f2-956d-43b6-94e0-12bc8ec66479"}}]
13:55:47 INFO - 1499288147295 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"709b91f2-956d-43b6-94e0-12bc8ec66479"}]
13:55:47 INFO - 1499288147583 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
13:55:47 INFO - [1828] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
13:55:47 INFO - 1499288147594 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
13:55:47 INFO - 1499288147595 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
13:55:47 INFO - ++DOMWINDOW == 25 (00000007605A0800) [pid = 1828] [serial = 45] [outer = 0000000761F32800]
13:55:47 INFO - 1499288147700 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
13:55:47 INFO - 1499288147705 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
13:55:47 INFO - 1499288147715 Marionette TRACE 359 <- [1,4,null,{}]
13:55:47 INFO - 1499288147725 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:55:47 INFO - 1499288147734 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8e859e5d-7b7e-4931-8174-0a98c205d074","ELEMENT":"8e859e5d-7b7e-4931-8174-0a98c205d074"}}]
13:55:47 INFO - 1499288147736 Marionette TRACE 359 -> [0,6,"getTitle",{}]
13:55:47 INFO - 1499288147738 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
13:55:47 INFO - 1499288147740 Marionette TRACE 359 -> [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}]
13:55:47 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499288147748 Marionette TRACE 359 <- [1,7,null,{"value":null}]
13:55:47 INFO - 1499288147750 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147754 Marionette TRACE 359 <- [1,8,null,{}]
13:55:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1968ms
13:55:47 INFO - TEST-START | test_click.py TestClick.test_container_element
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147797 Marionette DEBUG Closed connection 359
13:55:47 INFO - 1499288147798 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49793
13:55:47 INFO - 1499288147801 Marionette DEBUG Closed connection 360
13:55:47 INFO - 1499288147802 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49794
13:55:47 INFO - 1499288147804 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:47 INFO - 1499288147807 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:47 INFO - 1499288147829 Marionette DEBUG Register listener.js for window 12
13:55:47 INFO - 1499288147841 Marionette TRACE 361 <- [1,1,null,{"sessionId":"198927f8-1b8a-422b-926b-c9b653e6f1b7","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:47 INFO - 1499288147851 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
13:55:47 INFO - 1499288147853 Marionette TRACE 361 <- [1,2,null,{}]
13:55:47 INFO - 1499288147856 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:47 INFO - 1499288147857 Marionette TRACE 361 <- [1,3,null,{}]
13:55:47 INFO - 1499288147859 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:47 INFO - 1499288147861 Marionette TRACE 361 <- [1,4,null,{}]
13:55:47 INFO - 1499288147864 Marionette TRACE 361 -> [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}]
13:55:47 INFO - TEST-START: test_click.py TestClick.test_container_element1499288147873 Marionette TRACE 361 <- [1,5,null,{"value":null}]
13:55:47 INFO - 1499288147875 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147879 Marionette TRACE 361 <- [1,6,null,{}]
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147904 Marionette DEBUG Closed connection 361
13:55:47 INFO - 1499288147905 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49795
13:55:47 INFO - 1499288147909 Marionette DEBUG Closed connection 362
13:55:47 INFO - 1499288147910 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49796
13:55:47 INFO - 1499288147912 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:47 INFO - 1499288147915 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:47 INFO - 1499288147936 Marionette DEBUG Register listener.js for window 12
13:55:47 INFO - 1499288147949 Marionette TRACE 363 <- [1,1,null,{"sessionId":"011b1360-5c42-45c9-a666-487bec82c6ff","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:47 INFO - 1499288147956 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147960 Marionette TRACE 363 <- [1,2,null,{}]
13:55:47 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:47 INFO - 1499288147982 Marionette DEBUG Closed connection 363
13:55:47 INFO - 1499288147983 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49797
13:55:47 INFO - 1499288147989 Marionette DEBUG Closed connection 364
13:55:47 INFO - 1499288147990 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49798
13:55:47 INFO - 1499288147993 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:47 INFO - 1499288147996 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:48 INFO - 1499288148017 Marionette DEBUG Register listener.js for window 12
13:55:48 INFO - 1499288148042 Marionette TRACE 365 <- [1,1,null,{"sessionId":"be13a546-5151-4b83-910c-0d00a1f07540","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:48 INFO - 1499288148051 Marionette TRACE 365 -> [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"}]
13:55:48 INFO - 1499288148055 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
13:55:48 INFO - 1499288148062 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
13:55:48 INFO - 1499288148063 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
13:55:48 INFO - ++DOMWINDOW == 26 (0000000761853800) [pid = 1828] [serial = 46] [outer = 0000000761F32800]
13:55:48 INFO - 1499288148121 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"
13:55:48 INFO - 1499288148131 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"
13:55:48 INFO - 1499288148150 Marionette TRACE 365 <- [1,2,null,{}]
13:55:48 INFO - 1499288148157 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:55:48 INFO - 1499288148166 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"49ce36d5-fd40-445a-9ed0-9d3b1c3d4721","ELEMENT":"49ce36d5-fd40-445a-9ed0-9d3b1c3d4721"}}]
13:55:48 INFO - 1499288148170 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"49ce36d5-fd40-445a-9ed0-9d3b1c3d4721"}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:55:48 INFO - 1499288148608 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:55:48 INFO - 1499288148610 Marionette TRACE 365 <- [1,4,null,{}]
13:55:48 INFO - 1499288148612 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"49ce36d5-fd40-445a-9ed0-9d3b1c3d4721","name":"selected"}]
13:55:48 INFO - 1499288148640 Marionette TRACE 365 <- [1,5,null,{"value":true}]
13:55:48 INFO - 1499288148649 Marionette TRACE 365 -> [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}]
13:55:48 INFO - TEST-END: test_click.py TestClick.test_container_element1499288148657 Marionette TRACE 365 <- [1,6,null,{"value":null}]
13:55:48 INFO - 1499288148660 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148664 Marionette TRACE 365 <- [1,7,null,{}]
13:55:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 877ms
13:55:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148687 Marionette DEBUG Closed connection 365
13:55:48 INFO - 1499288148688 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49799
13:55:48 INFO - 1499288148692 Marionette DEBUG Closed connection 366
13:55:48 INFO - 1499288148693 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49800
13:55:48 INFO - 1499288148696 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:48 INFO - 1499288148698 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:48 INFO - 1499288148720 Marionette DEBUG Register listener.js for window 12
13:55:48 INFO - 1499288148732 Marionette TRACE 367 <- [1,1,null,{"sessionId":"825c6880-e75d-41fd-98bb-95c8a0c597dc","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:48 INFO - 1499288148737 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
13:55:48 INFO - 1499288148739 Marionette TRACE 367 <- [1,2,null,{}]
13:55:48 INFO - 1499288148740 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:48 INFO - 1499288148742 Marionette TRACE 367 <- [1,3,null,{}]
13:55:48 INFO - 1499288148750 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:48 INFO - 1499288148751 Marionette TRACE 367 <- [1,4,null,{}]
13:55:48 INFO - 1499288148754 Marionette TRACE 367 -> [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}]
13:55:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499288148762 Marionette TRACE 367 <- [1,5,null,{"value":null}]
13:55:48 INFO - 1499288148764 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148768 Marionette TRACE 367 <- [1,6,null,{}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148775 Marionette DEBUG Closed connection 367
13:55:48 INFO - 1499288148776 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49801
13:55:48 INFO - 1499288148778 Marionette DEBUG Closed connection 368
13:55:48 INFO - 1499288148779 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49802
13:55:48 INFO - 1499288148781 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:48 INFO - 1499288148785 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:48 INFO - 1499288148811 Marionette DEBUG Register listener.js for window 12
13:55:48 INFO - 1499288148826 Marionette TRACE 369 <- [1,1,null,{"sessionId":"babbec72-727d-4528-b605-ec8350c475c4","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:48 INFO - 1499288148829 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148833 Marionette TRACE 369 <- [1,2,null,{}]
13:55:48 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:48 INFO - 1499288148839 Marionette DEBUG Closed connection 369
13:55:48 INFO - 1499288148840 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49803
13:55:48 INFO - 1499288148842 Marionette DEBUG Closed connection 370
13:55:48 INFO - 1499288148843 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49804
13:55:48 INFO - 1499288148846 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:48 INFO - 1499288148848 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:48 INFO - 1499288148872 Marionette DEBUG Register listener.js for window 12
13:55:48 INFO - 1499288148885 Marionette TRACE 371 <- [1,1,null,{"sessionId":"5a9798c0-b049-4fd6-8f17-a72cf7a837b5","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:48 INFO - 1499288148888 Marionette TRACE 371 -> [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"}]
13:55:48 INFO - 1499288148892 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"
13:55:48 INFO - 1499288148900 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"
13:55:48 INFO - 1499288148901 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"
13:55:48 INFO - ++DOMWINDOW == 27 (0000000769B77800) [pid = 1828] [serial = 47] [outer = 0000000761F32800]
13:55:48 INFO - 1499288148960 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"
13:55:48 INFO - 1499288148965 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"
13:55:48 INFO - 1499288148983 Marionette TRACE 371 <- [1,2,null,{}]
13:55:48 INFO - 1499288148986 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:55:49 INFO - 1499288148993 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"476bb2b1-1176-4e49-9ef0-2a53922cc4c6","ELEMENT":"476bb2b1-1176-4e49-9ef0-2a53922cc4c6"}}]
13:55:49 INFO - 1499288148996 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"476bb2b1-1176-4e49-9ef0-2a53922cc4c6"}]
13:55:49 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:55:49 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:55:49 INFO - [1828] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:55:50 INFO - 1499288150010 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:55:50 INFO - 1499288150013 Marionette TRACE 371 <- [1,4,null,{}]
13:55:50 INFO - 1499288150050 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"476bb2b1-1176-4e49-9ef0-2a53922cc4c6","name":"selected"}]
13:55:50 INFO - 1499288150078 Marionette TRACE 371 <- [1,5,null,{"value":true}]
13:55:50 INFO - 1499288150080 Marionette TRACE 371 -> [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}]
13:55:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499288150088 Marionette TRACE 371 <- [1,6,null,{"value":null}]
13:55:50 INFO - 1499288150090 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150094 Marionette TRACE 371 <- [1,7,null,{}]
13:55:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1429ms
13:55:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150100 Marionette DEBUG Closed connection 371
13:55:50 INFO - 1499288150101 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49805
13:55:50 INFO - 1499288150103 Marionette DEBUG Closed connection 372
13:55:50 INFO - 1499288150104 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49806
13:55:50 INFO - 1499288150106 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:50 INFO - 1499288150109 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:50 INFO - 1499288150130 Marionette DEBUG Register listener.js for window 12
13:55:50 INFO - 1499288150184 Marionette TRACE 373 <- [1,1,null,{"sessionId":"bdf2d88a-f8cd-4958-b093-afe800873ce5","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:50 INFO - 1499288150236 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
13:55:50 INFO - 1499288150238 Marionette TRACE 373 <- [1,2,null,{}]
13:55:50 INFO - 1499288150266 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:50 INFO - 1499288150268 Marionette TRACE 373 <- [1,3,null,{}]
13:55:50 INFO - 1499288150270 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:50 INFO - 1499288150272 Marionette TRACE 373 <- [1,4,null,{}]
13:55:50 INFO - 1499288150274 Marionette TRACE 373 -> [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}]
13:55:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499288150282 Marionette TRACE 373 <- [1,5,null,{"value":null}]
13:55:50 INFO - 1499288150294 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150299 Marionette TRACE 373 <- [1,6,null,{}]
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150304 Marionette DEBUG Closed connection 373
13:55:50 INFO - 1499288150306 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49807
13:55:50 INFO - 1499288150308 Marionette DEBUG Closed connection 374
13:55:50 INFO - 1499288150309 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49808
13:55:50 INFO - 1499288150311 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:50 INFO - 1499288150313 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:50 INFO - 1499288150336 Marionette DEBUG Register listener.js for window 12
13:55:50 INFO - 1499288150349 Marionette TRACE 375 <- [1,1,null,{"sessionId":"7f736f4e-19d2-4896-8121-31152d55227e","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:50 INFO - 1499288150367 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150377 Marionette TRACE 375 <- [1,2,null,{}]
13:55:50 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:50 INFO - 1499288150426 Marionette DEBUG Closed connection 375
13:55:50 INFO - 1499288150429 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49809
13:55:50 INFO - 1499288150435 Marionette DEBUG Closed connection 376
13:55:50 INFO - 1499288150438 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49810
13:55:50 INFO - 1499288150492 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:50 INFO - 1499288150503 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:50 INFO - 1499288150546 Marionette DEBUG Register listener.js for window 12
13:55:50 INFO - 1499288150569 Marionette TRACE 377 <- [1,1,null,{"sessionId":"092e3aa5-9f51-4526-b84f-02b6bd704105","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:50 INFO - 1499288150580 Marionette TRACE 377 -> [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"}]
13:55:50 INFO - 1499288150588 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"
13:55:50 INFO - 1499288150599 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"
13:55:50 INFO - 1499288150600 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"
13:55:50 INFO - ++DOMWINDOW == 28 (000000075BB33800) [pid = 1828] [serial = 48] [outer = 0000000761F32800]
13:55:50 INFO - 1499288150662 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"
13:55:50 INFO - 1499288150672 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"
13:55:50 INFO - 1499288150704 Marionette TRACE 377 <- [1,2,null,{}]
13:55:50 INFO - 1499288150716 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:55:50 INFO - 1499288150730 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c3dfd2a3-d553-42af-b52f-dd25eb3d4799","ELEMENT":"c3dfd2a3-d553-42af-b52f-dd25eb3d4799"}}]
13:55:50 INFO - 1499288150734 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"c3dfd2a3-d553-42af-b52f-dd25eb3d4799"}]
13:55:51 INFO - 1499288151338 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:55:51 INFO - 1499288151341 Marionette TRACE 377 <- [1,4,null,{}]
13:55:51 INFO - 1499288151347 Marionette TRACE 377 -> [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}]
13:55:51 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499288151359 Marionette TRACE 377 <- [1,5,null,{"value":null}]
13:55:51 INFO - 1499288151376 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151383 Marionette TRACE 377 <- [1,6,null,{}]
13:55:51 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1290ms
13:55:51 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151391 Marionette DEBUG Closed connection 377
13:55:51 INFO - 1499288151395 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49811
13:55:51 INFO - 1499288151397 Marionette DEBUG Closed connection 378
13:55:51 INFO - 1499288151399 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49812
13:55:51 INFO - 1499288151404 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:51 INFO - 1499288151408 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:51 INFO - 1499288151451 Marionette DEBUG Register listener.js for window 12
13:55:51 INFO - 1499288151473 Marionette TRACE 379 <- [1,1,null,{"sessionId":"520a0edf-eff8-4c09-9025-859516e8b752","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:51 INFO - 1499288151513 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
13:55:51 INFO - 1499288151515 Marionette TRACE 379 <- [1,2,null,{}]
13:55:51 INFO - 1499288151519 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:51 INFO - 1499288151521 Marionette TRACE 379 <- [1,3,null,{}]
13:55:51 INFO - 1499288151529 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:51 INFO - 1499288151535 Marionette TRACE 379 <- [1,4,null,{}]
13:55:51 INFO - 1499288151586 Marionette TRACE 379 -> [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}]
13:55:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499288151618 Marionette TRACE 379 <- [1,5,null,{"value":null}]
13:55:51 INFO - 1499288151625 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151631 Marionette TRACE 379 <- [1,6,null,{}]
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151677 Marionette DEBUG Closed connection 379
13:55:51 INFO - 1499288151680 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49813
13:55:51 INFO - 1499288151702 Marionette DEBUG Closed connection 380
13:55:51 INFO - 1499288151705 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49814
13:55:51 INFO - 1499288151708 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:51 INFO - 1499288151712 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:51 INFO - 1499288151755 Marionette DEBUG Register listener.js for window 12
13:55:51 INFO - 1499288151771 Marionette TRACE 381 <- [1,1,null,{"sessionId":"3203b1f1-218f-4005-999d-1788c4a7ea28","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:51 INFO - 1499288151945 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151955 Marionette TRACE 381 <- [1,2,null,{}]
13:55:51 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:51 INFO - 1499288151984 Marionette DEBUG Closed connection 381
13:55:51 INFO - 1499288151987 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49815
13:55:51 INFO - 1499288151992 Marionette DEBUG Closed connection 382
13:55:51 INFO - 1499288151995 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49816
13:55:52 INFO - 1499288152051 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:52 INFO - 1499288152061 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:52 INFO - 1499288152086 Marionette DEBUG Register listener.js for window 12
13:55:52 INFO - 1499288152107 Marionette TRACE 383 <- [1,1,null,{"sessionId":"e64c4413-d601-4b13-ad69-c28dcc61bc05","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:52 INFO - 1499288152120 Marionette TRACE 383 -> [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"}]
13:55:52 INFO - 1499288152127 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"
13:55:52 INFO - 1499288152137 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"
13:55:52 INFO - 1499288152138 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"
13:55:52 INFO - ++DOMWINDOW == 29 (000000075F2B9000) [pid = 1828] [serial = 49] [outer = 0000000761F32800]
13:55:52 INFO - 1499288152191 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"
13:55:52 INFO - 1499288152202 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"
13:55:52 INFO - 1499288152232 Marionette TRACE 383 <- [1,2,null,{}]
13:55:52 INFO - 1499288152243 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:55:52 INFO - 1499288152255 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"055969d7-1162-4bfa-b21d-f06cd13c71d5","ELEMENT":"055969d7-1162-4bfa-b21d-f06cd13c71d5"}}]
13:55:52 INFO - 1499288152259 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"055969d7-1162-4bfa-b21d-f06cd13c71d5"}]
13:55:52 INFO - --DOMWINDOW == 28 (0000000761E18800) [pid = 1828] [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]
13:55:52 INFO - --DOMWINDOW == 27 (0000000767493000) [pid = 1828] [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]
13:55:52 INFO - --DOMWINDOW == 26 (0000000769A1B000) [pid = 1828] [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]
13:55:52 INFO - --DOMWINDOW == 25 (0000000760857000) [pid = 1828] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:52 INFO - --DOMWINDOW == 24 (000000075F240000) [pid = 1828] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:52 INFO - --DOMWINDOW == 23 (000000075BC69000) [pid = 1828] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:52 INFO - --DOMWINDOW == 22 (0000000768EB4000) [pid = 1828] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:55:52 INFO - 1499288152757 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:55:52 INFO - 1499288152759 Marionette TRACE 383 <- [1,4,null,{}]
13:55:52 INFO - 1499288152764 Marionette TRACE 383 -> [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}]
13:55:52 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499288152774 Marionette TRACE 383 <- [1,5,null,{"value":null}]
13:55:52 INFO - 1499288152778 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
13:55:52 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:52 INFO - 1499288152783 Marionette TRACE 383 <- [1,6,null,{}]
13:55:52 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1399ms
13:55:52 INFO - TEST-START | test_click.py TestClick.test_input_file
13:55:52 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:52 INFO - 1499288152808 Marionette DEBUG Closed connection 383
13:55:52 INFO - 1499288152812 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49817
13:55:52 INFO - 1499288152815 Marionette DEBUG Closed connection 384
13:55:52 INFO - 1499288152817 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49818
13:55:52 INFO - 1499288152821 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:52 INFO - 1499288152825 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:52 INFO - 1499288152848 Marionette DEBUG Register listener.js for window 12
13:55:52 INFO - 1499288152868 Marionette TRACE 385 <- [1,1,null,{"sessionId":"b8785218-bfe7-4e47-99d4-850b0cfbdfed","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:52 INFO - 1499288152880 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
13:55:52 INFO - 1499288152882 Marionette TRACE 385 <- [1,2,null,{}]
13:55:52 INFO - 1499288152885 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:52 INFO - 1499288152888 Marionette TRACE 385 <- [1,3,null,{}]
13:55:52 INFO - 1499288152894 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:52 INFO - 1499288152900 Marionette TRACE 385 <- [1,4,null,{}]
13:55:52 INFO - 1499288152903 Marionette TRACE 385 -> [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}]
13:55:52 INFO - TEST-START: test_click.py TestClick.test_input_file1499288152913 Marionette TRACE 385 <- [1,5,null,{"value":null}]
13:55:52 INFO - 1499288152915 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
13:55:52 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:52 INFO - 1499288152921 Marionette TRACE 385 <- [1,6,null,{}]
13:55:52 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:52 INFO - 1499288152927 Marionette DEBUG Closed connection 385
13:55:52 INFO - 1499288152929 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49819
13:55:52 INFO - 1499288152954 Marionette DEBUG Closed connection 386
13:55:52 INFO - 1499288152956 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49820
13:55:52 INFO - 1499288152958 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:52 INFO - 1499288152962 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:52 INFO - 1499288152984 Marionette DEBUG Register listener.js for window 12
13:55:53 INFO - 1499288152999 Marionette TRACE 387 <- [1,1,null,{"sessionId":"0bd9758b-ddb2-498f-b04a-03228f68b164","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:53 INFO - 1499288153748 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
13:55:53 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:53 INFO - 1499288153752 Marionette TRACE 387 <- [1,2,null,{}]
13:55:53 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:53 INFO - 1499288153808 Marionette DEBUG Closed connection 387
13:55:53 INFO - 1499288153815 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49821
13:55:53 INFO - 1499288153822 Marionette DEBUG Closed connection 388
13:55:53 INFO - 1499288153825 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49822
13:55:53 INFO - 1499288153877 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:53 INFO - 1499288153880 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:53 INFO - 1499288153904 Marionette DEBUG Register listener.js for window 12
13:55:53 INFO - 1499288153918 Marionette TRACE 389 <- [1,1,null,{"sessionId":"156f396d-8cbd-47f0-80b2-b023a209e2ad","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:53 INFO - 1499288153977 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:55:53 INFO - 1499288153981 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"
13:55:53 INFO - 1499288153992 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"
13:55:53 INFO - 1499288153994 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"
13:55:53 INFO - ++DOMWINDOW == 23 (000000075E6EB000) [pid = 1828] [serial = 50] [outer = 0000000761F32800]
13:55:54 INFO - 1499288154116 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:55:54 INFO - 1499288154121 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:55:54 INFO - 1499288154151 Marionette TRACE 389 <- [1,2,null,{}]
13:55:54 INFO - 1499288154156 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:55:54 INFO - 1499288154199 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"951d927a-a500-4821-bb44-28f48cf97c12","ELEMENT":"951d927a-a500-4821-bb44-28f48cf97c12"}}]
13:55:54 INFO - 1499288154215 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"951d927a-a500-4821-bb44-28f48cf97c12"}]
13:55:54 INFO - 1499288154373 Marionette TRACE 389 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
13:55:54 INFO - 1499288154389 Marionette TRACE 389 -> [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}]
13:55:54 INFO - TEST-END: test_click.py TestClick.test_input_file1499288154398 Marionette TRACE 389 <- [1,5,null,{"value":null}]
13:55:54 INFO - 1499288154402 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154406 Marionette TRACE 389 <- [1,6,null,{}]
13:55:54 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1623ms
13:55:54 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154429 Marionette DEBUG Closed connection 389
13:55:54 INFO - 1499288154431 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49823
13:55:54 INFO - 1499288154436 Marionette DEBUG Closed connection 390
13:55:54 INFO - 1499288154437 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49824
13:55:54 INFO - 1499288154440 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:54 INFO - 1499288154443 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:54 INFO - 1499288154466 Marionette DEBUG Register listener.js for window 12
13:55:54 INFO - 1499288154483 Marionette TRACE 391 <- [1,1,null,{"sessionId":"dae9803f-1338-4c97-8a05-b1dcc956a0a5","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:54 INFO - 1499288154490 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
13:55:54 INFO - 1499288154492 Marionette TRACE 391 <- [1,2,null,{}]
13:55:54 INFO - 1499288154494 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:54 INFO - 1499288154495 Marionette TRACE 391 <- [1,3,null,{}]
13:55:54 INFO - 1499288154497 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:54 INFO - 1499288154499 Marionette TRACE 391 <- [1,4,null,{}]
13:55:54 INFO - 1499288154501 Marionette TRACE 391 -> [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}]
13:55:54 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499288154510 Marionette TRACE 391 <- [1,5,null,{"value":null}]
13:55:54 INFO - 1499288154512 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154517 Marionette TRACE 391 <- [1,6,null,{}]
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154544 Marionette DEBUG Closed connection 391
13:55:54 INFO - 1499288154549 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49825
13:55:54 INFO - 1499288154553 Marionette DEBUG Closed connection 392
13:55:54 INFO - 1499288154555 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49826
13:55:54 INFO - 1499288154557 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:54 INFO - 1499288154562 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:54 INFO - 1499288154585 Marionette DEBUG Register listener.js for window 12
13:55:54 INFO - 1499288154599 Marionette TRACE 393 <- [1,1,null,{"sessionId":"4205684d-15d8-4a77-9140-38eab0c0b514","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:54 INFO - 1499288154609 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154613 Marionette TRACE 393 <- [1,2,null,{}]
13:55:54 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:54 INFO - 1499288154636 Marionette DEBUG Closed connection 393
13:55:54 INFO - 1499288154637 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49827
13:55:54 INFO - 1499288154639 Marionette DEBUG Closed connection 394
13:55:54 INFO - 1499288154640 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49828
13:55:54 INFO - 1499288154642 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:54 INFO - 1499288154645 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:54 INFO - 1499288154668 Marionette DEBUG Register listener.js for window 12
13:55:54 INFO - 1499288154680 Marionette TRACE 395 <- [1,1,null,{"sessionId":"a3ac1159-9de5-4891-8e6e-8e5e79e9f08a","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:54 INFO - 1499288154690 Marionette TRACE 395 -> [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"}]
13:55:54 INFO - 1499288154696 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:55:54 INFO - 1499288154703 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:55:54 INFO - 1499288154704 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:55:54 INFO - ++DOMWINDOW == 24 (000000075F2D6800) [pid = 1828] [serial = 51] [outer = 0000000761F32800]
13:55:54 INFO - 1499288154765 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"
13:55:54 INFO - 1499288154773 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"
13:55:54 INFO - 1499288154789 Marionette TRACE 395 <- [1,2,null,{}]
13:55:54 INFO - 1499288154792 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:55:54 INFO - 1499288154800 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"11cf60df-4452-4122-8041-be042e2468fc","ELEMENT":"11cf60df-4452-4122-8041-be042e2468fc"}}]
13:55:54 INFO - 1499288154802 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:55:54 INFO - 1499288154810 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7bfc2d3-f117-4ad9-8d14-e464dcfd859e","ELEMENT":"a7bfc2d3-f117-4ad9-8d14-e464dcfd859e"}}]
13:55:54 INFO - 1499288154812 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"11cf60df-4452-4122-8041-be042e2468fc"}]
13:55:55 INFO - 1499288155289 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:55:55 INFO - 1499288155290 Marionette TRACE 395 <- [1,5,null,{}]
13:55:55 INFO - 1499288155293 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"a7bfc2d3-f117-4ad9-8d14-e464dcfd859e"}]
13:55:55 INFO - 1499288155429 Marionette TRACE 395 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
13:55:55 INFO - 1499288155461 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:55:55 INFO - 1499288155469 Marionette TRACE 395 <- [1,7,null,{"value":false}]
13:55:55 INFO - 1499288155472 Marionette TRACE 395 -> [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}]
13:55:55 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499288155480 Marionette TRACE 395 <- [1,8,null,{"value":null}]
13:55:55 INFO - 1499288155482 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155487 Marionette TRACE 395 <- [1,9,null,{}]
13:55:55 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1079ms
13:55:55 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155493 Marionette DEBUG Closed connection 395
13:55:55 INFO - 1499288155494 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49829
13:55:55 INFO - 1499288155518 Marionette DEBUG Closed connection 396
13:55:55 INFO - 1499288155519 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49830
13:55:55 INFO - 1499288155521 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:55 INFO - 1499288155524 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:55 INFO - 1499288155547 Marionette DEBUG Register listener.js for window 12
13:55:55 INFO - 1499288155559 Marionette TRACE 397 <- [1,1,null,{"sessionId":"5527417e-25cd-4481-901c-5f12314d400f","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:55 INFO - 1499288155562 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:55:55 INFO - 1499288155563 Marionette TRACE 397 <- [1,2,null,{}]
13:55:55 INFO - 1499288155565 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:55:55 INFO - 1499288155567 Marionette TRACE 397 <- [1,3,null,{}]
13:55:55 INFO - 1499288155569 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:55:55 INFO - 1499288155570 Marionette TRACE 397 <- [1,4,null,{}]
13:55:55 INFO - 1499288155573 Marionette TRACE 397 -> [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}]
13:55:55 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499288155581 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:55:55 INFO - 1499288155583 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155587 Marionette TRACE 397 <- [1,6,null,{}]
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155593 Marionette DEBUG Closed connection 397
13:55:55 INFO - 1499288155595 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49831
13:55:55 INFO - 1499288155597 Marionette DEBUG Closed connection 398
13:55:55 INFO - 1499288155599 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49832
13:55:55 INFO - 1499288155601 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:55:55 INFO - 1499288155604 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:55 INFO - 1499288155627 Marionette DEBUG Register listener.js for window 12
13:55:55 INFO - 1499288155639 Marionette TRACE 399 <- [1,1,null,{"sessionId":"0c35533d-2b9b-4933-85be-3b1204c6c6d2","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:55 INFO - 1499288155642 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155646 Marionette TRACE 399 <- [1,2,null,{}]
13:55:55 INFO - [1828] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:55:55 INFO - 1499288155652 Marionette DEBUG Closed connection 399
13:55:55 INFO - 1499288155653 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49833
13:55:55 INFO - 1499288155655 Marionette DEBUG Closed connection 400
13:55:55 INFO - 1499288155656 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49834
13:55:55 INFO - 1499288155659 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:55:55 INFO - 1499288155661 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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}
13:55:55 INFO - 1499288155684 Marionette DEBUG Register listener.js for window 12
13:55:55 INFO - 1499288155696 Marionette TRACE 401 <- [1,1,null,{"sessionId":"cadaf8b5-bedc-4885-983e-4df5cca4b4a2","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":1828,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-093.001\\AppData\\Local\\Temp\\tmpxjdpr4.mozrunner","moz:accessibilityChecks":false}}]
13:55:55 INFO - 1499288155699 Marionette TRACE 401 -> [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"}]
13:55:55 INFO - 1499288155703 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"
13:55:55 INFO - 1499288155713 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"
13:55:55 INFO - 1499288155714 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"
13:55:55 INFO - ++DOMWINDOW == 25 (00000007668E4000) [pid = 1828] [serial = 52] [outer = 0000000761F32800]
13:55:55 INFO - 1499288155825 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"
13:55:55 INFO - 1499288155831 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"
13:55:55 INFO - 1499288155849 Marionette TRACE 401 <- [1,2,null,{}]
13:55:55 INFO - 1499288155857 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:55:55 INFO - 1499288155864 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7667a36-1990-4880-856c-a144e1de85cd","ELEMENT":"a7667a36-1990-4880-856c-a144e1de85cd"}}]
13:55:55 INFO - 1499288155866 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a7667a36-1990-4880-856c-a144e1de85cd"}]
13:55:55 INFO - 1499288155990 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
13:55:56 INFO - 1499288156000 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"a7667a36-1990-4880-856c-a144e1de85cd"}]
13:55:56 INFO - 1499288156170 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element