could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:55:32 INFO - 1500393332877 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}]
08:55:32 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500393332885 Marionette TRACE 352 <- [1,5,null,{"value":null}]
08:55:32 INFO - 1500393332907 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
08:55:32 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:32 INFO - 1500393332911 Marionette TRACE 352 <- [1,6,null,{}]
08:55:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 801ms
08:55:32 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:55:32 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:32 INFO - 1500393332918 Marionette DEBUG Closed connection 352
08:55:32 INFO - 1500393332919 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
08:55:32 INFO - 1500393332921 Marionette DEBUG Closed connection 353
08:55:32 INFO - 1500393332922 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
08:55:32 INFO - 1500393332924 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:32 INFO - 1500393332927 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:32 INFO - 1500393332950 Marionette DEBUG Register listener.js for window 15
08:55:33 INFO - 1500393333679 Marionette TRACE 354 <- [1,1,null,{"sessionId":"86dcb517-2b0d-4ce7-ae0e-da1ef330be34","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:33 INFO - 1500393333698 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
08:55:33 INFO - 1500393333699 Marionette TRACE 354 <- [1,2,null,{}]
08:55:33 INFO - 1500393333749 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:33 INFO - 1500393333751 Marionette TRACE 354 <- [1,3,null,{}]
08:55:33 INFO - 1500393333803 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:33 INFO - 1500393333805 Marionette TRACE 354 <- [1,4,null,{}]
08:55:33 INFO - 1500393333851 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}]
08:55:33 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500393333861 Marionette TRACE 354 <- [1,5,null,{"value":null}]
08:55:33 INFO - 1500393333908 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
08:55:33 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:33 INFO - 1500393333912 Marionette TRACE 354 <- [1,6,null,{}]
08:55:33 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:33 INFO - 1500393333918 Marionette DEBUG Closed connection 354
08:55:33 INFO - 1500393333920 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
08:55:33 INFO - 1500393333921 Marionette DEBUG Closed connection 355
08:55:33 INFO - 1500393333924 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
08:55:33 INFO - 1500393333926 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:33 INFO - 1500393333930 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:33 INFO - 1500393333952 Marionette DEBUG Register listener.js for window 15
08:55:33 INFO - 1500393333963 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9e7e79ea-8997-47be-803c-b275e7583b03","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:33 INFO - 1500393333969 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
08:55:33 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:33 INFO - 1500393333973 Marionette TRACE 356 <- [1,2,null,{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334032 Marionette DEBUG Closed connection 356
08:55:34 INFO - 1500393334033 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
08:55:34 INFO - 1500393334035 Marionette DEBUG Closed connection 357
08:55:34 INFO - 1500393334037 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
08:55:34 INFO - 1500393334043 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:34 INFO - 1500393334046 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:34 INFO - 1500393334068 Marionette DEBUG Register listener.js for window 15
08:55:34 INFO - 1500393334080 Marionette TRACE 358 <- [1,1,null,{"sessionId":"42f9f52e-c349-4d1b-9238-280131b83aec","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:34 INFO - 1500393334089 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
08:55:34 INFO - 1500393334093 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
08:55:34 INFO - 1500393334107 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
08:55:34 INFO - 1500393334108 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
08:55:34 INFO - ++DOMWINDOW == 22 (000000C352BD4800) [pid = 3604] [serial = 40] [outer = 000000C3525DA800]
08:55:34 INFO - 1500393334177 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
08:55:34 INFO - 1500393334182 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
08:55:34 INFO - 1500393334204 Marionette TRACE 358 <- [1,2,null,{}]
08:55:34 INFO - 1500393334207 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:55:34 INFO - 1500393334218 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4a0819b-a097-4310-97b5-09d32461798e","ELEMENT":"f4a0819b-a097-4310-97b5-09d32461798e"}}]
08:55:34 INFO - 1500393334220 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f4a0819b-a097-4310-97b5-09d32461798e"}]
08:55:34 INFO - 1500393334440 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
08:55:34 INFO - [Parent 3604] 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
08:55:34 INFO - 1500393334459 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
08:55:34 INFO - 1500393334461 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
08:55:34 INFO - ++DOMWINDOW == 23 (000000C354A38800) [pid = 3604] [serial = 41] [outer = 000000C3525DA800]
08:55:34 INFO - 1500393334522 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
08:55:34 INFO - 1500393334528 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
08:55:34 INFO - 1500393334545 Marionette TRACE 358 <- [1,4,null,{}]
08:55:34 INFO - 1500393334552 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:55:34 INFO - 1500393334560 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f469ec45-49fc-4f37-ab2b-c938126cdcd5","ELEMENT":"f469ec45-49fc-4f37-ab2b-c938126cdcd5"}}]
08:55:34 INFO - 1500393334562 Marionette TRACE 358 -> [0,6,"getTitle",{}]
08:55:34 INFO - 1500393334564 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
08:55:34 INFO - 1500393334568 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}]
08:55:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500393334576 Marionette TRACE 358 <- [1,7,null,{"value":null}]
08:55:34 INFO - 1500393334578 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334582 Marionette TRACE 358 <- [1,8,null,{}]
08:55:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1669ms
08:55:34 INFO - TEST-START | test_click.py TestClick.test_container_element
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334588 Marionette DEBUG Closed connection 358
08:55:34 INFO - 1500393334590 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
08:55:34 INFO - 1500393334591 Marionette DEBUG Closed connection 359
08:55:34 INFO - 1500393334593 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
08:55:34 INFO - 1500393334595 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:34 INFO - 1500393334598 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:34 INFO - 1500393334620 Marionette DEBUG Register listener.js for window 15
08:55:34 INFO - 1500393334631 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0926c4a0-5ba5-457b-bfb2-0cbf11f8e6bf","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:34 INFO - 1500393334660 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
08:55:34 INFO - 1500393334662 Marionette TRACE 360 <- [1,2,null,{}]
08:55:34 INFO - 1500393334664 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:34 INFO - 1500393334666 Marionette TRACE 360 <- [1,3,null,{}]
08:55:34 INFO - 1500393334668 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:34 INFO - 1500393334670 Marionette TRACE 360 <- [1,4,null,{}]
08:55:34 INFO - 1500393334673 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}]
08:55:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500393334681 Marionette TRACE 360 <- [1,5,null,{"value":null}]
08:55:34 INFO - 1500393334683 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334687 Marionette TRACE 360 <- [1,6,null,{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334710 Marionette DEBUG Closed connection 360
08:55:34 INFO - 1500393334712 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
08:55:34 INFO - 1500393334716 Marionette DEBUG Closed connection 361
08:55:34 INFO - 1500393334717 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
08:55:34 INFO - 1500393334719 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:34 INFO - 1500393334722 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:34 INFO - 1500393334749 Marionette DEBUG Register listener.js for window 15
08:55:34 INFO - 1500393334763 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4faaf9f8-4068-47ca-bbec-213c77681886","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:34 INFO - 1500393334774 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334778 Marionette TRACE 362 <- [1,2,null,{}]
08:55:34 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:34 INFO - 1500393334799 Marionette DEBUG Closed connection 362
08:55:34 INFO - 1500393334800 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
08:55:34 INFO - 1500393334804 Marionette DEBUG Closed connection 363
08:55:34 INFO - 1500393334806 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
08:55:34 INFO - 1500393334808 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:34 INFO - 1500393334812 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:34 INFO - 1500393334845 Marionette DEBUG Register listener.js for window 15
08:55:34 INFO - 1500393334857 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0d8a6e0a-c142-4f6d-a775-28c7394243ba","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:34 INFO - 1500393334867 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"}]
08:55:34 INFO - 1500393334871 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
08:55:34 INFO - 1500393334882 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
08:55:34 INFO - 1500393334882 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
08:55:34 INFO - ++DOMWINDOW == 24 (000000C354A4C000) [pid = 3604] [serial = 42] [outer = 000000C3525DA800]
08:55:34 INFO - 1500393334969 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"
08:55:34 INFO - 1500393334977 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"
08:55:35 INFO - 1500393334994 Marionette TRACE 364 <- [1,2,null,{}]
08:55:35 INFO - 1500393335002 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:55:35 INFO - 1500393335009 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e9d6893a-c2e2-4c21-9e36-3e568fa60919","ELEMENT":"e9d6893a-c2e2-4c21-9e36-3e568fa60919"}}]
08:55:35 INFO - 1500393335011 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e9d6893a-c2e2-4c21-9e36-3e568fa60919"}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
08:55:35 INFO - 1500393335457 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:55:35 INFO - 1500393335459 Marionette TRACE 364 <- [1,4,null,{}]
08:55:35 INFO - 1500393335461 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e9d6893a-c2e2-4c21-9e36-3e568fa60919","name":"selected"}]
08:55:35 INFO - 1500393335483 Marionette TRACE 364 <- [1,5,null,{"value":true}]
08:55:35 INFO - 1500393335486 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}]
08:55:35 INFO - TEST-END: test_click.py TestClick.test_container_element1500393335493 Marionette TRACE 364 <- [1,6,null,{"value":null}]
08:55:35 INFO - 1500393335495 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335499 Marionette TRACE 364 <- [1,7,null,{}]
08:55:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 917ms
08:55:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335505 Marionette DEBUG Closed connection 364
08:55:35 INFO - 1500393335507 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
08:55:35 INFO - 1500393335509 Marionette DEBUG Closed connection 365
08:55:35 INFO - 1500393335510 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
08:55:35 INFO - 1500393335512 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:35 INFO - 1500393335514 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:35 INFO - 1500393335536 Marionette DEBUG Register listener.js for window 15
08:55:35 INFO - 1500393335576 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e635d377-64d5-48f2-9cb5-d88e4012f4d3","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:35 INFO - 1500393335584 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
08:55:35 INFO - 1500393335586 Marionette TRACE 366 <- [1,2,null,{}]
08:55:35 INFO - 1500393335588 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:35 INFO - 1500393335589 Marionette TRACE 366 <- [1,3,null,{}]
08:55:35 INFO - 1500393335591 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:35 INFO - 1500393335592 Marionette TRACE 366 <- [1,4,null,{}]
08:55:35 INFO - 1500393335595 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}]
08:55:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500393335603 Marionette TRACE 366 <- [1,5,null,{"value":null}]
08:55:35 INFO - 1500393335605 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335609 Marionette TRACE 366 <- [1,6,null,{}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335615 Marionette DEBUG Closed connection 366
08:55:35 INFO - 1500393335617 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
08:55:35 INFO - 1500393335619 Marionette DEBUG Closed connection 367
08:55:35 INFO - 1500393335620 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
08:55:35 INFO - 1500393335622 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:35 INFO - 1500393335624 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:35 INFO - 1500393335647 Marionette DEBUG Register listener.js for window 15
08:55:35 INFO - 1500393335657 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3fd9e0f4-5c0e-4db9-abc4-d054e9f1a540","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:35 INFO - 1500393335687 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335691 Marionette TRACE 368 <- [1,2,null,{}]
08:55:35 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:35 INFO - 1500393335712 Marionette DEBUG Closed connection 368
08:55:35 INFO - 1500393335713 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
08:55:35 INFO - 1500393335718 Marionette DEBUG Closed connection 369
08:55:35 INFO - 1500393335719 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
08:55:35 INFO - 1500393335721 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:35 INFO - 1500393335724 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:35 INFO - 1500393335746 Marionette DEBUG Register listener.js for window 15
08:55:35 INFO - 1500393335757 Marionette TRACE 370 <- [1,1,null,{"sessionId":"38fb57b8-34a1-475b-a6ba-db364bfdfba6","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:35 INFO - 1500393335767 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"}]
08:55:35 INFO - 1500393335771 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"
08:55:35 INFO - 1500393335777 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"
08:55:35 INFO - 1500393335778 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"
08:55:35 INFO - ++DOMWINDOW == 25 (000000C357D06000) [pid = 3604] [serial = 43] [outer = 000000C3525DA800]
08:55:35 INFO - 1500393335821 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"
08:55:35 INFO - 1500393335829 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"
08:55:35 INFO - 1500393335847 Marionette TRACE 370 <- [1,2,null,{}]
08:55:35 INFO - 1500393335853 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:55:35 INFO - 1500393335860 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7f224b9-2f99-49fc-95a3-ff81277d9c7b","ELEMENT":"d7f224b9-2f99-49fc-95a3-ff81277d9c7b"}}]
08:55:35 INFO - 1500393335866 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d7f224b9-2f99-49fc-95a3-ff81277d9c7b"}]
08:55:36 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
08:55:36 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
08:55:36 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
08:55:36 INFO - 1500393336790 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:55:36 INFO - 1500393336805 Marionette TRACE 370 <- [1,4,null,{}]
08:55:36 INFO - 1500393336860 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d7f224b9-2f99-49fc-95a3-ff81277d9c7b","name":"selected"}]
08:55:36 INFO - 1500393336882 Marionette TRACE 370 <- [1,5,null,{"value":true}]
08:55:36 INFO - 1500393336924 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}]
08:55:36 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500393336936 Marionette TRACE 370 <- [1,6,null,{"value":null}]
08:55:36 INFO - 1500393336966 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
08:55:36 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:36 INFO - 1500393336970 Marionette TRACE 370 <- [1,7,null,{}]
08:55:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1470ms
08:55:36 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:55:37 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:37 INFO - 1500393337000 Marionette DEBUG Closed connection 370
08:55:37 INFO - 1500393337001 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
08:55:37 INFO - 1500393337005 Marionette DEBUG Closed connection 371
08:55:37 INFO - 1500393337007 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
08:55:37 INFO - 1500393337060 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:37 INFO - 1500393337072 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:37 INFO - 1500393337123 Marionette DEBUG Register listener.js for window 15
08:55:37 INFO - 1500393337149 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ebd54c0b-e385-4f29-af02-63fe786986d4","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:37 INFO - 1500393337164 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
08:55:37 INFO - 1500393337168 Marionette TRACE 372 <- [1,2,null,{}]
08:55:37 INFO - 1500393337172 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:37 INFO - 1500393337175 Marionette TRACE 372 <- [1,3,null,{}]
08:55:37 INFO - 1500393337179 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:37 INFO - 1500393337183 Marionette TRACE 372 <- [1,4,null,{}]
08:55:37 INFO - 1500393337187 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}]
08:55:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500393337202 Marionette TRACE 372 <- [1,5,null,{"value":null}]
08:55:37 INFO - 1500393337213 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
08:55:37 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:37 INFO - 1500393337221 Marionette TRACE 372 <- [1,6,null,{}]
08:55:37 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:37 INFO - 1500393337245 Marionette DEBUG Closed connection 372
08:55:37 INFO - 1500393337247 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
08:55:37 INFO - 1500393337268 Marionette DEBUG Closed connection 373
08:55:37 INFO - 1500393337270 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
08:55:37 INFO - 1500393337274 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:37 INFO - 1500393337280 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:37 INFO - 1500393337332 Marionette DEBUG Register listener.js for window 15
08:55:37 INFO - 1500393337352 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6348a75e-583a-4156-9eff-9c40342b2c91","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:37 INFO - 1500393337373 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
08:55:37 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:37 INFO - 1500393337379 Marionette TRACE 374 <- [1,2,null,{}]
08:55:37 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:37 INFO - 1500393337405 Marionette DEBUG Closed connection 374
08:55:37 INFO - 1500393337408 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
08:55:37 INFO - 1500393337412 Marionette DEBUG Closed connection 375
08:55:37 INFO - 1500393337414 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
08:55:37 INFO - 1500393337420 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:37 INFO - 1500393337424 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:37 INFO - 1500393337472 Marionette DEBUG Register listener.js for window 15
08:55:37 INFO - 1500393337489 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2e451780-7a93-40c0-9e72-d9c38e3952fe","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:37 INFO - 1500393337511 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"}]
08:55:37 INFO - 1500393337517 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"
08:55:37 INFO - 1500393337528 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"
08:55:37 INFO - 1500393337529 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"
08:55:37 INFO - ++DOMWINDOW == 26 (000000C34C12A000) [pid = 3604] [serial = 44] [outer = 000000C3525DA800]
08:55:37 INFO - 1500393337592 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"
08:55:37 INFO - 1500393337602 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"
08:55:37 INFO - 1500393337622 Marionette TRACE 376 <- [1,2,null,{}]
08:55:37 INFO - 1500393337633 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:55:37 INFO - 1500393337647 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c429c304-43a0-46de-b4be-a23defbbfbba","ELEMENT":"c429c304-43a0-46de-b4be-a23defbbfbba"}}]
08:55:37 INFO - 1500393337657 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c429c304-43a0-46de-b4be-a23defbbfbba"}]
08:55:38 INFO - 1500393338184 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:55:38 INFO - 1500393338187 Marionette TRACE 376 <- [1,4,null,{}]
08:55:38 INFO - 1500393338190 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}]
08:55:38 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500393338203 Marionette TRACE 376 <- [1,5,null,{"value":null}]
08:55:38 INFO - 1500393338207 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338213 Marionette TRACE 376 <- [1,6,null,{}]
08:55:38 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1243ms
08:55:38 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338268 Marionette DEBUG Closed connection 376
08:55:38 INFO - 1500393338271 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
08:55:38 INFO - 1500393338309 Marionette DEBUG Closed connection 377
08:55:38 INFO - 1500393338312 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
08:55:38 INFO - 1500393338324 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:38 INFO - 1500393338328 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:38 INFO - 1500393338377 Marionette DEBUG Register listener.js for window 15
08:55:38 INFO - 1500393338393 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9b82d1f3-a9bb-4198-80a4-97421ba67759","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:38 INFO - 1500393338402 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
08:55:38 INFO - 1500393338404 Marionette TRACE 378 <- [1,2,null,{}]
08:55:38 INFO - 1500393338406 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:38 INFO - 1500393338408 Marionette TRACE 378 <- [1,3,null,{}]
08:55:38 INFO - 1500393338410 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:38 INFO - 1500393338412 Marionette TRACE 378 <- [1,4,null,{}]
08:55:38 INFO - 1500393338415 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}]
08:55:38 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500393338437 Marionette TRACE 378 <- [1,5,null,{"value":null}]
08:55:38 INFO - 1500393338445 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338450 Marionette TRACE 378 <- [1,6,null,{}]
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338472 Marionette DEBUG Closed connection 378
08:55:38 INFO - 1500393338475 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
08:55:38 INFO - 1500393338521 Marionette DEBUG Closed connection 379
08:55:38 INFO - 1500393338523 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
08:55:38 INFO - 1500393338549 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:38 INFO - 1500393338555 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:38 INFO - 1500393338605 Marionette DEBUG Register listener.js for window 15
08:55:38 INFO - 1500393338624 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0f9b4de3-b40f-4830-8001-d5518e97d895","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:38 INFO - 1500393338637 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338642 Marionette TRACE 380 <- [1,2,null,{}]
08:55:38 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:38 INFO - 1500393338660 Marionette DEBUG Closed connection 380
08:55:38 INFO - 1500393338662 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
08:55:38 INFO - 1500393338682 Marionette DEBUG Closed connection 381
08:55:38 INFO - 1500393338684 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
08:55:38 INFO - 1500393338688 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:38 INFO - 1500393338691 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:38 INFO - 1500393338741 Marionette DEBUG Register listener.js for window 15
08:55:38 INFO - 1500393338756 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ccbe8050-eda1-4432-9c35-4c2c9766cda7","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:38 INFO - 1500393338786 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"}]
08:55:38 INFO - 1500393338791 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"
08:55:38 INFO - 1500393338801 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"
08:55:38 INFO - 1500393338802 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"
08:55:38 INFO - ++DOMWINDOW == 27 (000000C359429800) [pid = 3604] [serial = 45] [outer = 000000C3525DA800]
08:55:38 INFO - 1500393338870 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"
08:55:38 INFO - 1500393338877 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"
08:55:38 INFO - 1500393338898 Marionette TRACE 382 <- [1,2,null,{}]
08:55:38 INFO - 1500393338908 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:55:38 INFO - 1500393338919 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ea08d79-adee-4c8a-8641-3b6b4d96d78c","ELEMENT":"2ea08d79-adee-4c8a-8641-3b6b4d96d78c"}}]
08:55:38 INFO - 1500393338921 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2ea08d79-adee-4c8a-8641-3b6b4d96d78c"}]
08:55:39 INFO - 1500393339455 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:55:39 INFO - 1500393339458 Marionette TRACE 382 <- [1,4,null,{}]
08:55:39 INFO - 1500393339615 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}]
08:55:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500393339630 Marionette TRACE 382 <- [1,5,null,{"value":null}]
08:55:39 INFO - 1500393339636 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
08:55:39 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1435ms
08:55:39 INFO - TEST-START | test_click.py TestClick.test_input_file
08:55:39 INFO - 1500393339648 Marionette TRACE 382 <- [1,6,null,{}]
08:55:39 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:39 INFO - 1500393339674 Marionette DEBUG Closed connection 382
08:55:39 INFO - 1500393339677 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
08:55:39 INFO - 1500393339680 Marionette DEBUG Closed connection 383
08:55:39 INFO - 1500393339683 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
08:55:39 INFO - 1500393339687 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:39 INFO - 1500393339699 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:39 INFO - 1500393339727 Marionette DEBUG Register listener.js for window 15
08:55:39 INFO - 1500393339760 Marionette TRACE 384 <- [1,1,null,{"sessionId":"1493b59a-d812-4e76-a750-0dc67c6e3893","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:39 INFO - 1500393339781 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
08:55:39 INFO - 1500393339784 Marionette TRACE 384 <- [1,2,null,{}]
08:55:39 INFO - 1500393339817 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:39 INFO - 1500393339820 Marionette TRACE 384 <- [1,3,null,{}]
08:55:39 INFO - 1500393339828 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:39 INFO - 1500393339831 Marionette TRACE 384 <- [1,4,null,{}]
08:55:39 INFO - 1500393339886 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}]
08:55:39 INFO - TEST-START: test_click.py TestClick.test_input_file1500393339898 Marionette TRACE 384 <- [1,5,null,{"value":null}]
08:55:39 INFO - 1500393339903 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
08:55:39 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:39 INFO - 1500393339909 Marionette TRACE 384 <- [1,6,null,{}]
08:55:39 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:39 INFO - 1500393339933 Marionette DEBUG Closed connection 384
08:55:39 INFO - 1500393339936 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
08:55:39 INFO - 1500393339939 Marionette DEBUG Closed connection 385
08:55:39 INFO - 1500393339941 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
08:55:39 INFO - 1500393339954 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:39 INFO - 1500393339957 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:39 INFO - 1500393339985 Marionette DEBUG Register listener.js for window 15
08:55:40 INFO - 1500393340002 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2f041857-ad9b-496d-9ba2-7b36d5fca5b6","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:40 INFO - 1500393340017 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340023 Marionette TRACE 386 <- [1,2,null,{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340032 Marionette DEBUG Closed connection 386
08:55:40 INFO - 1500393340035 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
08:55:40 INFO - 1500393340056 Marionette DEBUG Closed connection 387
08:55:40 INFO - 1500393340058 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
08:55:40 INFO - 1500393340066 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:40 INFO - 1500393340072 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:40 INFO - 1500393340098 Marionette DEBUG Register listener.js for window 15
08:55:40 INFO - 1500393340114 Marionette TRACE 388 <- [1,1,null,{"sessionId":"87affb96-550c-4ff6-ad30-8a4c16a2dac3","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:40 INFO - 1500393340127 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:55:40 INFO - 1500393340132 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"
08:55:40 INFO - 1500393340143 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"
08:55:40 INFO - 1500393340145 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"
08:55:40 INFO - ++DOMWINDOW == 28 (000000C34682E800) [pid = 3604] [serial = 46] [outer = 000000C3525DA800]
08:55:40 INFO - 1500393340207 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:55:40 INFO - 1500393340214 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:55:40 INFO - 1500393340232 Marionette TRACE 388 <- [1,2,null,{}]
08:55:40 INFO - 1500393340243 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:55:40 INFO - 1500393340253 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eeb6656d-a53a-4b41-89ef-0a846927f68e","ELEMENT":"eeb6656d-a53a-4b41-89ef-0a846927f68e"}}]
08:55:40 INFO - 1500393340255 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"eeb6656d-a53a-4b41-89ef-0a846927f68e"}]
08:55:40 INFO - 1500393340397 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:55:40 INFO - --DOMWINDOW == 27 (000000C352FEC800) [pid = 3604] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:55:40 INFO - --DOMWINDOW == 26 (000000C34E193000) [pid = 3604] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:55:40 INFO - --DOMWINDOW == 25 (000000C3466BF000) [pid = 3604] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:55:40 INFO - --DOMWINDOW == 24 (000000C3568C2800) [pid = 3604] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:55:40 INFO - --DOMWINDOW == 23 (000000C358656000) [pid = 3604] [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%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]
08:55:40 INFO - --DOMWINDOW == 22 (000000C356B51000) [pid = 3604] [serial = 32] [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]
08:55:40 INFO - --DOMWINDOW == 21 (000000C359507000) [pid = 3604] [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%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]
08:55:40 INFO - 1500393340424 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}]
08:55:40 INFO - TEST-END: test_click.py TestClick.test_input_file1500393340432 Marionette TRACE 388 <- [1,5,null,{"value":null}]
08:55:40 INFO - 1500393340434 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340439 Marionette TRACE 388 <- [1,6,null,{}]
08:55:40 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 790ms
08:55:40 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340463 Marionette DEBUG Closed connection 388
08:55:40 INFO - 1500393340464 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
08:55:40 INFO - 1500393340466 Marionette DEBUG Closed connection 389
08:55:40 INFO - 1500393340469 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
08:55:40 INFO - 1500393340471 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:40 INFO - 1500393340474 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:40 INFO - 1500393340498 Marionette DEBUG Register listener.js for window 15
08:55:40 INFO - 1500393340513 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f3e733f6-291a-4567-b25c-872794f15752","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:40 INFO - 1500393340528 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
08:55:40 INFO - 1500393340530 Marionette TRACE 390 <- [1,2,null,{}]
08:55:40 INFO - 1500393340635 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:40 INFO - 1500393340637 Marionette TRACE 390 <- [1,3,null,{}]
08:55:40 INFO - 1500393340640 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:40 INFO - 1500393340642 Marionette TRACE 390 <- [1,4,null,{}]
08:55:40 INFO - 1500393340646 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}]
08:55:40 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500393340655 Marionette TRACE 390 <- [1,5,null,{"value":null}]
08:55:40 INFO - 1500393340659 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340663 Marionette TRACE 390 <- [1,6,null,{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340686 Marionette DEBUG Closed connection 390
08:55:40 INFO - 1500393340687 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
08:55:40 INFO - 1500393340692 Marionette DEBUG Closed connection 391
08:55:40 INFO - 1500393340693 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
08:55:40 INFO - 1500393340696 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:40 INFO - 1500393340701 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:40 INFO - 1500393340725 Marionette DEBUG Register listener.js for window 15
08:55:40 INFO - 1500393340741 Marionette TRACE 392 <- [1,1,null,{"sessionId":"432045a6-86e6-40cb-a948-2688746a0c38","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:40 INFO - 1500393340755 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340761 Marionette TRACE 392 <- [1,2,null,{}]
08:55:40 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:40 INFO - 1500393340785 Marionette DEBUG Closed connection 392
08:55:40 INFO - 1500393340790 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
08:55:40 INFO - 1500393340793 Marionette DEBUG Closed connection 393
08:55:40 INFO - 1500393340794 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
08:55:40 INFO - 1500393340797 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:40 INFO - 1500393340799 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:40 INFO - 1500393340823 Marionette DEBUG Register listener.js for window 15
08:55:40 INFO - 1500393340842 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4be2b278-8fe8-4ee8-8efb-89f701ffa514","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:41 INFO - 1500393341575 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"}]
08:55:41 INFO - 1500393341582 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:55:41 INFO - 1500393341591 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:55:41 INFO - 1500393341591 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:55:41 INFO - ++DOMWINDOW == 22 (000000C34E9B6800) [pid = 3604] [serial = 47] [outer = 000000C3525DA800]
08:55:41 INFO - 1500393341697 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"
08:55:41 INFO - 1500393341703 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"
08:55:41 INFO - 1500393341715 Marionette TRACE 394 <- [1,2,null,{}]
08:55:41 INFO - 1500393341769 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:55:41 INFO - 1500393341777 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63bf0aee-80ca-4e7a-9a89-221622cd5f7c","ELEMENT":"63bf0aee-80ca-4e7a-9a89-221622cd5f7c"}}]
08:55:41 INFO - 1500393341779 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:55:41 INFO - 1500393341786 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"08cdadb2-238f-4139-b4a0-20d2f6629a9a","ELEMENT":"08cdadb2-238f-4139-b4a0-20d2f6629a9a"}}]
08:55:41 INFO - 1500393341836 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"63bf0aee-80ca-4e7a-9a89-221622cd5f7c"}]
08:55:42 INFO - 1500393342294 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:55:42 INFO - 1500393342298 Marionette TRACE 394 <- [1,5,null,{}]
08:55:42 INFO - 1500393342300 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"08cdadb2-238f-4139-b4a0-20d2f6629a9a"}]
08:55:42 INFO - 1500393342405 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:55:42 INFO - 1500393342432 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
08:55:42 INFO - 1500393342441 Marionette TRACE 394 <- [1,7,null,{"value":false}]
08:55:42 INFO - 1500393342444 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}]
08:55:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500393342452 Marionette TRACE 394 <- [1,8,null,{"value":null}]
08:55:42 INFO - 1500393342454 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342459 Marionette TRACE 394 <- [1,9,null,{}]
08:55:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2019ms
08:55:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342483 Marionette DEBUG Closed connection 394
08:55:42 INFO - 1500393342484 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
08:55:42 INFO - 1500393342486 Marionette DEBUG Closed connection 395
08:55:42 INFO - 1500393342487 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
08:55:42 INFO - 1500393342490 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:42 INFO - 1500393342492 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:42 INFO - 1500393342516 Marionette DEBUG Register listener.js for window 15
08:55:42 INFO - 1500393342528 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fcdc8691-8527-4939-9e26-9f80628abbd2","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:42 INFO - 1500393342534 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
08:55:42 INFO - 1500393342536 Marionette TRACE 396 <- [1,2,null,{}]
08:55:42 INFO - 1500393342538 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:55:42 INFO - 1500393342539 Marionette TRACE 396 <- [1,3,null,{}]
08:55:42 INFO - 1500393342541 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
08:55:42 INFO - 1500393342543 Marionette TRACE 396 <- [1,4,null,{}]
08:55:42 INFO - 1500393342545 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}]
08:55:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500393342553 Marionette TRACE 396 <- [1,5,null,{"value":null}]
08:55:42 INFO - 1500393342555 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342559 Marionette TRACE 396 <- [1,6,null,{}]
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342565 Marionette DEBUG Closed connection 396
08:55:42 INFO - 1500393342566 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
08:55:42 INFO - 1500393342568 Marionette DEBUG Closed connection 397
08:55:42 INFO - 1500393342571 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
08:55:42 INFO - 1500393342573 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:55:42 INFO - 1500393342576 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:42 INFO - 1500393342601 Marionette DEBUG Register listener.js for window 15
08:55:42 INFO - 1500393342625 Marionette TRACE 398 <- [1,1,null,{"sessionId":"495a1d8e-403b-4d0e-bc88-bbe10c0596d6","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:42 INFO - 1500393342633 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342638 Marionette TRACE 398 <- [1,2,null,{}]
08:55:42 INFO - [Parent 3604] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:55:42 INFO - 1500393342644 Marionette DEBUG Closed connection 398
08:55:42 INFO - 1500393342645 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
08:55:42 INFO - 1500393342647 Marionette DEBUG Closed connection 399
08:55:42 INFO - 1500393342648 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
08:55:42 INFO - 1500393342651 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:55:42 INFO - 1500393342653 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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}
08:55:42 INFO - 1500393342678 Marionette DEBUG Register listener.js for window 15
08:55:42 INFO - 1500393342740 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a29b4e05-c0da-4087-a716-338775fdebdb","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":3604,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-195.001\\AppData\\Local\\Temp\\tmp7vzmbx.mozrunner","moz:accessibilityChecks":false}}]
08:55:42 INFO - 1500393342747 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"}]
08:55:42 INFO - 1500393342751 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"
08:55:42 INFO - 1500393342758 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"
08:55:42 INFO - 1500393342759 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"
08:55:42 INFO - ++DOMWINDOW == 23 (000000C35767B800) [pid = 3604] [serial = 48] [outer = 000000C3525DA800]
08:55:42 INFO - 1500393342818 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"
08:55:42 INFO - 1500393342823 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"
08:55:42 INFO - 1500393342841 Marionette TRACE 400 <- [1,2,null,{}]
08:55:42 INFO - 1500393342849 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:55:42 INFO - 1500393342857 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d47e3d7-6de7-42e7-8058-328ae18a480e","ELEMENT":"2d47e3d7-6de7-42e7-8058-328ae18a480e"}}]
08:55:42 INFO - 1500393342859 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2d47e3d7-6de7-42e7-8058-328ae18a480e"}]
08:55:42 INFO - 1500393342983 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
08:55:42 INFO - 1500393342985 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2d47e3d7-6de7-42e7-8058-328ae18a480e"}]
08:55:43 INFO - 1500393343100 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element