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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:49:23 INFO - 1500976163568 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
02:49:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500976163576 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:49:23 INFO - 1500976163628 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:49:23 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:23 INFO - 1500976163633 Marionette TRACE 352 <- [1,6,null,{}]
02:49:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1552ms
02:49:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:49:23 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:23 INFO - 1500976163689 Marionette DEBUG Closed connection 352
02:49:23 INFO - 1500976163691 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49781
02:49:23 INFO - 1500976163743 Marionette DEBUG Closed connection 353
02:49:23 INFO - 1500976163744 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
02:49:23 INFO - 1500976163799 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:23 INFO - 1500976163802 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:23 INFO - 1500976163822 Marionette DEBUG Register listener.js for window 12
02:49:23 INFO - 1500976163833 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4cf7a0ea-9cef-4e62-970e-1aff80e502aa","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:23 INFO - 1500976163857 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:49:23 INFO - 1500976163859 Marionette TRACE 354 <- [1,2,null,{}]
02:49:23 INFO - 1500976163878 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:23 INFO - 1500976163879 Marionette TRACE 354 <- [1,3,null,{}]
02:49:23 INFO - 1500976163881 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:23 INFO - 1500976163883 Marionette TRACE 354 <- [1,4,null,{}]
02:49:23 INFO - 1500976163887 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
02:49:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500976163894 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:49:23 INFO - 1500976163898 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:49:23 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:23 INFO - 1500976163903 Marionette TRACE 354 <- [1,6,null,{}]
02:49:23 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:23 INFO - 1500976163928 Marionette DEBUG Closed connection 354
02:49:23 INFO - 1500976163930 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
02:49:23 INFO - 1500976163931 Marionette DEBUG Closed connection 355
02:49:23 INFO - 1500976163932 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
02:49:23 INFO - 1500976163956 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:23 INFO - 1500976163968 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:24 INFO - 1500976164010 Marionette DEBUG Register listener.js for window 12
02:49:24 INFO - 1500976164033 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f24ac277-cdeb-446a-bc32-84923a07f303","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:24 INFO - 1500976164050 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:49:24 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:24 INFO - 1500976164057 Marionette TRACE 356 <- [1,2,null,{}]
02:49:24 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:24 INFO - 1500976164117 Marionette DEBUG Closed connection 356
02:49:24 INFO - 1500976164120 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
02:49:24 INFO - 1500976164141 Marionette DEBUG Closed connection 357
02:49:24 INFO - 1500976164144 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
02:49:24 INFO - 1500976164151 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:24 INFO - 1500976164157 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:24 INFO - 1500976164199 Marionette DEBUG Register listener.js for window 12
02:49:24 INFO - 1500976164220 Marionette TRACE 358 <- [1,1,null,{"sessionId":"29942071-c42e-43c9-b893-870d34618d4d","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:24 INFO - 1500976164247 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
02:49:24 INFO - 1500976164255 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
02:49:24 INFO - 1500976164272 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
02:49:24 INFO - 1500976164273 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
02:49:24 INFO - ++DOMWINDOW == 28 (00000038E5E26800) [pid = 2812] [serial = 41] [outer = 00000038EA857000]
02:49:24 INFO - 1500976164353 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
02:49:24 INFO - 1500976164360 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
02:49:24 INFO - 1500976164385 Marionette TRACE 358 <- [1,2,null,{}]
02:49:24 INFO - 1500976164405 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:49:24 INFO - 1500976164417 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6a21a106-8e99-4085-8af5-3dc08a96af54","ELEMENT":"6a21a106-8e99-4085-8af5-3dc08a96af54"}}]
02:49:24 INFO - 1500976164421 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6a21a106-8e99-4085-8af5-3dc08a96af54"}]
02:49:24 INFO - 1500976164811 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
02:49:24 INFO - [Parent 2812] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
02:49:24 INFO - 1500976164831 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
02:49:24 INFO - 1500976164833 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
02:49:24 INFO - ++DOMWINDOW == 29 (00000038E7C4A800) [pid = 2812] [serial = 42] [outer = 00000038EA857000]
02:49:24 INFO - 1500976164916 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
02:49:24 INFO - 1500976164923 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
02:49:24 INFO - 1500976164945 Marionette TRACE 358 <- [1,4,null,{}]
02:49:25 INFO - 1500976164998 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:49:25 INFO - 1500976165009 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4012d60f-8b85-450e-a353-4750fb11a37e","ELEMENT":"4012d60f-8b85-450e-a353-4750fb11a37e"}}]
02:49:25 INFO - 1500976165012 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:49:25 INFO - 1500976165014 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:49:25 INFO - 1500976165018 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
02:49:25 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500976165030 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:49:25 INFO - 1500976165033 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165039 Marionette TRACE 358 <- [1,8,null,{}]
02:49:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1407ms
02:49:25 INFO - TEST-START | test_click.py TestClick.test_container_element
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165047 Marionette DEBUG Closed connection 358
02:49:25 INFO - 1500976165050 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49789
02:49:25 INFO - 1500976165052 Marionette DEBUG Closed connection 359
02:49:25 INFO - 1500976165055 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
02:49:25 INFO - 1500976165085 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:25 INFO - 1500976165091 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:25 INFO - 1500976165132 Marionette DEBUG Register listener.js for window 12
02:49:25 INFO - 1500976165162 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f17a205f-5c31-44dc-82bd-fd57d5bcc0fc","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:25 INFO - 1500976165175 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:49:25 INFO - 1500976165177 Marionette TRACE 360 <- [1,2,null,{}]
02:49:25 INFO - 1500976165229 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:25 INFO - 1500976165231 Marionette TRACE 360 <- [1,3,null,{}]
02:49:25 INFO - 1500976165235 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:25 INFO - 1500976165237 Marionette TRACE 360 <- [1,4,null,{}]
02:49:25 INFO - 1500976165240 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
02:49:25 INFO - TEST-START: test_click.py TestClick.test_container_element1500976165250 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:49:25 INFO - 1500976165253 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165258 Marionette TRACE 360 <- [1,6,null,{}]
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165281 Marionette DEBUG Closed connection 360
02:49:25 INFO - 1500976165283 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
02:49:25 INFO - 1500976165338 Marionette DEBUG Closed connection 361
02:49:25 INFO - 1500976165340 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
02:49:25 INFO - 1500976165364 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:25 INFO - 1500976165367 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:25 INFO - 1500976165408 Marionette DEBUG Register listener.js for window 12
02:49:25 INFO - 1500976165425 Marionette TRACE 362 <- [1,1,null,{"sessionId":"39129e50-a81c-40e3-8422-ea1fa5c185cd","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:25 INFO - 1500976165434 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165439 Marionette TRACE 362 <- [1,2,null,{}]
02:49:25 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:25 INFO - 1500976165447 Marionette DEBUG Closed connection 362
02:49:25 INFO - 1500976165450 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
02:49:25 INFO - 1500976165452 Marionette DEBUG Closed connection 363
02:49:25 INFO - 1500976165454 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
02:49:25 INFO - 1500976165457 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:25 INFO - 1500976165464 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:25 INFO - 1500976165507 Marionette DEBUG Register listener.js for window 12
02:49:25 INFO - 1500976165556 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f645251b-311b-4c4a-a73f-3392cf559701","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:25 INFO - 1500976165590 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:49:25 INFO - 1500976165595 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
02:49:25 INFO - 1500976165603 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
02:49:25 INFO - 1500976165604 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
02:49:25 INFO - ++DOMWINDOW == 30 (00000038ED00A000) [pid = 2812] [serial = 43] [outer = 00000038EA857000]
02:49:25 INFO - 1500976165679 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"
02:49:25 INFO - 1500976165694 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"
02:49:25 INFO - 1500976165711 Marionette TRACE 364 <- [1,2,null,{}]
02:49:25 INFO - 1500976165718 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:49:25 INFO - 1500976165729 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb7b782a-15e0-4728-84ea-213392615fa6","ELEMENT":"fb7b782a-15e0-4728-84ea-213392615fa6"}}]
02:49:25 INFO - 1500976165731 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fb7b782a-15e0-4728-84ea-213392615fa6"}]
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
02:49:26 INFO - 1500976166327 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:49:26 INFO - 1500976166330 Marionette TRACE 364 <- [1,4,null,{}]
02:49:26 INFO - 1500976166332 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fb7b782a-15e0-4728-84ea-213392615fa6","name":"selected"}]
02:49:26 INFO - 1500976166388 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:49:26 INFO - 1500976166563 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
02:49:26 INFO - TEST-END: test_click.py TestClick.test_container_element1500976166578 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:49:26 INFO - 1500976166591 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:26 INFO - 1500976166604 Marionette TRACE 364 <- [1,7,null,{}]
02:49:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1582ms
02:49:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:26 INFO - 1500976166634 Marionette DEBUG Closed connection 364
02:49:26 INFO - 1500976166638 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
02:49:26 INFO - 1500976166681 Marionette DEBUG Closed connection 365
02:49:26 INFO - 1500976166684 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
02:49:26 INFO - 1500976166712 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:26 INFO - 1500976166722 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:26 INFO - 1500976166747 Marionette DEBUG Register listener.js for window 12
02:49:26 INFO - 1500976166768 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4eb65f93-1795-4468-9d93-7c5d33d88c9b","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:26 INFO - 1500976166785 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:49:26 INFO - 1500976166789 Marionette TRACE 366 <- [1,2,null,{}]
02:49:26 INFO - 1500976166812 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:26 INFO - 1500976166815 Marionette TRACE 366 <- [1,3,null,{}]
02:49:26 INFO - 1500976166819 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:26 INFO - 1500976166822 Marionette TRACE 366 <- [1,4,null,{}]
02:49:26 INFO - 1500976166825 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
02:49:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500976166838 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:49:26 INFO - 1500976166874 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:26 INFO - 1500976166881 Marionette TRACE 366 <- [1,6,null,{}]
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:26 INFO - 1500976166912 Marionette DEBUG Closed connection 366
02:49:26 INFO - 1500976166915 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
02:49:26 INFO - 1500976166920 Marionette DEBUG Closed connection 367
02:49:26 INFO - 1500976166922 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
02:49:26 INFO - 1500976166928 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:26 INFO - 1500976166932 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:26 INFO - 1500976166955 Marionette DEBUG Register listener.js for window 12
02:49:26 INFO - 1500976166971 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0a94155a-8977-45da-95d9-d27ee5c66ff7","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:26 INFO - 1500976166982 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:49:26 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:26 INFO - 1500976166990 Marionette TRACE 368 <- [1,2,null,{}]
02:49:27 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:27 INFO - 1500976167017 Marionette DEBUG Closed connection 368
02:49:27 INFO - 1500976167019 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
02:49:27 INFO - 1500976167025 Marionette DEBUG Closed connection 369
02:49:27 INFO - 1500976167027 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
02:49:27 INFO - 1500976167041 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:27 INFO - 1500976167046 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:27 INFO - 1500976167070 Marionette DEBUG Register listener.js for window 12
02:49:27 INFO - 1500976167086 Marionette TRACE 370 <- [1,1,null,{"sessionId":"528581df-0220-46ac-bfb7-4237fdc1eace","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:27 INFO - 1500976167094 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:49:27 INFO - 1500976167099 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"
02:49:27 INFO - 1500976167109 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"
02:49:27 INFO - 1500976167110 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"
02:49:27 INFO - ++DOMWINDOW == 31 (00000038DF670000) [pid = 2812] [serial = 44] [outer = 00000038EA857000]
02:49:27 INFO - 1500976167167 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"
02:49:27 INFO - 1500976167175 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"
02:49:27 INFO - 1500976167197 Marionette TRACE 370 <- [1,2,null,{}]
02:49:27 INFO - 1500976167200 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:49:27 INFO - 1500976167211 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02ef7ba9-2648-426b-b543-59024de0622c","ELEMENT":"02ef7ba9-2648-426b-b543-59024de0622c"}}]
02:49:27 INFO - 1500976167219 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"02ef7ba9-2648-426b-b543-59024de0622c"}]
02:49:27 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
02:49:27 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
02:49:27 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
02:49:27 INFO - --DOMWINDOW == 30 (00000038F55CE000) [pid = 2812] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 29 (00000038E1447000) [pid = 2812] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 28 (00000038EFEE2000) [pid = 2812] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 27 (00000038DFA05800) [pid = 2812] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 26 (00000038F4607800) [pid = 2812] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 25 (00000038E63DF800) [pid = 2812] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 24 (00000038E6072800) [pid = 2812] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:27 INFO - --DOMWINDOW == 23 (00000038E9D91000) [pid = 2812] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:28 INFO - 1500976168359 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:49:28 INFO - 1500976168380 Marionette TRACE 370 <- [1,4,null,{}]
02:49:28 INFO - 1500976168427 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"02ef7ba9-2648-426b-b543-59024de0622c","name":"selected"}]
02:49:28 INFO - 1500976168457 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:49:28 INFO - 1500976168460 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
02:49:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500976168468 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:49:28 INFO - 1500976168523 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168527 Marionette TRACE 370 <- [1,7,null,{}]
02:49:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1905ms
02:49:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168580 Marionette DEBUG Closed connection 370
02:49:28 INFO - 1500976168582 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
02:49:28 INFO - 1500976168633 Marionette DEBUG Closed connection 371
02:49:28 INFO - 1500976168636 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
02:49:28 INFO - 1500976168689 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:28 INFO - 1500976168692 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:28 INFO - 1500976168715 Marionette DEBUG Register listener.js for window 12
02:49:28 INFO - 1500976168730 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2184ccb4-3a96-4305-ba4f-ace078ce083d","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:28 INFO - 1500976168763 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:49:28 INFO - 1500976168765 Marionette TRACE 372 <- [1,2,null,{}]
02:49:28 INFO - 1500976168784 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:28 INFO - 1500976168786 Marionette TRACE 372 <- [1,3,null,{}]
02:49:28 INFO - 1500976168790 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:28 INFO - 1500976168791 Marionette TRACE 372 <- [1,4,null,{}]
02:49:28 INFO - 1500976168794 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
02:49:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500976168803 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:49:28 INFO - 1500976168807 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168811 Marionette TRACE 372 <- [1,6,null,{}]
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168834 Marionette DEBUG Closed connection 372
02:49:28 INFO - 1500976168835 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
02:49:28 INFO - 1500976168840 Marionette DEBUG Closed connection 373
02:49:28 INFO - 1500976168842 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
02:49:28 INFO - 1500976168844 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:28 INFO - 1500976168849 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:28 INFO - 1500976168872 Marionette DEBUG Register listener.js for window 12
02:49:28 INFO - 1500976168887 Marionette TRACE 374 <- [1,1,null,{"sessionId":"55fa00e1-d463-4697-8298-004250bfd8dc","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:28 INFO - 1500976168897 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168902 Marionette TRACE 374 <- [1,2,null,{}]
02:49:28 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:28 INFO - 1500976168927 Marionette DEBUG Closed connection 374
02:49:28 INFO - 1500976168932 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
02:49:28 INFO - 1500976168937 Marionette DEBUG Closed connection 375
02:49:28 INFO - 1500976168939 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
02:49:28 INFO - 1500976168942 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:28 INFO - 1500976168945 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:28 INFO - 1500976168969 Marionette DEBUG Register listener.js for window 12
02:49:28 INFO - 1500976168983 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2b46e88a-80a4-4d37-a54b-cee53a3b621c","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:28 INFO - 1500976168993 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
02:49:28 INFO - 1500976168998 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"
02:49:29 INFO - 1500976169006 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"
02:49:29 INFO - 1500976169007 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"
02:49:29 INFO - ++DOMWINDOW == 24 (00000038E7C3A800) [pid = 2812] [serial = 45] [outer = 00000038EA857000]
02:49:29 INFO - 1500976169069 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"
02:49:29 INFO - 1500976169076 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"
02:49:29 INFO - 1500976169096 Marionette TRACE 376 <- [1,2,null,{}]
02:49:29 INFO - 1500976169103 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:49:29 INFO - 1500976169112 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb1b93ba-7709-471e-ac35-8781270ff9d3","ELEMENT":"cb1b93ba-7709-471e-ac35-8781270ff9d3"}}]
02:49:29 INFO - 1500976169117 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"cb1b93ba-7709-471e-ac35-8781270ff9d3"}]
02:49:29 INFO - 1500976169579 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:49:29 INFO - 1500976169582 Marionette TRACE 376 <- [1,4,null,{}]
02:49:29 INFO - 1500976169585 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
02:49:29 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500976169594 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:49:29 INFO - 1500976169596 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169600 Marionette TRACE 376 <- [1,6,null,{}]
02:49:29 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1071ms
02:49:29 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169606 Marionette DEBUG Closed connection 376
02:49:29 INFO - 1500976169608 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
02:49:29 INFO - 1500976169610 Marionette DEBUG Closed connection 377
02:49:29 INFO - 1500976169611 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
02:49:29 INFO - 1500976169613 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:29 INFO - 1500976169616 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:29 INFO - 1500976169638 Marionette DEBUG Register listener.js for window 12
02:49:29 INFO - 1500976169649 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e108f88c-4974-40d2-8522-fdc5cbd3fbde","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:29 INFO - 1500976169656 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:49:29 INFO - 1500976169658 Marionette TRACE 378 <- [1,2,null,{}]
02:49:29 INFO - 1500976169660 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:29 INFO - 1500976169661 Marionette TRACE 378 <- [1,3,null,{}]
02:49:29 INFO - 1500976169663 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:29 INFO - 1500976169665 Marionette TRACE 378 <- [1,4,null,{}]
02:49:29 INFO - 1500976169667 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
02:49:29 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500976169706 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:49:29 INFO - 1500976169709 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169713 Marionette TRACE 378 <- [1,6,null,{}]
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169719 Marionette DEBUG Closed connection 378
02:49:29 INFO - 1500976169720 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
02:49:29 INFO - 1500976169722 Marionette DEBUG Closed connection 379
02:49:29 INFO - 1500976169724 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
02:49:29 INFO - 1500976169727 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:29 INFO - 1500976169730 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:29 INFO - 1500976169752 Marionette DEBUG Register listener.js for window 12
02:49:29 INFO - 1500976169764 Marionette TRACE 380 <- [1,1,null,{"sessionId":"32f2b972-a27e-4269-b910-34ee269373b8","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:29 INFO - 1500976169770 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169774 Marionette TRACE 380 <- [1,2,null,{}]
02:49:29 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:29 INFO - 1500976169780 Marionette DEBUG Closed connection 380
02:49:29 INFO - 1500976169782 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
02:49:29 INFO - 1500976169783 Marionette DEBUG Closed connection 381
02:49:29 INFO - 1500976169785 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
02:49:29 INFO - 1500976169787 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:29 INFO - 1500976169790 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:29 INFO - 1500976169813 Marionette DEBUG Register listener.js for window 12
02:49:29 INFO - 1500976169826 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5f54ffa1-7546-400c-a08d-0b56c96e8a42","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:29 INFO - 1500976169832 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:49:29 INFO - 1500976169836 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"
02:49:29 INFO - 1500976169843 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"
02:49:29 INFO - 1500976169844 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"
02:49:29 INFO - ++DOMWINDOW == 25 (00000038E9D80000) [pid = 2812] [serial = 46] [outer = 00000038EA857000]
02:49:29 INFO - 1500976169902 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"
02:49:29 INFO - 1500976169906 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"
02:49:29 INFO - 1500976169924 Marionette TRACE 382 <- [1,2,null,{}]
02:49:29 INFO - 1500976169927 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:49:29 INFO - 1500976169936 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1561586f-732c-408d-afe4-1bdc8a7d0a48","ELEMENT":"1561586f-732c-408d-afe4-1bdc8a7d0a48"}}]
02:49:29 INFO - 1500976169938 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1561586f-732c-408d-afe4-1bdc8a7d0a48"}]
02:49:30 INFO - 1500976170471 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:49:30 INFO - 1500976170473 Marionette TRACE 382 <- [1,4,null,{}]
02:49:30 INFO - 1500976170476 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
02:49:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500976170486 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:49:30 INFO - 1500976170490 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:49:30 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:30 INFO - 1500976170494 Marionette TRACE 382 <- [1,6,null,{}]
02:49:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 893ms
02:49:30 INFO - TEST-START | test_click.py TestClick.test_input_file
02:49:30 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:30 INFO - 1500976170500 Marionette DEBUG Closed connection 382
02:49:30 INFO - 1500976170501 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
02:49:30 INFO - 1500976170503 Marionette DEBUG Closed connection 383
02:49:30 INFO - 1500976170504 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
02:49:30 INFO - 1500976170507 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:30 INFO - 1500976170509 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:30 INFO - 1500976170531 Marionette DEBUG Register listener.js for window 12
02:49:30 INFO - 1500976170543 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2a3c6935-6522-439a-8677-4bb24bc6be32","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:30 INFO - 1500976170549 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:49:30 INFO - 1500976170551 Marionette TRACE 384 <- [1,2,null,{}]
02:49:30 INFO - 1500976170553 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:30 INFO - 1500976170555 Marionette TRACE 384 <- [1,3,null,{}]
02:49:30 INFO - 1500976170584 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:30 INFO - 1500976170586 Marionette TRACE 384 <- [1,4,null,{}]
02:49:31 INFO - 1500976171191 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
02:49:31 INFO - TEST-START: test_click.py TestClick.test_input_file1500976171199 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:49:31 INFO - 1500976171251 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171256 Marionette TRACE 384 <- [1,6,null,{}]
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171297 Marionette DEBUG Closed connection 384
02:49:31 INFO - 1500976171298 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
02:49:31 INFO - 1500976171307 Marionette DEBUG Closed connection 385
02:49:31 INFO - 1500976171309 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
02:49:31 INFO - 1500976171311 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:31 INFO - 1500976171315 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:31 INFO - 1500976171342 Marionette DEBUG Register listener.js for window 12
02:49:31 INFO - 1500976171357 Marionette TRACE 386 <- [1,1,null,{"sessionId":"8a71699d-35c9-4b21-aae9-4722b2d5b956","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:31 INFO - 1500976171366 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171370 Marionette TRACE 386 <- [1,2,null,{}]
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171393 Marionette DEBUG Closed connection 386
02:49:31 INFO - 1500976171394 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
02:49:31 INFO - 1500976171398 Marionette DEBUG Closed connection 387
02:49:31 INFO - 1500976171400 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
02:49:31 INFO - 1500976171402 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:31 INFO - 1500976171405 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:31 INFO - 1500976171427 Marionette DEBUG Register listener.js for window 12
02:49:31 INFO - 1500976171450 Marionette TRACE 388 <- [1,1,null,{"sessionId":"8070b66f-b850-4187-9a78-194462aa1836","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:31 INFO - 1500976171461 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:49:31 INFO - 1500976171466 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"
02:49:31 INFO - 1500976171475 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"
02:49:31 INFO - 1500976171477 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"
02:49:31 INFO - ++DOMWINDOW == 26 (00000038EA1A7000) [pid = 2812] [serial = 47] [outer = 00000038EA857000]
02:49:31 INFO - 1500976171538 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:49:31 INFO - 1500976171543 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:49:31 INFO - 1500976171561 Marionette TRACE 388 <- [1,2,null,{}]
02:49:31 INFO - 1500976171568 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:49:31 INFO - 1500976171577 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ea26e9ea-14ef-4f65-a363-2a255ffe5ec5","ELEMENT":"ea26e9ea-14ef-4f65-a363-2a255ffe5ec5"}}]
02:49:31 INFO - 1500976171580 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ea26e9ea-14ef-4f65-a363-2a255ffe5ec5"}]
02:49:31 INFO - 1500976171745 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:49:31 INFO - 1500976171749 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
02:49:31 INFO - TEST-END: test_click.py TestClick.test_input_file1500976171757 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:49:31 INFO - 1500976171759 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171764 Marionette TRACE 388 <- [1,6,null,{}]
02:49:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1270ms
02:49:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:49:31 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:31 INFO - 1500976171788 Marionette DEBUG Closed connection 388
02:49:31 INFO - 1500976171790 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
02:49:31 INFO - 1500976171845 Marionette DEBUG Closed connection 389
02:49:31 INFO - 1500976171848 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
02:49:31 INFO - 1500976171855 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:31 INFO - 1500976171865 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:31 INFO - 1500976171914 Marionette DEBUG Register listener.js for window 12
02:49:31 INFO - 1500976171937 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7e903699-d898-4996-9d26-109bb6526ffe","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:31 INFO - 1500976171954 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:49:31 INFO - 1500976171957 Marionette TRACE 390 <- [1,2,null,{}]
02:49:32 INFO - 1500976172012 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:32 INFO - 1500976172015 Marionette TRACE 390 <- [1,3,null,{}]
02:49:32 INFO - 1500976172020 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:32 INFO - 1500976172025 Marionette TRACE 390 <- [1,4,null,{}]
02:49:32 INFO - 1500976172079 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
02:49:32 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500976172095 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:49:32 INFO - 1500976172101 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:49:32 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:32 INFO - 1500976172108 Marionette TRACE 390 <- [1,6,null,{}]
02:49:32 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:32 INFO - 1500976172124 Marionette DEBUG Closed connection 390
02:49:32 INFO - 1500976172127 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
02:49:32 INFO - 1500976172130 Marionette DEBUG Closed connection 391
02:49:32 INFO - 1500976172134 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
02:49:32 INFO - 1500976172137 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:32 INFO - 1500976172143 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:32 INFO - 1500976172189 Marionette DEBUG Register listener.js for window 12
02:49:32 INFO - 1500976172206 Marionette TRACE 392 <- [1,1,null,{"sessionId":"369f5a06-fd8b-42b5-8cf2-caba5e483433","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:32 INFO - 1500976172249 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:49:32 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:32 INFO - 1500976172255 Marionette TRACE 392 <- [1,2,null,{}]
02:49:32 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:32 INFO - 1500976172264 Marionette DEBUG Closed connection 392
02:49:32 INFO - 1500976172266 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
02:49:32 INFO - 1500976172288 Marionette DEBUG Closed connection 393
02:49:32 INFO - 1500976172291 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
02:49:32 INFO - 1500976172342 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:32 INFO - 1500976172346 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:32 INFO - 1500976172391 Marionette DEBUG Register listener.js for window 12
02:49:32 INFO - 1500976172408 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1628136e-ff76-44cd-99c9-b31c2341c23d","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:32 INFO - 1500976172421 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
02:49:32 INFO - 1500976172427 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:49:32 INFO - 1500976172434 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:49:32 INFO - 1500976172435 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:49:32 INFO - ++DOMWINDOW == 27 (00000038DF4A4800) [pid = 2812] [serial = 48] [outer = 00000038EA857000]
02:49:32 INFO - 1500976172723 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"
02:49:32 INFO - 1500976172730 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"
02:49:32 INFO - 1500976172748 Marionette TRACE 394 <- [1,2,null,{}]
02:49:32 INFO - 1500976172754 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:49:32 INFO - 1500976172766 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79a99355-94c5-41fa-bc6d-a1596a4f1d77","ELEMENT":"79a99355-94c5-41fa-bc6d-a1596a4f1d77"}}]
02:49:32 INFO - 1500976172771 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:49:32 INFO - 1500976172781 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"71551bc7-f79d-457a-ab15-fcf59877e031","ELEMENT":"71551bc7-f79d-457a-ab15-fcf59877e031"}}]
02:49:32 INFO - 1500976172786 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"79a99355-94c5-41fa-bc6d-a1596a4f1d77"}]
02:49:33 INFO - 1500976173331 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:49:33 INFO - 1500976173334 Marionette TRACE 394 <- [1,5,null,{}]
02:49:33 INFO - 1500976173338 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"71551bc7-f79d-457a-ab15-fcf59877e031"}]
02:49:33 INFO - 1500976173431 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:49:33 INFO - 1500976173472 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:49:33 INFO - 1500976173485 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:49:33 INFO - 1500976173492 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
02:49:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500976173503 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:49:33 INFO - 1500976173506 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173514 Marionette TRACE 394 <- [1,9,null,{}]
02:49:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1751ms
02:49:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173523 Marionette DEBUG Closed connection 394
02:49:33 INFO - 1500976173525 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
02:49:33 INFO - 1500976173527 Marionette DEBUG Closed connection 395
02:49:33 INFO - 1500976173530 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
02:49:33 INFO - 1500976173533 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:33 INFO - 1500976173543 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:33 INFO - 1500976173570 Marionette DEBUG Register listener.js for window 12
02:49:33 INFO - 1500976173599 Marionette TRACE 396 <- [1,1,null,{"sessionId":"eda5fea4-403e-48ee-bbb1-91c60326f564","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:33 INFO - 1500976173616 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:49:33 INFO - 1500976173618 Marionette TRACE 396 <- [1,2,null,{}]
02:49:33 INFO - 1500976173620 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:49:33 INFO - 1500976173623 Marionette TRACE 396 <- [1,3,null,{}]
02:49:33 INFO - 1500976173653 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:49:33 INFO - 1500976173658 Marionette TRACE 396 <- [1,4,null,{}]
02:49:33 INFO - 1500976173661 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
02:49:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500976173670 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:49:33 INFO - 1500976173717 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173723 Marionette TRACE 396 <- [1,6,null,{}]
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173753 Marionette DEBUG Closed connection 396
02:49:33 INFO - 1500976173756 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
02:49:33 INFO - 1500976173778 Marionette DEBUG Closed connection 397
02:49:33 INFO - 1500976173780 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
02:49:33 INFO - 1500976173792 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:49:33 INFO - 1500976173795 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:33 INFO - 1500976173821 Marionette DEBUG Register listener.js for window 12
02:49:33 INFO - 1500976173839 Marionette TRACE 398 <- [1,1,null,{"sessionId":"bb2c497e-f374-4884-b1e6-49a70e6ec07c","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:33 INFO - 1500976173852 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173858 Marionette TRACE 398 <- [1,2,null,{}]
02:49:33 INFO - [Parent 2812] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
02:49:33 INFO - 1500976173868 Marionette DEBUG Closed connection 398
02:49:33 INFO - 1500976173870 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
02:49:33 INFO - 1500976173890 Marionette DEBUG Closed connection 399
02:49:33 INFO - 1500976173892 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
02:49:33 INFO - 1500976173895 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:49:33 INFO - 1500976173899 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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}
02:49:33 INFO - 1500976173923 Marionette DEBUG Register listener.js for window 12
02:49:33 INFO - 1500976173937 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8057c5d0-d84d-4b64-baaa-3111cc19aa75","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":2812,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpudl8sx.mozrunner","moz:accessibilityChecks":false}}]
02:49:33 INFO - 1500976173949 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
02:49:33 INFO - 1500976173956 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"
02:49:33 INFO - 1500976173965 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"
02:49:33 INFO - 1500976173966 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"
02:49:33 INFO - ++DOMWINDOW == 28 (00000038E63DF800) [pid = 2812] [serial = 49] [outer = 00000038EA857000]
02:49:34 INFO - 1500976174032 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"
02:49:34 INFO - 1500976174038 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"
02:49:34 INFO - 1500976174055 Marionette TRACE 400 <- [1,2,null,{}]
02:49:34 INFO - --DOMWINDOW == 27 (00000038E7C34000) [pid = 2812] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:34 INFO - --DOMWINDOW == 26 (00000038F2052800) [pid = 2812] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
02:49:34 INFO - --DOMWINDOW == 25 (00000038EDEC8800) [pid = 2812] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%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.clicks%20%3D%200%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.clicks%2B%2B%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]
02:49:34 INFO - --DOMWINDOW == 24 (00000038EBCBE800) [pid = 2812] [serial = 35] [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]
02:49:34 INFO - --DOMWINDOW == 23 (00000038EADE0800) [pid = 2812] [serial = 34] [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]
02:49:34 INFO - --DOMWINDOW == 22 (00000038F2057000) [pid = 2812] [serial = 39] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test.html]
02:49:34 INFO - --DOMWINDOW == 21 (00000038F2DCD800) [pid = 2812] [serial = 38] [outer = 0000000000000000] [url = http://127.0.0.1:49333/clicks.html]
02:49:34 INFO - --DOMWINDOW == 20 (00000038EE124800) [pid = 2812] [serial = 37] [outer = 0000000000000000] [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]
02:49:34 INFO - --DOMWINDOW == 19 (00000038EA1AF000) [pid = 2812] [serial = 33] [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]
02:49:34 INFO - 1500976174068 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:49:34 INFO - 1500976174077 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7ce80be7-b0df-40a5-bbc6-3e4fefebd122","ELEMENT":"7ce80be7-b0df-40a5-bbc6-3e4fefebd122"}}]
02:49:34 INFO - 1500976174079 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"7ce80be7-b0df-40a5-bbc6-3e4fefebd122"}]
02:49:34 INFO - 1500976174204 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:49:34 INFO - 1500976174225 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"7ce80be7-b0df-40a5-bbc6-3e4fefebd122"}]
02:49:34 INFO - 1500976174321 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element