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:32:09 INFO - 1500082329685 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:32:09 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500082329692 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:32:09 INFO - 1500082329694 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329698 Marionette TRACE 352 <- [1,6,null,{}]
18:32:09 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 636ms
18:32:09 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329703 Marionette DEBUG Closed connection 352
18:32:09 INFO - 1500082329705 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49786
18:32:09 INFO - 1500082329707 Marionette DEBUG Closed connection 353
18:32:09 INFO - 1500082329708 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49787
18:32:09 INFO - 1500082329710 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:09 INFO - 1500082329712 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:09 INFO - 1500082329734 Marionette DEBUG Register listener.js for window 15
18:32:09 INFO - 1500082329745 Marionette TRACE 354 <- [1,1,null,{"sessionId":"85dd77b2-4940-4a01-94fd-4642f13948e6","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:09 INFO - 1500082329775 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:32:09 INFO - 1500082329777 Marionette TRACE 354 <- [1,2,null,{}]
18:32:09 INFO - 1500082329779 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:09 INFO - 1500082329781 Marionette TRACE 354 <- [1,3,null,{}]
18:32:09 INFO - 1500082329783 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:09 INFO - 1500082329784 Marionette TRACE 354 <- [1,4,null,{}]
18:32:09 INFO - 1500082329787 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:32:09 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500082329797 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:32:09 INFO - 1500082329799 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329803 Marionette TRACE 354 <- [1,6,null,{}]
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329810 Marionette DEBUG Closed connection 354
18:32:09 INFO - 1500082329812 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49788
18:32:09 INFO - 1500082329813 Marionette DEBUG Closed connection 355
18:32:09 INFO - 1500082329815 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49789
18:32:09 INFO - 1500082329839 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:09 INFO - 1500082329852 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:09 INFO - 1500082329876 Marionette DEBUG Register listener.js for window 15
18:32:09 INFO - 1500082329887 Marionette TRACE 356 <- [1,1,null,{"sessionId":"86f54abd-72d3-44ee-b7b8-ab6cfabaa728","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:09 INFO - 1500082329899 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329903 Marionette TRACE 356 <- [1,2,null,{}]
18:32:09 INFO - [Parent 1544] 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:32:09 INFO - 1500082329926 Marionette DEBUG Closed connection 356
18:32:09 INFO - 1500082329928 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49790
18:32:09 INFO - 1500082329929 Marionette DEBUG Closed connection 357
18:32:09 INFO - 1500082329931 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49791
18:32:09 INFO - 1500082329933 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:09 INFO - 1500082329935 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:09 INFO - 1500082329957 Marionette DEBUG Register listener.js for window 15
18:32:09 INFO - 1500082329968 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e74cef29-d14d-4a0c-bf2e-8c3a56883abd","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:09 INFO - 1500082329980 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
18:32:09 INFO - 1500082329984 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
18:32:10 INFO - 1500082329996 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
18:32:10 INFO - 1500082329997 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
18:32:10 INFO - ++DOMWINDOW == 28 (000000A8F05E9800) [pid = 1544] [serial = 40] [outer = 000000A8EBE25000]
18:32:10 INFO - 1500082330054 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
18:32:10 INFO - 1500082330059 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
18:32:10 INFO - 1500082330076 Marionette TRACE 358 <- [1,2,null,{}]
18:32:10 INFO - 1500082330092 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:32:10 INFO - 1500082330098 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4df00c8e-6c80-405b-8198-5fa1ceaaf91f","ELEMENT":"4df00c8e-6c80-405b-8198-5fa1ceaaf91f"}}]
18:32:10 INFO - 1500082330100 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4df00c8e-6c80-405b-8198-5fa1ceaaf91f"}]
18:32:10 INFO - 1500082330327 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
18:32:10 INFO - [Parent 1544] 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:32:10 INFO - 1500082330352 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
18:32:10 INFO - 1500082330354 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
18:32:10 INFO - ++DOMWINDOW == 29 (000000A8F45C8000) [pid = 1544] [serial = 41] [outer = 000000A8EBE25000]
18:32:11 INFO - 1500082331316 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
18:32:11 INFO - 1500082331327 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
18:32:11 INFO - 1500082331348 Marionette TRACE 358 <- [1,4,null,{}]
18:32:11 INFO - 1500082331603 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:32:11 INFO - 1500082331609 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"906f1f70-2e90-4e04-93d0-1be4ed94ff6d","ELEMENT":"906f1f70-2e90-4e04-93d0-1be4ed94ff6d"}}]
18:32:11 INFO - 1500082331612 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:32:11 INFO - 1500082331613 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:32:11 INFO - 1500082331620 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:32:11 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500082331628 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:32:11 INFO - 1500082331632 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331637 Marionette TRACE 358 <- [1,8,null,{}]
18:32:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1940ms
18:32:11 INFO - TEST-START | test_click.py TestClick.test_container_element
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331694 Marionette DEBUG Closed connection 358
18:32:11 INFO - 1500082331695 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49794
18:32:11 INFO - 1500082331716 Marionette DEBUG Closed connection 359
18:32:11 INFO - 1500082331717 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49795
18:32:11 INFO - 1500082331720 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:11 INFO - 1500082331723 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:11 INFO - 1500082331747 Marionette DEBUG Register listener.js for window 15
18:32:11 INFO - 1500082331759 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d4380bee-8aee-4759-8a72-06d4979390be","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:11 INFO - 1500082331774 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:32:11 INFO - 1500082331776 Marionette TRACE 360 <- [1,2,null,{}]
18:32:11 INFO - 1500082331782 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:11 INFO - 1500082331784 Marionette TRACE 360 <- [1,3,null,{}]
18:32:11 INFO - 1500082331786 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:11 INFO - 1500082331788 Marionette TRACE 360 <- [1,4,null,{}]
18:32:11 INFO - 1500082331886 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:32:11 INFO - TEST-START: test_click.py TestClick.test_container_element1500082331894 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:32:11 INFO - 1500082331897 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331902 Marionette TRACE 360 <- [1,6,null,{}]
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331926 Marionette DEBUG Closed connection 360
18:32:11 INFO - 1500082331928 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49796
18:32:11 INFO - 1500082331929 Marionette DEBUG Closed connection 361
18:32:11 INFO - 1500082331931 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49797
18:32:11 INFO - 1500082331933 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:11 INFO - 1500082331935 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:11 INFO - 1500082331958 Marionette DEBUG Register listener.js for window 15
18:32:11 INFO - 1500082331969 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3d94af53-1d56-43a0-be5b-d341ce882d62","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:11 INFO - 1500082331975 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331979 Marionette TRACE 362 <- [1,2,null,{}]
18:32:11 INFO - [Parent 1544] 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:32:11 INFO - 1500082331985 Marionette DEBUG Closed connection 362
18:32:11 INFO - 1500082331986 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49798
18:32:11 INFO - 1500082331988 Marionette DEBUG Closed connection 363
18:32:11 INFO - 1500082331989 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49799
18:32:11 INFO - 1500082331991 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:11 INFO - 1500082331994 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:12 INFO - 1500082332016 Marionette DEBUG Register listener.js for window 15
18:32:12 INFO - 1500082332038 Marionette TRACE 364 <- [1,1,null,{"sessionId":"dfa9441a-e83b-4bbc-a7a0-325173bd4eb9","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:12 INFO - 1500082332072 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:32:12 INFO - 1500082332076 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
18:32:12 INFO - 1500082332085 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
18:32:12 INFO - 1500082332085 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
18:32:12 INFO - ++DOMWINDOW == 30 (000000A8E3A38000) [pid = 1544] [serial = 42] [outer = 000000A8EBE25000]
18:32:12 INFO - 1500082332148 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:32:12 INFO - 1500082332158 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:32:12 INFO - 1500082332178 Marionette TRACE 364 <- [1,2,null,{}]
18:32:12 INFO - 1500082332193 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:32:12 INFO - 1500082332199 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbf86a2c-57b8-497a-9778-63fdfc94d2ca","ELEMENT":"cbf86a2c-57b8-497a-9778-63fdfc94d2ca"}}]
18:32:12 INFO - 1500082332201 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cbf86a2c-57b8-497a-9778-63fdfc94d2ca"}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - [Parent 1544] 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:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332660 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:32:12 INFO - 1500082332661 Marionette TRACE 364 <- [1,4,null,{}]
18:32:12 INFO - 1500082332664 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cbf86a2c-57b8-497a-9778-63fdfc94d2ca","name":"selected"}]
18:32:12 INFO - 1500082332695 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:32:12 INFO - 1500082332702 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:32:12 INFO - TEST-END: test_click.py TestClick.test_container_element1500082332711 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:32:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1089ms
18:32:12 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:32:12 INFO - 1500082332722 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332727 Marionette TRACE 364 <- [1,7,null,{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332749 Marionette DEBUG Closed connection 364
18:32:12 INFO - 1500082332751 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49800
18:32:12 INFO - 1500082332753 Marionette DEBUG Closed connection 365
18:32:12 INFO - 1500082332754 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49801
18:32:12 INFO - 1500082332756 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:12 INFO - 1500082332758 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:12 INFO - 1500082332781 Marionette DEBUG Register listener.js for window 15
18:32:12 INFO - 1500082332794 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3dff2a84-c74d-4ced-966b-5dbd11aa1aec","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:12 INFO - 1500082332800 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:32:12 INFO - 1500082332801 Marionette TRACE 366 <- [1,2,null,{}]
18:32:12 INFO - 1500082332807 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:12 INFO - 1500082332809 Marionette TRACE 366 <- [1,3,null,{}]
18:32:12 INFO - --DOMWINDOW == 29 (000000A8EFC1A800) [pid = 1544] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 28 (000000A8F241A000) [pid = 1544] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 27 (000000A8EB2ED800) [pid = 1544] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 26 (000000A8E71A1800) [pid = 1544] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 25 (000000A8E5CAC000) [pid = 1544] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 24 (000000A8EDC94800) [pid = 1544] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 23 (000000A8E13B7000) [pid = 1544] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - --DOMWINDOW == 22 (000000A8F24E1800) [pid = 1544] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
18:32:12 INFO - 1500082332822 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:12 INFO - 1500082332823 Marionette TRACE 366 <- [1,4,null,{}]
18:32:12 INFO - 1500082332826 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:32:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500082332833 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:32:12 INFO - 1500082332836 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332840 Marionette TRACE 366 <- [1,6,null,{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332846 Marionette DEBUG Closed connection 366
18:32:12 INFO - 1500082332848 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49802
18:32:12 INFO - 1500082332849 Marionette DEBUG Closed connection 367
18:32:12 INFO - 1500082332868 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49803
18:32:12 INFO - 1500082332891 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:12 INFO - 1500082332893 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:12 INFO - 1500082332915 Marionette DEBUG Register listener.js for window 15
18:32:12 INFO - 1500082332927 Marionette TRACE 368 <- [1,1,null,{"sessionId":"23584853-9fbc-4241-a4a8-9a2a1ea03fab","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:12 INFO - 1500082332933 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332937 Marionette TRACE 368 <- [1,2,null,{}]
18:32:12 INFO - [Parent 1544] 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:32:12 INFO - 1500082332943 Marionette DEBUG Closed connection 368
18:32:12 INFO - 1500082332944 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49804
18:32:12 INFO - 1500082332945 Marionette DEBUG Closed connection 369
18:32:12 INFO - 1500082332947 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49805
18:32:12 INFO - 1500082332949 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:12 INFO - 1500082332951 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:12 INFO - 1500082332974 Marionette DEBUG Register listener.js for window 15
18:32:12 INFO - 1500082332985 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1f50d5f2-3d26-45cb-9010-c07d80caea19","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:12 INFO - 1500082332991 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:32:13 INFO - 1500082332996 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:32:13 INFO - 1500082333003 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:32:13 INFO - 1500082333005 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:32:13 INFO - ++DOMWINDOW == 23 (000000A8E7194000) [pid = 1544] [serial = 43] [outer = 000000A8EBE25000]
18:32:13 INFO - 1500082333052 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:32:13 INFO - 1500082333065 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:32:13 INFO - 1500082333073 Marionette TRACE 370 <- [1,2,null,{}]
18:32:13 INFO - 1500082333084 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:32:13 INFO - 1500082333091 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"10a0d6b7-13cb-4716-b503-8914c94d062c","ELEMENT":"10a0d6b7-13cb-4716-b503-8914c94d062c"}}]
18:32:13 INFO - 1500082333094 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"10a0d6b7-13cb-4716-b503-8914c94d062c"}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - [Parent 1544] 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:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333536 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:32:13 INFO - 1500082333538 Marionette TRACE 370 <- [1,4,null,{}]
18:32:13 INFO - 1500082333540 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"10a0d6b7-13cb-4716-b503-8914c94d062c","name":"selected"}]
18:32:13 INFO - 1500082333568 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:32:13 INFO - 1500082333571 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:32:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500082333578 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:32:13 INFO - 1500082333580 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333584 Marionette TRACE 370 <- [1,7,null,{}]
18:32:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 856ms
18:32:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333590 Marionette DEBUG Closed connection 370
18:32:13 INFO - 1500082333591 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49806
18:32:13 INFO - 1500082333592 Marionette DEBUG Closed connection 371
18:32:13 INFO - 1500082333594 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49807
18:32:13 INFO - 1500082333596 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:13 INFO - 1500082333598 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:13 INFO - 1500082333621 Marionette DEBUG Register listener.js for window 15
18:32:13 INFO - 1500082333632 Marionette TRACE 372 <- [1,1,null,{"sessionId":"5f623c55-c798-4dc1-94c9-9843069c36f3","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:13 INFO - 1500082333638 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:32:13 INFO - 1500082333640 Marionette TRACE 372 <- [1,2,null,{}]
18:32:13 INFO - 1500082333642 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:13 INFO - 1500082333643 Marionette TRACE 372 <- [1,3,null,{}]
18:32:13 INFO - 1500082333645 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:13 INFO - 1500082333646 Marionette TRACE 372 <- [1,4,null,{}]
18:32:13 INFO - 1500082333649 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:32:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500082333658 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:32:13 INFO - 1500082333660 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333665 Marionette TRACE 372 <- [1,6,null,{}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333670 Marionette DEBUG Closed connection 372
18:32:13 INFO - 1500082333672 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49808
18:32:13 INFO - 1500082333674 Marionette DEBUG Closed connection 373
18:32:13 INFO - 1500082333675 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49809
18:32:13 INFO - 1500082333677 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:13 INFO - 1500082333679 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:13 INFO - 1500082333702 Marionette DEBUG Register listener.js for window 15
18:32:13 INFO - 1500082333712 Marionette TRACE 374 <- [1,1,null,{"sessionId":"08384ddd-97e0-48fe-820a-bc6c5df18b96","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:13 INFO - 1500082333718 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333722 Marionette TRACE 374 <- [1,2,null,{}]
18:32:13 INFO - [Parent 1544] 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:32:13 INFO - 1500082333728 Marionette DEBUG Closed connection 374
18:32:13 INFO - 1500082333730 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49810
18:32:13 INFO - 1500082333731 Marionette DEBUG Closed connection 375
18:32:13 INFO - 1500082333733 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49811
18:32:13 INFO - 1500082333735 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:13 INFO - 1500082333737 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:13 INFO - 1500082333761 Marionette DEBUG Register listener.js for window 15
18:32:13 INFO - 1500082333772 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e44520bb-16a1-4b34-bd2b-c61401c3cc4e","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:13 INFO - 1500082333777 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:32:13 INFO - 1500082333781 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:32:13 INFO - 1500082333787 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:32:13 INFO - 1500082333788 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:32:13 INFO - ++DOMWINDOW == 24 (000000A8EF615800) [pid = 1544] [serial = 44] [outer = 000000A8EBE25000]
18:32:13 INFO - 1500082333836 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:32:13 INFO - 1500082333843 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:32:13 INFO - 1500082333860 Marionette TRACE 376 <- [1,2,null,{}]
18:32:13 INFO - 1500082333863 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:32:13 INFO - 1500082333870 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"39a90b89-bbd7-4cc0-8498-f840c4651ee3","ELEMENT":"39a90b89-bbd7-4cc0-8498-f840c4651ee3"}}]
18:32:13 INFO - 1500082333872 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"39a90b89-bbd7-4cc0-8498-f840c4651ee3"}]
18:32:14 INFO - 1500082334323 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:32:14 INFO - 1500082334325 Marionette TRACE 376 <- [1,4,null,{}]
18:32:14 INFO - 1500082334328 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:32:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500082334335 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:32:14 INFO - 1500082334337 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334341 Marionette TRACE 376 <- [1,6,null,{}]
18:32:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 758ms
18:32:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334347 Marionette DEBUG Closed connection 376
18:32:14 INFO - 1500082334348 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49812
18:32:14 INFO - 1500082334350 Marionette DEBUG Closed connection 377
18:32:14 INFO - 1500082334351 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49813
18:32:14 INFO - 1500082334353 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:14 INFO - 1500082334355 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:14 INFO - 1500082334379 Marionette DEBUG Register listener.js for window 15
18:32:14 INFO - 1500082334389 Marionette TRACE 378 <- [1,1,null,{"sessionId":"1e488a75-6c57-44c8-8f21-2984f119a000","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:14 INFO - 1500082334395 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:32:14 INFO - 1500082334397 Marionette TRACE 378 <- [1,2,null,{}]
18:32:14 INFO - 1500082334399 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:14 INFO - 1500082334400 Marionette TRACE 378 <- [1,3,null,{}]
18:32:14 INFO - 1500082334402 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:14 INFO - 1500082334403 Marionette TRACE 378 <- [1,4,null,{}]
18:32:14 INFO - 1500082334406 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:32:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500082334413 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:32:14 INFO - 1500082334414 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334419 Marionette TRACE 378 <- [1,6,null,{}]
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334426 Marionette DEBUG Closed connection 378
18:32:14 INFO - 1500082334428 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49814
18:32:14 INFO - 1500082334429 Marionette DEBUG Closed connection 379
18:32:14 INFO - 1500082334431 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49815
18:32:14 INFO - 1500082334433 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:14 INFO - 1500082334435 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:14 INFO - 1500082334459 Marionette DEBUG Register listener.js for window 15
18:32:14 INFO - 1500082334469 Marionette TRACE 380 <- [1,1,null,{"sessionId":"32a6ad5d-2c8d-4caa-b1d2-06c7b75b0de3","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:14 INFO - 1500082334475 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334479 Marionette TRACE 380 <- [1,2,null,{}]
18:32:14 INFO - [Parent 1544] 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:32:14 INFO - 1500082334485 Marionette DEBUG Closed connection 380
18:32:14 INFO - 1500082334487 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49816
18:32:14 INFO - 1500082334488 Marionette DEBUG Closed connection 381
18:32:14 INFO - 1500082334489 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49817
18:32:14 INFO - 1500082334491 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:14 INFO - 1500082334494 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:14 INFO - 1500082334517 Marionette DEBUG Register listener.js for window 15
18:32:14 INFO - 1500082334530 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d0b55534-2b14-42e9-be77-81c3bb0e3a34","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:14 INFO - 1500082334537 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:32:14 INFO - 1500082334541 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:32:14 INFO - 1500082334549 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:32:14 INFO - 1500082334550 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:32:14 INFO - ++DOMWINDOW == 25 (000000A8F168F000) [pid = 1544] [serial = 45] [outer = 000000A8EBE25000]
18:32:14 INFO - 1500082334602 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:32:14 INFO - 1500082334606 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:32:14 INFO - 1500082334624 Marionette TRACE 382 <- [1,2,null,{}]
18:32:14 INFO - 1500082334627 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:32:14 INFO - 1500082334634 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"72e52be1-b14e-45c2-83a3-74112cacbc05","ELEMENT":"72e52be1-b14e-45c2-83a3-74112cacbc05"}}]
18:32:14 INFO - 1500082334636 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"72e52be1-b14e-45c2-83a3-74112cacbc05"}]
18:32:15 INFO - 1500082335069 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:32:15 INFO - 1500082335071 Marionette TRACE 382 <- [1,4,null,{}]
18:32:15 INFO - 1500082335074 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:32:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500082335080 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:32:15 INFO - 1500082335082 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335087 Marionette TRACE 382 <- [1,6,null,{}]
18:32:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 745ms
18:32:15 INFO - TEST-START | test_click.py TestClick.test_input_file
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335093 Marionette DEBUG Closed connection 382
18:32:15 INFO - 1500082335094 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49818
18:32:15 INFO - 1500082335096 Marionette DEBUG Closed connection 383
18:32:15 INFO - 1500082335097 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49819
18:32:15 INFO - 1500082335099 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:15 INFO - 1500082335101 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335125 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335135 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0ae6cbdc-fbcd-4772-a08f-ecba8d609a33","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335141 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:32:15 INFO - 1500082335143 Marionette TRACE 384 <- [1,2,null,{}]
18:32:15 INFO - 1500082335144 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:15 INFO - 1500082335146 Marionette TRACE 384 <- [1,3,null,{}]
18:32:15 INFO - 1500082335147 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:15 INFO - 1500082335149 Marionette TRACE 384 <- [1,4,null,{}]
18:32:15 INFO - 1500082335151 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:32:15 INFO - TEST-START: test_click.py TestClick.test_input_file1500082335158 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:32:15 INFO - 1500082335160 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335164 Marionette TRACE 384 <- [1,6,null,{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335170 Marionette DEBUG Closed connection 384
18:32:15 INFO - 1500082335171 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49820
18:32:15 INFO - 1500082335175 Marionette DEBUG Closed connection 385
18:32:15 INFO - 1500082335176 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49821
18:32:15 INFO - 1500082335179 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:15 INFO - 1500082335181 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335204 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335225 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a69114ac-92ba-46af-bb7d-631d5e84b902","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335231 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335235 Marionette TRACE 386 <- [1,2,null,{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335241 Marionette DEBUG Closed connection 386
18:32:15 INFO - 1500082335243 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49822
18:32:15 INFO - 1500082335245 Marionette DEBUG Closed connection 387
18:32:15 INFO - 1500082335246 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49823
18:32:15 INFO - 1500082335249 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:15 INFO - 1500082335251 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335275 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335285 Marionette TRACE 388 <- [1,1,null,{"sessionId":"90503c50-4d07-49c6-aab8-cb591d4483a7","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335291 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:32:15 INFO - 1500082335295 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:32:15 INFO - 1500082335300 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:32:15 INFO - 1500082335302 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:32:15 INFO - ++DOMWINDOW == 26 (000000A8F24D4000) [pid = 1544] [serial = 46] [outer = 000000A8EBE25000]
18:32:15 INFO - 1500082335353 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:32:15 INFO - 1500082335358 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:32:15 INFO - 1500082335373 Marionette TRACE 388 <- [1,2,null,{}]
18:32:15 INFO - 1500082335376 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:32:15 INFO - 1500082335382 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d3e99ad-af4d-4d0f-901d-3586f2274b17","ELEMENT":"9d3e99ad-af4d-4d0f-901d-3586f2274b17"}}]
18:32:15 INFO - 1500082335384 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"9d3e99ad-af4d-4d0f-901d-3586f2274b17"}]
18:32:15 INFO - 1500082335536 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:32:15 INFO - 1500082335539 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:32:15 INFO - TEST-END: test_click.py TestClick.test_input_file1500082335557 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:32:15 INFO - 1500082335560 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335564 Marionette TRACE 388 <- [1,6,null,{}]
18:32:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 478ms
18:32:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335570 Marionette DEBUG Closed connection 388
18:32:15 INFO - 1500082335572 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49824
18:32:15 INFO - 1500082335573 Marionette DEBUG Closed connection 389
18:32:15 INFO - 1500082335575 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49825
18:32:15 INFO - 1500082335577 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:15 INFO - 1500082335579 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335602 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335613 Marionette TRACE 390 <- [1,1,null,{"sessionId":"78ce0892-2937-423a-9eca-00708e51cbb5","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335619 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:32:15 INFO - 1500082335620 Marionette TRACE 390 <- [1,2,null,{}]
18:32:15 INFO - 1500082335622 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:15 INFO - 1500082335623 Marionette TRACE 390 <- [1,3,null,{}]
18:32:15 INFO - 1500082335625 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:15 INFO - 1500082335626 Marionette TRACE 390 <- [1,4,null,{}]
18:32:15 INFO - 1500082335629 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:32:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500082335636 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:32:15 INFO - 1500082335638 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335642 Marionette TRACE 390 <- [1,6,null,{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335649 Marionette DEBUG Closed connection 390
18:32:15 INFO - 1500082335650 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49826
18:32:15 INFO - 1500082335652 Marionette DEBUG Closed connection 391
18:32:15 INFO - 1500082335653 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49827
18:32:15 INFO - 1500082335655 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:15 INFO - 1500082335657 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335682 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335692 Marionette TRACE 392 <- [1,1,null,{"sessionId":"08ea1dcb-2d88-40c0-887f-137d9c2eff73","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335698 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335702 Marionette TRACE 392 <- [1,2,null,{}]
18:32:15 INFO - [Parent 1544] 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:32:15 INFO - 1500082335708 Marionette DEBUG Closed connection 392
18:32:15 INFO - 1500082335709 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49828
18:32:15 INFO - 1500082335711 Marionette DEBUG Closed connection 393
18:32:15 INFO - 1500082335712 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49829
18:32:15 INFO - 1500082335714 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:15 INFO - 1500082335716 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:15 INFO - 1500082335740 Marionette DEBUG Register listener.js for window 15
18:32:15 INFO - 1500082335750 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2207686f-ccab-4c77-acec-f548558bc7ce","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:15 INFO - 1500082335756 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:32:15 INFO - 1500082335760 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:32:15 INFO - 1500082335768 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:32:15 INFO - 1500082335769 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:32:15 INFO - ++DOMWINDOW == 27 (000000A8F355F000) [pid = 1544] [serial = 47] [outer = 000000A8EBE25000]
18:32:15 INFO - 1500082335827 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:32:15 INFO - 1500082335833 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:32:15 INFO - 1500082335840 Marionette TRACE 394 <- [1,2,null,{}]
18:32:15 INFO - 1500082335849 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:32:15 INFO - 1500082335857 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ae39025-6bb0-4678-a480-d2ae6a6e1390","ELEMENT":"8ae39025-6bb0-4678-a480-d2ae6a6e1390"}}]
18:32:15 INFO - 1500082335859 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:32:15 INFO - 1500082335864 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f285eba-00c2-4507-92bc-f1a2d7116b92","ELEMENT":"4f285eba-00c2-4507-92bc-f1a2d7116b92"}}]
18:32:15 INFO - 1500082335866 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8ae39025-6bb0-4678-a480-d2ae6a6e1390"}]
18:32:16 INFO - 1500082336320 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:32:16 INFO - 1500082336322 Marionette TRACE 394 <- [1,5,null,{}]
18:32:16 INFO - 1500082336325 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4f285eba-00c2-4507-92bc-f1a2d7116b92"}]
18:32:16 INFO - 1500082336458 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:32:16 INFO - 1500082336461 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:32:16 INFO - 1500082336468 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:32:16 INFO - 1500082336471 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:32:16 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500082336478 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:32:16 INFO - 1500082336480 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336484 Marionette TRACE 394 <- [1,9,null,{}]
18:32:16 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 919ms
18:32:16 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336490 Marionette DEBUG Closed connection 394
18:32:16 INFO - 1500082336492 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49830
18:32:16 INFO - 1500082336493 Marionette DEBUG Closed connection 395
18:32:16 INFO - 1500082336495 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49831
18:32:16 INFO - 1500082336497 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:16 INFO - 1500082336499 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:16 INFO - 1500082336523 Marionette DEBUG Register listener.js for window 15
18:32:16 INFO - 1500082336533 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f82b51b4-96c0-4561-881a-295e9c6efe10","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:16 INFO - 1500082336539 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:32:16 INFO - 1500082336541 Marionette TRACE 396 <- [1,2,null,{}]
18:32:16 INFO - 1500082336542 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:32:16 INFO - 1500082336544 Marionette TRACE 396 <- [1,3,null,{}]
18:32:16 INFO - 1500082336545 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:32:16 INFO - 1500082336547 Marionette TRACE 396 <- [1,4,null,{}]
18:32:16 INFO - 1500082336549 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:32:16 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500082336557 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:32:16 INFO - 1500082336559 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336563 Marionette TRACE 396 <- [1,6,null,{}]
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336570 Marionette DEBUG Closed connection 396
18:32:16 INFO - 1500082336571 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49832
18:32:16 INFO - 1500082336572 Marionette DEBUG Closed connection 397
18:32:16 INFO - 1500082336574 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49833
18:32:16 INFO - 1500082336576 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:32:16 INFO - 1500082336578 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:16 INFO - 1500082336602 Marionette DEBUG Register listener.js for window 15
18:32:16 INFO - 1500082336614 Marionette TRACE 398 <- [1,1,null,{"sessionId":"58c8957f-0cb1-49b0-be38-3a3aa8ca6c06","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:16 INFO - 1500082336620 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336624 Marionette TRACE 398 <- [1,2,null,{}]
18:32:16 INFO - [Parent 1544] 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:32:16 INFO - 1500082336630 Marionette DEBUG Closed connection 398
18:32:16 INFO - 1500082336631 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49834
18:32:16 INFO - 1500082336633 Marionette DEBUG Closed connection 399
18:32:16 INFO - 1500082336634 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49835
18:32:16 INFO - 1500082336636 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:32:16 INFO - 1500082336638 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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}
18:32:16 INFO - 1500082336662 Marionette DEBUG Register listener.js for window 15
18:32:16 INFO - 1500082336673 Marionette TRACE 400 <- [1,1,null,{"sessionId":"cb715700-b2a4-43bf-9294-f261646550ab","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":1544,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-030.001\\AppData\\Local\\Temp\\tmpflnkos.mozrunner","moz:accessibilityChecks":false}}]
18:32:16 INFO - 1500082336679 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:32:16 INFO - 1500082336683 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:32:16 INFO - 1500082336688 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:32:16 INFO - 1500082336689 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:32:16 INFO - ++DOMWINDOW == 28 (000000A8F45C5000) [pid = 1544] [serial = 48] [outer = 000000A8EBE25000]
18:32:16 INFO - 1500082336741 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:32:16 INFO - 1500082336746 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:32:16 INFO - 1500082336753 Marionette TRACE 400 <- [1,2,null,{}]
18:32:16 INFO - 1500082336762 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:32:16 INFO - 1500082336784 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fc697c55-f89e-4247-9c9a-216074f25407","ELEMENT":"fc697c55-f89e-4247-9c9a-216074f25407"}}]
18:32:16 INFO - 1500082336786 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fc697c55-f89e-4247-9c9a-216074f25407"}]
18:32:16 INFO - 1500082336912 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:32:16 INFO - 1500082336914 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"fc697c55-f89e-4247-9c9a-216074f25407"}]
18:32:17 INFO - 1500082337057 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element