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]
18:43:46 INFO - 1500342226648 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:43:46 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500342226655 Marionette TRACE 353 <- [1,5,null,{"value":null}]
18:43:46 INFO - 1500342226681 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226688 Marionette TRACE 353 <- [1,6,null,{}]
18:43:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 647ms
18:43:46 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226694 Marionette DEBUG Closed connection 353
18:43:46 INFO - 1500342226696 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49795
18:43:46 INFO - 1500342226698 Marionette DEBUG Closed connection 354
18:43:46 INFO - 1500342226700 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49796
18:43:46 INFO - 1500342226702 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:46 INFO - 1500342226704 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:46 INFO - 1500342226726 Marionette DEBUG Register listener.js for window 15
18:43:46 INFO - 1500342226737 Marionette TRACE 355 <- [1,1,null,{"sessionId":"27a0dac5-2b28-49ed-ba7b-3b581394b294","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:46 INFO - 1500342226767 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
18:43:46 INFO - 1500342226768 Marionette TRACE 355 <- [1,2,null,{}]
18:43:46 INFO - 1500342226770 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:46 INFO - 1500342226772 Marionette TRACE 355 <- [1,3,null,{}]
18:43:46 INFO - 1500342226774 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:46 INFO - 1500342226775 Marionette TRACE 355 <- [1,4,null,{}]
18:43:46 INFO - 1500342226777 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:43:46 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500342226785 Marionette TRACE 355 <- [1,5,null,{"value":null}]
18:43:46 INFO - 1500342226787 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226791 Marionette TRACE 355 <- [1,6,null,{}]
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226797 Marionette DEBUG Closed connection 355
18:43:46 INFO - 1500342226798 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49797
18:43:46 INFO - 1500342226800 Marionette DEBUG Closed connection 356
18:43:46 INFO - 1500342226802 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49798
18:43:46 INFO - 1500342226804 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:46 INFO - 1500342226806 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:46 INFO - 1500342226826 Marionette DEBUG Register listener.js for window 15
18:43:46 INFO - 1500342226836 Marionette TRACE 357 <- [1,1,null,{"sessionId":"c7bde056-cb8b-43c9-8a1c-4939b71ebe31","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:46 INFO - 1500342226842 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226846 Marionette TRACE 357 <- [1,2,null,{}]
18:43:46 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:46 INFO - 1500342226853 Marionette DEBUG Closed connection 357
18:43:46 INFO - 1500342226855 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49799
18:43:46 INFO - 1500342226904 Marionette DEBUG Closed connection 358
18:43:46 INFO - 1500342226906 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49800
18:43:46 INFO - 1500342226908 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:46 INFO - 1500342226911 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:46 INFO - 1500342226932 Marionette DEBUG Register listener.js for window 15
18:43:46 INFO - 1500342226942 Marionette TRACE 359 <- [1,1,null,{"sessionId":"c87724e5-cf60-4f4c-b487-92089432cefa","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:46 INFO - 1500342226948 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
18:43:46 INFO - 1500342226952 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
18:43:46 INFO - 1500342226963 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
18:43:46 INFO - 1500342226964 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
18:43:46 INFO - ++DOMWINDOW == 28 (000000D5535EA000) [pid = 3588] [serial = 40] [outer = 000000D5579DB000]
18:43:47 INFO - 1500342227019 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
18:43:47 INFO - 1500342227024 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
18:43:47 INFO - 1500342227041 Marionette TRACE 359 <- [1,2,null,{}]
18:43:47 INFO - 1500342227842 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:43:47 INFO - 1500342227848 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a331739e-2ff1-4253-8f38-2ee0d7d277ca","ELEMENT":"a331739e-2ff1-4253-8f38-2ee0d7d277ca"}}]
18:43:47 INFO - 1500342227854 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"a331739e-2ff1-4253-8f38-2ee0d7d277ca"}]
18:43:48 INFO - 1500342228153 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
18:43:48 INFO - [Parent 3588] 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
18:43:48 INFO - 1500342228175 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
18:43:48 INFO - 1500342228176 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
18:43:48 INFO - ++DOMWINDOW == 29 (000000D55C6E5000) [pid = 3588] [serial = 41] [outer = 000000D5579DB000]
18:43:48 INFO - 1500342228484 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
18:43:48 INFO - 1500342228496 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
18:43:48 INFO - 1500342228514 Marionette TRACE 359 <- [1,4,null,{}]
18:43:48 INFO - 1500342228521 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:43:48 INFO - 1500342228527 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a440d8ad-e7ac-499c-882a-60b5c5b8fb04","ELEMENT":"a440d8ad-e7ac-499c-882a-60b5c5b8fb04"}}]
18:43:48 INFO - 1500342228529 Marionette TRACE 359 -> [0,6,"getTitle",{}]
18:43:48 INFO - 1500342228530 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
18:43:48 INFO - 1500342228533 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:43:48 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500342228541 Marionette TRACE 359 <- [1,7,null,{"value":null}]
18:43:48 INFO - 1500342228550 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228554 Marionette TRACE 359 <- [1,8,null,{}]
18:43:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1866ms
18:43:48 INFO - TEST-START | test_click.py TestClick.test_container_element
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228560 Marionette DEBUG Closed connection 359
18:43:48 INFO - 1500342228562 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49803
18:43:48 INFO - 1500342228563 Marionette DEBUG Closed connection 360
18:43:48 INFO - 1500342228564 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49804
18:43:48 INFO - 1500342228566 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:48 INFO - 1500342228568 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:48 INFO - 1500342228589 Marionette DEBUG Register listener.js for window 15
18:43:48 INFO - 1500342228611 Marionette TRACE 361 <- [1,1,null,{"sessionId":"11d967ad-4ae0-4e7f-8eab-b64a68e232f7","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:48 INFO - 1500342228618 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
18:43:48 INFO - 1500342228619 Marionette TRACE 361 <- [1,2,null,{}]
18:43:48 INFO - 1500342228647 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:48 INFO - 1500342228649 Marionette TRACE 361 <- [1,3,null,{}]
18:43:48 INFO - 1500342228651 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:48 INFO - 1500342228653 Marionette TRACE 361 <- [1,4,null,{}]
18:43:48 INFO - 1500342228700 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:43:48 INFO - TEST-START: test_click.py TestClick.test_container_element1500342228707 Marionette TRACE 361 <- [1,5,null,{"value":null}]
18:43:48 INFO - 1500342228711 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228715 Marionette TRACE 361 <- [1,6,null,{}]
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228738 Marionette DEBUG Closed connection 361
18:43:48 INFO - 1500342228739 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49805
18:43:48 INFO - 1500342228826 Marionette DEBUG Closed connection 362
18:43:48 INFO - 1500342228828 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49806
18:43:48 INFO - 1500342228833 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:48 INFO - 1500342228835 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:48 INFO - 1500342228856 Marionette DEBUG Register listener.js for window 15
18:43:48 INFO - 1500342228867 Marionette TRACE 363 <- [1,1,null,{"sessionId":"f2d909ec-8b10-4fa8-bb6c-870df7a451a8","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:48 INFO - 1500342228877 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228881 Marionette TRACE 363 <- [1,2,null,{}]
18:43:48 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:48 INFO - 1500342228939 Marionette DEBUG Closed connection 363
18:43:48 INFO - 1500342228940 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49807
18:43:48 INFO - 1500342228963 Marionette DEBUG Closed connection 364
18:43:48 INFO - 1500342228965 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49808
18:43:48 INFO - 1500342228970 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:48 INFO - 1500342228973 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:48 INFO - 1500342228994 Marionette DEBUG Register listener.js for window 15
18:43:49 INFO - 1500342229005 Marionette TRACE 365 <- [1,1,null,{"sessionId":"bae39c87-cb54-4fd8-ad6a-f3abc43f6796","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:49 INFO - 1500342229011 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:43:49 INFO - 1500342229015 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
18:43:49 INFO - 1500342229023 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
18:43:49 INFO - 1500342229024 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
18:43:49 INFO - ++DOMWINDOW == 30 (000000D54BD3A800) [pid = 3588] [serial = 42] [outer = 000000D5579DB000]
18:43:49 INFO - 1500342229100 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"
18:43:49 INFO - 1500342229105 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"
18:43:49 INFO - 1500342229126 Marionette TRACE 365 <- [1,2,null,{}]
18:43:49 INFO - 1500342229129 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:43:49 INFO - 1500342229136 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3b9e03e-867c-4d68-bd51-5b6a343394d6","ELEMENT":"a3b9e03e-867c-4d68-bd51-5b6a343394d6"}}]
18:43:49 INFO - 1500342229138 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a3b9e03e-867c-4d68-bd51-5b6a343394d6"}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
18:43:49 INFO - --DOMWINDOW == 29 (000000D556942800) [pid = 3588] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 28 (000000D556244000) [pid = 3588] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 27 (000000D54F926800) [pid = 3588] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 26 (000000D56012F000) [pid = 3588] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 25 (000000D5512AC000) [pid = 3588] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 24 (000000D54BD28800) [pid = 3588] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 23 (000000D55B6EC000) [pid = 3588] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - --DOMWINDOW == 22 (000000D561E9E000) [pid = 3588] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
18:43:49 INFO - 1500342229591 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:43:49 INFO - 1500342229593 Marionette TRACE 365 <- [1,4,null,{}]
18:43:49 INFO - 1500342229595 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"a3b9e03e-867c-4d68-bd51-5b6a343394d6","name":"selected"}]
18:43:49 INFO - 1500342229624 Marionette TRACE 365 <- [1,5,null,{"value":true}]
18:43:49 INFO - 1500342229627 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:43:49 INFO - TEST-END: test_click.py TestClick.test_container_element1500342229634 Marionette TRACE 365 <- [1,6,null,{"value":null}]
18:43:49 INFO - 1500342229636 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229641 Marionette TRACE 365 <- [1,7,null,{}]
18:43:49 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1086ms
18:43:49 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229647 Marionette DEBUG Closed connection 365
18:43:49 INFO - 1500342229648 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49809
18:43:49 INFO - 1500342229650 Marionette DEBUG Closed connection 366
18:43:49 INFO - 1500342229651 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49810
18:43:49 INFO - 1500342229653 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:49 INFO - 1500342229655 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:49 INFO - 1500342229677 Marionette DEBUG Register listener.js for window 15
18:43:49 INFO - 1500342229689 Marionette TRACE 367 <- [1,1,null,{"sessionId":"fdfef49a-1eab-4b89-a813-c72015f963a7","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:49 INFO - 1500342229694 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
18:43:49 INFO - 1500342229696 Marionette TRACE 367 <- [1,2,null,{}]
18:43:49 INFO - 1500342229698 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:49 INFO - 1500342229699 Marionette TRACE 367 <- [1,3,null,{}]
18:43:49 INFO - 1500342229703 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:49 INFO - 1500342229705 Marionette TRACE 367 <- [1,4,null,{}]
18:43:49 INFO - 1500342229707 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:43:49 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500342229714 Marionette TRACE 367 <- [1,5,null,{"value":null}]
18:43:49 INFO - 1500342229716 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229721 Marionette TRACE 367 <- [1,6,null,{}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229727 Marionette DEBUG Closed connection 367
18:43:49 INFO - 1500342229728 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49811
18:43:49 INFO - 1500342229730 Marionette DEBUG Closed connection 368
18:43:49 INFO - 1500342229731 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49812
18:43:49 INFO - 1500342229733 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:49 INFO - 1500342229736 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:49 INFO - 1500342229757 Marionette DEBUG Register listener.js for window 15
18:43:49 INFO - 1500342229767 Marionette TRACE 369 <- [1,1,null,{"sessionId":"80fddbbe-5b0d-42e7-8609-cbeb5647f6b3","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:49 INFO - 1500342229773 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229777 Marionette TRACE 369 <- [1,2,null,{}]
18:43:49 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:49 INFO - 1500342229783 Marionette DEBUG Closed connection 369
18:43:49 INFO - 1500342229784 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49813
18:43:49 INFO - 1500342229786 Marionette DEBUG Closed connection 370
18:43:49 INFO - 1500342229788 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49814
18:43:49 INFO - 1500342229790 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:49 INFO - 1500342229792 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:49 INFO - 1500342229813 Marionette DEBUG Register listener.js for window 15
18:43:49 INFO - 1500342229824 Marionette TRACE 371 <- [1,1,null,{"sessionId":"f999c90b-d818-43c4-a948-3df8353eb543","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:49 INFO - 1500342229830 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:43:49 INFO - 1500342229835 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"
18:43:49 INFO - 1500342229842 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"
18:43:49 INFO - 1500342229844 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"
18:43:49 INFO - ++DOMWINDOW == 23 (000000D55612E800) [pid = 3588] [serial = 43] [outer = 000000D5579DB000]
18:43:49 INFO - 1500342229895 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"
18:43:49 INFO - 1500342229900 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"
18:43:49 INFO - 1500342229918 Marionette TRACE 371 <- [1,2,null,{}]
18:43:49 INFO - 1500342229922 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:43:49 INFO - 1500342229928 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7290f315-e8d5-4dec-b06b-7a84a7c730ce","ELEMENT":"7290f315-e8d5-4dec-b06b-7a84a7c730ce"}}]
18:43:49 INFO - 1500342229930 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"7290f315-e8d5-4dec-b06b-7a84a7c730ce"}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
18:43:50 INFO - 1500342230361 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:43:50 INFO - 1500342230363 Marionette TRACE 371 <- [1,4,null,{}]
18:43:50 INFO - 1500342230365 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"7290f315-e8d5-4dec-b06b-7a84a7c730ce","name":"selected"}]
18:43:50 INFO - 1500342230392 Marionette TRACE 371 <- [1,5,null,{"value":true}]
18:43:50 INFO - 1500342230395 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:43:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500342230402 Marionette TRACE 371 <- [1,6,null,{"value":null}]
18:43:50 INFO - 1500342230404 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230408 Marionette TRACE 371 <- [1,7,null,{}]
18:43:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 767ms
18:43:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230414 Marionette DEBUG Closed connection 371
18:43:50 INFO - 1500342230416 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49815
18:43:50 INFO - 1500342230417 Marionette DEBUG Closed connection 372
18:43:50 INFO - 1500342230418 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49816
18:43:50 INFO - 1500342230420 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:50 INFO - 1500342230422 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:50 INFO - 1500342230443 Marionette DEBUG Register listener.js for window 15
18:43:50 INFO - 1500342230454 Marionette TRACE 373 <- [1,1,null,{"sessionId":"6e4af738-3c96-4f47-99a4-198e98aeee6b","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:50 INFO - 1500342230460 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
18:43:50 INFO - 1500342230462 Marionette TRACE 373 <- [1,2,null,{}]
18:43:50 INFO - 1500342230463 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:50 INFO - 1500342230465 Marionette TRACE 373 <- [1,3,null,{}]
18:43:50 INFO - 1500342230469 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:50 INFO - 1500342230471 Marionette TRACE 373 <- [1,4,null,{}]
18:43:50 INFO - 1500342230473 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:43:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500342230480 Marionette TRACE 373 <- [1,5,null,{"value":null}]
18:43:50 INFO - 1500342230482 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230486 Marionette TRACE 373 <- [1,6,null,{}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230492 Marionette DEBUG Closed connection 373
18:43:50 INFO - 1500342230494 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49817
18:43:50 INFO - 1500342230495 Marionette DEBUG Closed connection 374
18:43:50 INFO - 1500342230497 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49818
18:43:50 INFO - 1500342230499 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:50 INFO - 1500342230501 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:50 INFO - 1500342230522 Marionette DEBUG Register listener.js for window 15
18:43:50 INFO - 1500342230534 Marionette TRACE 375 <- [1,1,null,{"sessionId":"8e872245-6fbb-4189-8c57-d323b38ff559","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:50 INFO - 1500342230540 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230543 Marionette TRACE 375 <- [1,2,null,{}]
18:43:50 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:50 INFO - 1500342230550 Marionette DEBUG Closed connection 375
18:43:50 INFO - 1500342230565 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49819
18:43:50 INFO - 1500342230567 Marionette DEBUG Closed connection 376
18:43:50 INFO - 1500342230568 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49820
18:43:50 INFO - 1500342230570 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:50 INFO - 1500342230573 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:50 INFO - 1500342230594 Marionette DEBUG Register listener.js for window 15
18:43:50 INFO - 1500342230604 Marionette TRACE 377 <- [1,1,null,{"sessionId":"44ee592d-6286-4b54-bb94-8e97322adb89","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:50 INFO - 1500342230610 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:43:50 INFO - 1500342230614 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"
18:43:50 INFO - 1500342230620 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"
18:43:50 INFO - 1500342230621 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"
18:43:50 INFO - ++DOMWINDOW == 24 (000000D5575D9000) [pid = 3588] [serial = 44] [outer = 000000D5579DB000]
18:43:50 INFO - 1500342230678 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"
18:43:50 INFO - 1500342230684 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"
18:43:50 INFO - 1500342230700 Marionette TRACE 377 <- [1,2,null,{}]
18:43:50 INFO - 1500342230705 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:43:50 INFO - 1500342230711 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5da4d2b9-491c-4617-95ce-be836a509eff","ELEMENT":"5da4d2b9-491c-4617-95ce-be836a509eff"}}]
18:43:50 INFO - 1500342230713 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"5da4d2b9-491c-4617-95ce-be836a509eff"}]
18:43:51 INFO - 1500342231156 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:43:51 INFO - 1500342231158 Marionette TRACE 377 <- [1,4,null,{}]
18:43:51 INFO - 1500342231161 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:43:51 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500342231168 Marionette TRACE 377 <- [1,5,null,{"value":null}]
18:43:51 INFO - 1500342231170 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231174 Marionette TRACE 377 <- [1,6,null,{}]
18:43:51 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 765ms
18:43:51 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231180 Marionette DEBUG Closed connection 377
18:43:51 INFO - 1500342231181 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49821
18:43:51 INFO - 1500342231183 Marionette DEBUG Closed connection 378
18:43:51 INFO - 1500342231184 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49822
18:43:51 INFO - 1500342231186 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:51 INFO - 1500342231188 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:51 INFO - 1500342231210 Marionette DEBUG Register listener.js for window 15
18:43:51 INFO - 1500342231220 Marionette TRACE 379 <- [1,1,null,{"sessionId":"6f48e25a-0b74-426d-bbfd-99201f4950d3","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:51 INFO - 1500342231227 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
18:43:51 INFO - 1500342231229 Marionette TRACE 379 <- [1,2,null,{}]
18:43:51 INFO - 1500342231230 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:51 INFO - 1500342231232 Marionette TRACE 379 <- [1,3,null,{}]
18:43:51 INFO - 1500342231233 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:51 INFO - 1500342231235 Marionette TRACE 379 <- [1,4,null,{}]
18:43:51 INFO - 1500342231238 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:43:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500342231245 Marionette TRACE 379 <- [1,5,null,{"value":null}]
18:43:51 INFO - 1500342231246 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231250 Marionette TRACE 379 <- [1,6,null,{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231258 Marionette DEBUG Closed connection 379
18:43:51 INFO - 1500342231260 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49823
18:43:51 INFO - 1500342231261 Marionette DEBUG Closed connection 380
18:43:51 INFO - 1500342231263 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49824
18:43:51 INFO - 1500342231265 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:51 INFO - 1500342231267 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:51 INFO - 1500342231289 Marionette DEBUG Register listener.js for window 15
18:43:51 INFO - 1500342231299 Marionette TRACE 381 <- [1,1,null,{"sessionId":"ef076939-4fa8-42c1-850e-240a88ed5267","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:51 INFO - 1500342231305 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231309 Marionette TRACE 381 <- [1,2,null,{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231315 Marionette DEBUG Closed connection 381
18:43:51 INFO - 1500342231316 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49825
18:43:51 INFO - 1500342231318 Marionette DEBUG Closed connection 382
18:43:51 INFO - 1500342231319 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49826
18:43:51 INFO - 1500342231321 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:51 INFO - 1500342231324 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:51 INFO - 1500342231346 Marionette DEBUG Register listener.js for window 15
18:43:51 INFO - 1500342231357 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d628e2d4-1c21-487d-99be-5e5e8a83bf43","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:51 INFO - 1500342231363 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:43:51 INFO - 1500342231366 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"
18:43:51 INFO - 1500342231375 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"
18:43:51 INFO - 1500342231376 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"
18:43:51 INFO - ++DOMWINDOW == 25 (000000D5579CF000) [pid = 3588] [serial = 45] [outer = 000000D5579DB000]
18:43:51 INFO - 1500342231426 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"
18:43:51 INFO - 1500342231430 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"
18:43:51 INFO - 1500342231439 Marionette TRACE 383 <- [1,2,null,{}]
18:43:51 INFO - 1500342231452 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:43:51 INFO - 1500342231458 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4971f55c-d57d-426d-a41a-347b11e22227","ELEMENT":"4971f55c-d57d-426d-a41a-347b11e22227"}}]
18:43:51 INFO - 1500342231460 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"4971f55c-d57d-426d-a41a-347b11e22227"}]
18:43:51 INFO - 1500342231888 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:43:51 INFO - 1500342231890 Marionette TRACE 383 <- [1,4,null,{}]
18:43:51 INFO - 1500342231892 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:43:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500342231899 Marionette TRACE 383 <- [1,5,null,{"value":null}]
18:43:51 INFO - 1500342231901 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231905 Marionette TRACE 383 <- [1,6,null,{}]
18:43:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 732ms
18:43:51 INFO - TEST-START | test_click.py TestClick.test_input_file
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231911 Marionette DEBUG Closed connection 383
18:43:51 INFO - 1500342231912 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49827
18:43:51 INFO - 1500342231914 Marionette DEBUG Closed connection 384
18:43:51 INFO - 1500342231915 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49828
18:43:51 INFO - 1500342231918 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:51 INFO - 1500342231920 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:51 INFO - 1500342231942 Marionette DEBUG Register listener.js for window 15
18:43:51 INFO - 1500342231964 Marionette TRACE 385 <- [1,1,null,{"sessionId":"eedea7bc-281b-4729-a192-717f066c6f72","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:51 INFO - 1500342231970 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
18:43:51 INFO - 1500342231972 Marionette TRACE 385 <- [1,2,null,{}]
18:43:51 INFO - 1500342231974 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:51 INFO - 1500342231975 Marionette TRACE 385 <- [1,3,null,{}]
18:43:51 INFO - 1500342231977 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:51 INFO - 1500342231978 Marionette TRACE 385 <- [1,4,null,{}]
18:43:51 INFO - 1500342231981 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:43:51 INFO - TEST-START: test_click.py TestClick.test_input_file1500342231988 Marionette TRACE 385 <- [1,5,null,{"value":null}]
18:43:51 INFO - 1500342231990 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
18:43:51 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:51 INFO - 1500342231994 Marionette TRACE 385 <- [1,6,null,{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232003 Marionette DEBUG Closed connection 385
18:43:52 INFO - 1500342232004 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49829
18:43:52 INFO - 1500342232008 Marionette DEBUG Closed connection 386
18:43:52 INFO - 1500342232009 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49830
18:43:52 INFO - 1500342232011 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:52 INFO - 1500342232013 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:52 INFO - 1500342232035 Marionette DEBUG Register listener.js for window 15
18:43:52 INFO - 1500342232046 Marionette TRACE 387 <- [1,1,null,{"sessionId":"8daeb534-5ddd-4e2c-9c7d-b1d41f86791d","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:52 INFO - 1500342232052 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232056 Marionette TRACE 387 <- [1,2,null,{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232061 Marionette DEBUG Closed connection 387
18:43:52 INFO - 1500342232063 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49831
18:43:52 INFO - 1500342232064 Marionette DEBUG Closed connection 388
18:43:52 INFO - 1500342232066 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49832
18:43:52 INFO - 1500342232068 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:52 INFO - 1500342232070 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:52 INFO - 1500342232092 Marionette DEBUG Register listener.js for window 15
18:43:52 INFO - 1500342232103 Marionette TRACE 389 <- [1,1,null,{"sessionId":"e64c9b1b-ae2c-445a-a0be-4d2e00b87828","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:52 INFO - 1500342232109 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:43:52 INFO - 1500342232112 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"
18:43:52 INFO - 1500342232118 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"
18:43:52 INFO - 1500342232119 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"
18:43:52 INFO - ++DOMWINDOW == 26 (000000D55B50C000) [pid = 3588] [serial = 46] [outer = 000000D5579DB000]
18:43:52 INFO - 1500342232164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:43:52 INFO - 1500342232177 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:43:52 INFO - 1500342232186 Marionette TRACE 389 <- [1,2,null,{}]
18:43:52 INFO - 1500342232194 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:43:52 INFO - 1500342232202 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8867e885-fe5a-4b5a-8853-2982bee0041b","ELEMENT":"8867e885-fe5a-4b5a-8853-2982bee0041b"}}]
18:43:52 INFO - 1500342232204 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"8867e885-fe5a-4b5a-8853-2982bee0041b"}]
18:43:52 INFO - 1500342232356 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:43:52 INFO - 1500342232359 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:43:52 INFO - TEST-END: test_click.py TestClick.test_input_file1500342232366 Marionette TRACE 389 <- [1,5,null,{"value":null}]
18:43:52 INFO - 1500342232368 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232372 Marionette TRACE 389 <- [1,6,null,{}]
18:43:52 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 477ms
18:43:52 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232390 Marionette DEBUG Closed connection 389
18:43:52 INFO - 1500342232391 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49833
18:43:52 INFO - 1500342232393 Marionette DEBUG Closed connection 390
18:43:52 INFO - 1500342232394 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49834
18:43:52 INFO - 1500342232396 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:52 INFO - 1500342232398 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:52 INFO - 1500342232420 Marionette DEBUG Register listener.js for window 15
18:43:52 INFO - 1500342232431 Marionette TRACE 391 <- [1,1,null,{"sessionId":"ee3617d5-16b8-44fe-afed-75d9f3aa92d1","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:52 INFO - 1500342232437 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
18:43:52 INFO - 1500342232438 Marionette TRACE 391 <- [1,2,null,{}]
18:43:52 INFO - 1500342232440 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:52 INFO - 1500342232441 Marionette TRACE 391 <- [1,3,null,{}]
18:43:52 INFO - 1500342232443 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:52 INFO - 1500342232444 Marionette TRACE 391 <- [1,4,null,{}]
18:43:52 INFO - 1500342232447 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:43:52 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500342232454 Marionette TRACE 391 <- [1,5,null,{"value":null}]
18:43:52 INFO - 1500342232456 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232460 Marionette TRACE 391 <- [1,6,null,{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232466 Marionette DEBUG Closed connection 391
18:43:52 INFO - 1500342232467 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49835
18:43:52 INFO - 1500342232469 Marionette DEBUG Closed connection 392
18:43:52 INFO - 1500342232470 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49836
18:43:52 INFO - 1500342232472 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:52 INFO - 1500342232474 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:52 INFO - 1500342232496 Marionette DEBUG Register listener.js for window 15
18:43:52 INFO - 1500342232507 Marionette TRACE 393 <- [1,1,null,{"sessionId":"e4faee0c-fc1d-48f9-86e0-d181b894c58c","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:52 INFO - 1500342232513 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232517 Marionette TRACE 393 <- [1,2,null,{}]
18:43:52 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:52 INFO - 1500342232522 Marionette DEBUG Closed connection 393
18:43:52 INFO - 1500342232524 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49837
18:43:52 INFO - 1500342232525 Marionette DEBUG Closed connection 394
18:43:52 INFO - 1500342232526 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49838
18:43:52 INFO - 1500342232529 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:52 INFO - 1500342232531 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:52 INFO - 1500342232553 Marionette DEBUG Register listener.js for window 15
18:43:52 INFO - 1500342232563 Marionette TRACE 395 <- [1,1,null,{"sessionId":"47b3e745-5bd3-4d17-8707-bed24d50b59a","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:52 INFO - 1500342232569 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:43:52 INFO - 1500342232573 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:43:52 INFO - 1500342232580 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:43:52 INFO - 1500342232581 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:43:52 INFO - ++DOMWINDOW == 27 (000000D55C70D800) [pid = 3588] [serial = 47] [outer = 000000D5579DB000]
18:43:52 INFO - 1500342232639 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"
18:43:52 INFO - 1500342232644 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"
18:43:52 INFO - 1500342232651 Marionette TRACE 395 <- [1,2,null,{}]
18:43:52 INFO - 1500342232660 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:43:52 INFO - 1500342232668 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fd494515-061a-4adc-b1a2-53ed7aafb6a4","ELEMENT":"fd494515-061a-4adc-b1a2-53ed7aafb6a4"}}]
18:43:52 INFO - 1500342232670 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:43:52 INFO - 1500342232676 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4051cdda-41b6-4b6d-892a-2604361210f9","ELEMENT":"4051cdda-41b6-4b6d-892a-2604361210f9"}}]
18:43:52 INFO - 1500342232677 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"fd494515-061a-4adc-b1a2-53ed7aafb6a4"}]
18:43:53 INFO - 1500342233116 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:43:53 INFO - 1500342233118 Marionette TRACE 395 <- [1,5,null,{}]
18:43:53 INFO - 1500342233120 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"4051cdda-41b6-4b6d-892a-2604361210f9"}]
18:43:53 INFO - 1500342233248 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:43:53 INFO - 1500342233251 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:43:53 INFO - 1500342233258 Marionette TRACE 395 <- [1,7,null,{"value":false}]
18:43:53 INFO - 1500342233260 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:43:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500342233267 Marionette TRACE 395 <- [1,8,null,{"value":null}]
18:43:53 INFO - 1500342233268 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233273 Marionette TRACE 395 <- [1,9,null,{}]
18:43:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 890ms
18:43:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233279 Marionette DEBUG Closed connection 395
18:43:53 INFO - 1500342233280 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49839
18:43:53 INFO - 1500342233281 Marionette DEBUG Closed connection 396
18:43:53 INFO - 1500342233283 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49840
18:43:53 INFO - 1500342233285 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:53 INFO - 1500342233287 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:53 INFO - 1500342233309 Marionette DEBUG Register listener.js for window 15
18:43:53 INFO - 1500342233322 Marionette TRACE 397 <- [1,1,null,{"sessionId":"be19f26d-87fe-41e3-9cc2-1ba3347e3d7b","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:53 INFO - 1500342233328 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
18:43:53 INFO - 1500342233330 Marionette TRACE 397 <- [1,2,null,{}]
18:43:53 INFO - 1500342233332 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:43:53 INFO - 1500342233333 Marionette TRACE 397 <- [1,3,null,{}]
18:43:53 INFO - 1500342233335 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
18:43:53 INFO - 1500342233336 Marionette TRACE 397 <- [1,4,null,{}]
18:43:53 INFO - 1500342233339 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:43:53 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500342233346 Marionette TRACE 397 <- [1,5,null,{"value":null}]
18:43:53 INFO - 1500342233348 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233352 Marionette TRACE 397 <- [1,6,null,{}]
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233358 Marionette DEBUG Closed connection 397
18:43:53 INFO - 1500342233359 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49841
18:43:53 INFO - 1500342233361 Marionette DEBUG Closed connection 398
18:43:53 INFO - 1500342233362 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49842
18:43:53 INFO - 1500342233364 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:43:53 INFO - 1500342233366 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:53 INFO - 1500342233389 Marionette DEBUG Register listener.js for window 15
18:43:53 INFO - 1500342233399 Marionette TRACE 399 <- [1,1,null,{"sessionId":"ab556447-9b85-4eb7-b297-485030cf3296","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:53 INFO - 1500342233405 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233409 Marionette TRACE 399 <- [1,2,null,{}]
18:43:53 INFO - [Parent 3588] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:43:53 INFO - 1500342233414 Marionette DEBUG Closed connection 399
18:43:53 INFO - 1500342233416 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49843
18:43:53 INFO - 1500342233417 Marionette DEBUG Closed connection 400
18:43:53 INFO - 1500342233419 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49844
18:43:53 INFO - 1500342233421 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:43:53 INFO - 1500342233423 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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}
18:43:53 INFO - 1500342233446 Marionette DEBUG Register listener.js for window 15
18:43:53 INFO - 1500342233456 Marionette TRACE 401 <- [1,1,null,{"sessionId":"37ea6917-4507-4b0b-a85e-2aa485400442","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":3588,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpnhispa.mozrunner","moz:accessibilityChecks":false}}]
18:43:53 INFO - 1500342233462 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:43:53 INFO - 1500342233465 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"
18:43:53 INFO - 1500342233471 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"
18:43:53 INFO - 1500342233472 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"
18:43:53 INFO - ++DOMWINDOW == 28 (000000D560458800) [pid = 3588] [serial = 48] [outer = 000000D5579DB000]
18:43:53 INFO - 1500342233539 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"
18:43:53 INFO - 1500342233544 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"
18:43:53 INFO - 1500342233551 Marionette TRACE 401 <- [1,2,null,{}]
18:43:53 INFO - 1500342233560 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:43:53 INFO - 1500342233568 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09599de1-eb89-4e0c-af7a-8ef2c8f552a4","ELEMENT":"09599de1-eb89-4e0c-af7a-8ef2c8f552a4"}}]
18:43:53 INFO - 1500342233570 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"09599de1-eb89-4e0c-af7a-8ef2c8f552a4"}]
18:43:53 INFO - 1500342233710 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
18:43:53 INFO - 1500342233714 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"09599de1-eb89-4e0c-af7a-8ef2c8f552a4"}]
18:43:53 INFO - 1500342233849 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element