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]
06:48:15 INFO - 1500040095635 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}]
06:48:15 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500040095643 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:48:15 INFO - 1500040095645 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095649 Marionette TRACE 352 <- [1,6,null,{}]
06:48:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1268ms
06:48:15 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095655 Marionette DEBUG Closed connection 352
06:48:15 INFO - 1500040095656 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49859
06:48:15 INFO - 1500040095658 Marionette DEBUG Closed connection 353
06:48:15 INFO - 1500040095659 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49860
06:48:15 INFO - 1500040095661 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:15 INFO - 1500040095663 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:15 INFO - 1500040095685 Marionette DEBUG Register listener.js for window 15
06:48:15 INFO - 1500040095696 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9b5fb8fd-925f-42b5-8629-8251b1c7dd3f","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:15 INFO - 1500040095725 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:48:15 INFO - 1500040095726 Marionette TRACE 354 <- [1,2,null,{}]
06:48:15 INFO - 1500040095728 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:15 INFO - 1500040095729 Marionette TRACE 354 <- [1,3,null,{}]
06:48:15 INFO - 1500040095731 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:15 INFO - 1500040095733 Marionette TRACE 354 <- [1,4,null,{}]
06:48:15 INFO - 1500040095735 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}]
06:48:15 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500040095746 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:48:15 INFO - 1500040095751 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095756 Marionette TRACE 354 <- [1,6,null,{}]
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095776 Marionette DEBUG Closed connection 354
06:48:15 INFO - 1500040095778 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49861
06:48:15 INFO - 1500040095782 Marionette DEBUG Closed connection 355
06:48:15 INFO - 1500040095783 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49862
06:48:15 INFO - 1500040095786 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:15 INFO - 1500040095788 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:15 INFO - 1500040095809 Marionette DEBUG Register listener.js for window 15
06:48:15 INFO - 1500040095828 Marionette TRACE 356 <- [1,1,null,{"sessionId":"882f74eb-ffbc-4c6d-b9db-1998e3387d71","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:15 INFO - 1500040095838 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095842 Marionette TRACE 356 <- [1,2,null,{}]
06:48:15 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:15 INFO - 1500040095848 Marionette DEBUG Closed connection 356
06:48:15 INFO - 1500040095849 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49863
06:48:15 INFO - 1500040095851 Marionette DEBUG Closed connection 357
06:48:15 INFO - 1500040095852 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49864
06:48:15 INFO - 1500040095856 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:15 INFO - 1500040095859 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:15 INFO - 1500040095880 Marionette DEBUG Register listener.js for window 15
06:48:15 INFO - 1500040095891 Marionette TRACE 358 <- [1,1,null,{"sessionId":"bf6627c1-a508-437d-b744-5242e586c8c5","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:15 INFO - 1500040095916 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49391/clicks.html"}]
06:48:15 INFO - 1500040095920 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49391/hidden.html"
06:48:15 INFO - 1500040095936 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49391/hidden.html"
06:48:15 INFO - 1500040095937 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49391/hidden.html"
06:48:15 INFO - ++DOMWINDOW == 20 (00000073221A3800) [pid = 3560] [serial = 40] [outer = 000000732433F000]
06:48:16 INFO - 1500040096052 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49391/clicks.html"
06:48:16 INFO - 1500040096059 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49391/clicks.html"
06:48:16 INFO - 1500040096084 Marionette TRACE 358 <- [1,2,null,{}]
06:48:16 INFO - 1500040096089 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:48:16 INFO - 1500040096102 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ae6e9062-cdbf-4eb8-8d9c-fb45d9cbc99c","ELEMENT":"ae6e9062-cdbf-4eb8-8d9c-fb45d9cbc99c"}}]
06:48:16 INFO - 1500040096107 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"ae6e9062-cdbf-4eb8-8d9c-fb45d9cbc99c"}]
06:48:16 INFO - 1500040096543 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49391/clicks.html"
06:48:16 INFO - [Parent 3560] 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
06:48:16 INFO - 1500040096567 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49391/clicks.html"
06:48:16 INFO - 1500040096571 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49391/clicks.html"
06:48:16 INFO - ++DOMWINDOW == 21 (0000007317C5C000) [pid = 3560] [serial = 41] [outer = 000000732433F000]
06:48:16 INFO - 1500040096649 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49391/test.html"
06:48:16 INFO - 1500040096656 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49391/test.html"
06:48:16 INFO - 1500040096690 Marionette TRACE 358 <- [1,4,null,{}]
06:48:16 INFO - 1500040096695 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:48:16 INFO - 1500040096708 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"64a866e5-3c08-4c3c-accd-5a3e2d625ed3","ELEMENT":"64a866e5-3c08-4c3c-accd-5a3e2d625ed3"}}]
06:48:16 INFO - 1500040096712 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:48:16 INFO - 1500040096714 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:48:16 INFO - 1500040096720 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}]
06:48:16 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500040096734 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:48:16 INFO - 1500040096774 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:48:16 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:16 INFO - 1500040096783 Marionette TRACE 358 <- [1,8,null,{}]
06:48:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1135ms
06:48:16 INFO - TEST-START | test_click.py TestClick.test_container_element
06:48:16 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:16 INFO - 1500040096803 Marionette DEBUG Closed connection 358
06:48:16 INFO - 1500040096806 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49867
06:48:16 INFO - 1500040096809 Marionette DEBUG Closed connection 359
06:48:16 INFO - 1500040096812 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49868
06:48:16 INFO - 1500040096833 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:16 INFO - 1500040096843 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:16 INFO - 1500040096890 Marionette DEBUG Register listener.js for window 15
06:48:16 INFO - 1500040096911 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d8465d39-1bdc-4945-9169-b59098fe5c06","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:16 INFO - 1500040096928 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:48:16 INFO - 1500040096931 Marionette TRACE 360 <- [1,2,null,{}]
06:48:16 INFO - 1500040096946 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:16 INFO - 1500040096949 Marionette TRACE 360 <- [1,3,null,{}]
06:48:16 INFO - 1500040096953 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:16 INFO - 1500040096957 Marionette TRACE 360 <- [1,4,null,{}]
06:48:16 INFO - 1500040096965 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}]
06:48:16 INFO - TEST-START: test_click.py TestClick.test_container_element1500040096979 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:48:17 INFO - 1500040097031 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:17 INFO - 1500040097038 Marionette TRACE 360 <- [1,6,null,{}]
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:17 INFO - 1500040097064 Marionette DEBUG Closed connection 360
06:48:17 INFO - 1500040097067 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49869
06:48:17 INFO - 1500040097071 Marionette DEBUG Closed connection 361
06:48:17 INFO - 1500040097073 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49870
06:48:17 INFO - 1500040097126 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:17 INFO - 1500040097130 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:17 INFO - 1500040097175 Marionette DEBUG Register listener.js for window 15
06:48:17 INFO - 1500040097192 Marionette TRACE 362 <- [1,1,null,{"sessionId":"8e76132f-8c4d-4b53-a502-a13958e17821","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:17 INFO - 1500040097207 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:17 INFO - 1500040097213 Marionette TRACE 362 <- [1,2,null,{}]
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:17 INFO - 1500040097220 Marionette DEBUG Closed connection 362
06:48:17 INFO - 1500040097223 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49871
06:48:17 INFO - 1500040097234 Marionette DEBUG Closed connection 363
06:48:17 INFO - 1500040097236 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49872
06:48:17 INFO - 1500040097239 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:17 INFO - 1500040097243 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:17 INFO - 1500040097289 Marionette DEBUG Register listener.js for window 15
06:48:17 INFO - 1500040097306 Marionette TRACE 364 <- [1,1,null,{"sessionId":"cf75740e-7733-4393-8d3d-65b4b80517e1","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:17 INFO - 1500040097334 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"}]
06:48:17 INFO - 1500040097340 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49391/test.html"
06:48:17 INFO - 1500040097347 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49391/test.html"
06:48:17 INFO - 1500040097348 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49391/test.html"
06:48:17 INFO - ++DOMWINDOW == 22 (00000073204C7000) [pid = 3560] [serial = 42] [outer = 000000732433F000]
06:48:17 INFO - 1500040097431 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"
06:48:17 INFO - 1500040097439 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"
06:48:17 INFO - 1500040097458 Marionette TRACE 364 <- [1,2,null,{}]
06:48:17 INFO - 1500040097465 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:48:17 INFO - 1500040097476 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8796e5fa-0a5b-46ea-8f3e-6bb7d625fc47","ELEMENT":"8796e5fa-0a5b-46ea-8f3e-6bb7d625fc47"}}]
06:48:17 INFO - 1500040097479 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8796e5fa-0a5b-46ea-8f3e-6bb7d625fc47"}]
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
06:48:17 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
06:48:18 INFO - 1500040098126 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:48:18 INFO - 1500040098128 Marionette TRACE 364 <- [1,4,null,{}]
06:48:18 INFO - 1500040098132 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8796e5fa-0a5b-46ea-8f3e-6bb7d625fc47","name":"selected"}]
06:48:18 INFO - 1500040098195 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:48:18 INFO - 1500040098205 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}]
06:48:18 INFO - TEST-END: test_click.py TestClick.test_container_element1500040098218 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:48:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1455ms
06:48:18 INFO - 1500040098234 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:48:18 INFO - 1500040098240 Marionette TRACE 364 <- [1,7,null,{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - 1500040098247 Marionette DEBUG Closed connection 364
06:48:18 INFO - 1500040098249 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49873
06:48:18 INFO - 1500040098252 Marionette DEBUG Closed connection 365
06:48:18 INFO - 1500040098254 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49874
06:48:18 INFO - 1500040098257 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:18 INFO - 1500040098260 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:18 INFO - 1500040098306 Marionette DEBUG Register listener.js for window 15
06:48:18 INFO - 1500040098321 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fc3b987b-c0bb-4cfa-a478-2fadd02f23af","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:18 INFO - 1500040098350 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:48:18 INFO - 1500040098352 Marionette TRACE 366 <- [1,2,null,{}]
06:48:18 INFO - 1500040098365 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:18 INFO - 1500040098367 Marionette TRACE 366 <- [1,3,null,{}]
06:48:18 INFO - 1500040098370 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:18 INFO - 1500040098372 Marionette TRACE 366 <- [1,4,null,{}]
06:48:18 INFO - 1500040098375 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}]
06:48:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500040098387 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:48:18 INFO - 1500040098421 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - 1500040098427 Marionette TRACE 366 <- [1,6,null,{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - 1500040098450 Marionette DEBUG Closed connection 366
06:48:18 INFO - 1500040098452 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49875
06:48:18 INFO - 1500040098457 Marionette DEBUG Closed connection 367
06:48:18 INFO - 1500040098459 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49876
06:48:18 INFO - 1500040098602 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:18 INFO - 1500040098616 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:18 INFO - 1500040098642 Marionette DEBUG Register listener.js for window 15
06:48:18 INFO - 1500040098663 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5dcf2e67-c1f0-453f-9444-0c6265f745bc","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:18 INFO - 1500040098679 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - 1500040098686 Marionette TRACE 368 <- [1,2,null,{}]
06:48:18 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:18 INFO - 1500040098747 Marionette DEBUG Closed connection 368
06:48:18 INFO - 1500040098749 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49877
06:48:18 INFO - 1500040098772 Marionette DEBUG Closed connection 369
06:48:18 INFO - 1500040098775 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49878
06:48:18 INFO - 1500040098781 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:18 INFO - 1500040098787 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:18 INFO - 1500040098813 Marionette DEBUG Register listener.js for window 15
06:48:18 INFO - 1500040098832 Marionette TRACE 370 <- [1,1,null,{"sessionId":"dccfdc88-3f9b-4790-8cf0-de4166faa6fd","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:18 INFO - 1500040098858 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"}]
06:48:18 INFO - 1500040098866 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"
06:48:18 INFO - 1500040098877 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"
06:48:18 INFO - 1500040098878 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"
06:48:18 INFO - ++DOMWINDOW == 23 (0000007316464800) [pid = 3560] [serial = 43] [outer = 000000732433F000]
06:48:18 INFO - 1500040098933 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"
06:48:18 INFO - 1500040098948 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"
06:48:18 INFO - 1500040098961 Marionette TRACE 370 <- [1,2,null,{}]
06:48:18 INFO - 1500040098975 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:48:18 INFO - 1500040098986 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e605859a-c3b7-434d-b717-9d61044d0968","ELEMENT":"e605859a-c3b7-434d-b717-9d61044d0968"}}]
06:48:18 INFO - 1500040098990 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"e605859a-c3b7-434d-b717-9d61044d0968"}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
06:48:19 INFO - --DOMWINDOW == 22 (000000732BA5A800) [pid = 3560] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49391/test_accessibility.html]
06:48:19 INFO - --DOMWINDOW == 21 (00000073181C6000) [pid = 3560] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49391/test_accessibility.html]
06:48:19 INFO - --DOMWINDOW == 20 (000000731BC48800) [pid = 3560] [serial = 32] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
06:48:19 INFO - 1500040099436 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:48:19 INFO - 1500040099439 Marionette TRACE 370 <- [1,4,null,{}]
06:48:19 INFO - 1500040099441 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"e605859a-c3b7-434d-b717-9d61044d0968","name":"selected"}]
06:48:19 INFO - 1500040099473 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:48:19 INFO - 1500040099476 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}]
06:48:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500040099487 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:48:19 INFO - 1500040099489 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099494 Marionette TRACE 370 <- [1,7,null,{}]
06:48:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1254ms
06:48:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099502 Marionette DEBUG Closed connection 370
06:48:19 INFO - 1500040099505 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49879
06:48:19 INFO - 1500040099507 Marionette DEBUG Closed connection 371
06:48:19 INFO - 1500040099509 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49880
06:48:19 INFO - 1500040099512 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:19 INFO - 1500040099515 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:19 INFO - 1500040099538 Marionette DEBUG Register listener.js for window 15
06:48:19 INFO - 1500040099555 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e6ffd1bb-213c-45c5-8988-2ca254144289","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:19 INFO - 1500040099570 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:48:19 INFO - 1500040099575 Marionette TRACE 372 <- [1,2,null,{}]
06:48:19 INFO - 1500040099584 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:19 INFO - 1500040099586 Marionette TRACE 372 <- [1,3,null,{}]
06:48:19 INFO - 1500040099587 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:19 INFO - 1500040099589 Marionette TRACE 372 <- [1,4,null,{}]
06:48:19 INFO - 1500040099591 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}]
06:48:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500040099601 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:48:19 INFO - 1500040099603 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099608 Marionette TRACE 372 <- [1,6,null,{}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099614 Marionette DEBUG Closed connection 372
06:48:19 INFO - 1500040099616 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49881
06:48:19 INFO - 1500040099621 Marionette DEBUG Closed connection 373
06:48:19 INFO - 1500040099623 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49882
06:48:19 INFO - 1500040099673 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:19 INFO - 1500040099676 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:19 INFO - 1500040099699 Marionette DEBUG Register listener.js for window 15
06:48:19 INFO - 1500040099712 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6731b280-eed9-4fc0-a3ef-63ca69b212d8","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:19 INFO - 1500040099820 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099825 Marionette TRACE 374 <- [1,2,null,{}]
06:48:19 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:19 INFO - 1500040099833 Marionette DEBUG Closed connection 374
06:48:19 INFO - 1500040099835 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49883
06:48:19 INFO - 1500040099857 Marionette DEBUG Closed connection 375
06:48:19 INFO - 1500040099859 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49884
06:48:19 INFO - 1500040099862 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:19 INFO - 1500040099868 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:19 INFO - 1500040099891 Marionette DEBUG Register listener.js for window 15
06:48:19 INFO - 1500040099925 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5c9cd4f0-c1ba-4744-8b23-232e108b4883","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:19 INFO - 1500040099938 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"}]
06:48:19 INFO - 1500040099943 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"
06:48:19 INFO - 1500040099952 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"
06:48:19 INFO - 1500040099953 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"
06:48:19 INFO - ++DOMWINDOW == 21 (000000731BA9F800) [pid = 3560] [serial = 44] [outer = 000000732433F000]
06:48:20 INFO - 1500040100015 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"
06:48:20 INFO - 1500040100022 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"
06:48:20 INFO - 1500040100041 Marionette TRACE 376 <- [1,2,null,{}]
06:48:20 INFO - 1500040100049 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:48:20 INFO - 1500040100057 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b97dd05-c2a1-4db2-b39f-9a8cc6144b0b","ELEMENT":"0b97dd05-c2a1-4db2-b39f-9a8cc6144b0b"}}]
06:48:20 INFO - 1500040100059 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"0b97dd05-c2a1-4db2-b39f-9a8cc6144b0b"}]
06:48:20 INFO - 1500040100985 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:48:21 INFO - 1500040101005 Marionette TRACE 376 <- [1,4,null,{}]
06:48:21 INFO - 1500040101012 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}]
06:48:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500040101021 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:48:21 INFO - 1500040101062 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101067 Marionette TRACE 376 <- [1,6,null,{}]
06:48:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1573ms
06:48:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101124 Marionette DEBUG Closed connection 376
06:48:21 INFO - 1500040101126 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49885
06:48:21 INFO - 1500040101177 Marionette DEBUG Closed connection 377
06:48:21 INFO - 1500040101179 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49886
06:48:21 INFO - 1500040101220 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:21 INFO - 1500040101222 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:21 INFO - 1500040101246 Marionette DEBUG Register listener.js for window 15
06:48:21 INFO - 1500040101260 Marionette TRACE 378 <- [1,1,null,{"sessionId":"26f53f46-f2eb-40f7-a7f5-f10fea1cd215","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:21 INFO - 1500040101283 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:48:21 INFO - 1500040101285 Marionette TRACE 378 <- [1,2,null,{}]
06:48:21 INFO - 1500040101288 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:21 INFO - 1500040101290 Marionette TRACE 378 <- [1,3,null,{}]
06:48:21 INFO - 1500040101292 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:21 INFO - 1500040101294 Marionette TRACE 378 <- [1,4,null,{}]
06:48:21 INFO - 1500040101297 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}]
06:48:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500040101310 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:48:21 INFO - 1500040101312 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101316 Marionette TRACE 378 <- [1,6,null,{}]
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101324 Marionette DEBUG Closed connection 378
06:48:21 INFO - 1500040101329 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49887
06:48:21 INFO - 1500040101332 Marionette DEBUG Closed connection 379
06:48:21 INFO - 1500040101333 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49888
06:48:21 INFO - 1500040101336 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:21 INFO - 1500040101338 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:21 INFO - 1500040101360 Marionette DEBUG Register listener.js for window 15
06:48:21 INFO - 1500040101372 Marionette TRACE 380 <- [1,1,null,{"sessionId":"79647828-e42d-4c2f-a49e-41db4adc12b3","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:21 INFO - 1500040101378 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101383 Marionette TRACE 380 <- [1,2,null,{}]
06:48:21 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:21 INFO - 1500040101389 Marionette DEBUG Closed connection 380
06:48:21 INFO - 1500040101390 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49889
06:48:21 INFO - 1500040101392 Marionette DEBUG Closed connection 381
06:48:21 INFO - 1500040101393 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49890
06:48:21 INFO - 1500040101395 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:21 INFO - 1500040101398 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:21 INFO - 1500040101421 Marionette DEBUG Register listener.js for window 15
06:48:21 INFO - 1500040101433 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b650cd61-0b0a-4ea2-b986-59a90cce9e20","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:21 INFO - 1500040101440 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"}]
06:48:21 INFO - 1500040101444 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"
06:48:21 INFO - 1500040101451 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"
06:48:21 INFO - 1500040101452 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"
06:48:21 INFO - ++DOMWINDOW == 22 (000000731DD16000) [pid = 3560] [serial = 45] [outer = 000000732433F000]
06:48:21 INFO - 1500040101503 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"
06:48:21 INFO - 1500040101517 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"
06:48:21 INFO - 1500040101532 Marionette TRACE 382 <- [1,2,null,{}]
06:48:21 INFO - 1500040101536 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:48:21 INFO - 1500040101546 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0112e252-47cf-4628-ba3e-046b5201c208","ELEMENT":"0112e252-47cf-4628-ba3e-046b5201c208"}}]
06:48:21 INFO - 1500040101548 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0112e252-47cf-4628-ba3e-046b5201c208"}]
06:48:22 INFO - 1500040102068 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:48:22 INFO - 1500040102070 Marionette TRACE 382 <- [1,4,null,{}]
06:48:22 INFO - 1500040102073 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}]
06:48:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500040102080 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:48:22 INFO - 1500040102082 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1020ms
06:48:22 INFO - 1500040102088 Marionette TRACE 382 <- [1,6,null,{}]
06:48:22 INFO - TEST-START | test_click.py TestClick.test_input_file
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102094 Marionette DEBUG Closed connection 382
06:48:22 INFO - 1500040102095 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49891
06:48:22 INFO - 1500040102097 Marionette DEBUG Closed connection 383
06:48:22 INFO - 1500040102098 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49892
06:48:22 INFO - 1500040102101 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:22 INFO - 1500040102103 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102127 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102139 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a35f78db-ef6e-439d-bbe6-97427ae05e82","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102170 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:48:22 INFO - 1500040102172 Marionette TRACE 384 <- [1,2,null,{}]
06:48:22 INFO - 1500040102174 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:22 INFO - 1500040102175 Marionette TRACE 384 <- [1,3,null,{}]
06:48:22 INFO - 1500040102177 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:22 INFO - 1500040102179 Marionette TRACE 384 <- [1,4,null,{}]
06:48:22 INFO - 1500040102182 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}]
06:48:22 INFO - TEST-START: test_click.py TestClick.test_input_file1500040102193 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:48:22 INFO - 1500040102196 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102200 Marionette TRACE 384 <- [1,6,null,{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102206 Marionette DEBUG Closed connection 384
06:48:22 INFO - 1500040102207 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49893
06:48:22 INFO - 1500040102209 Marionette DEBUG Closed connection 385
06:48:22 INFO - 1500040102210 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49894
06:48:22 INFO - 1500040102233 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:22 INFO - 1500040102236 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102259 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102271 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fb66fdff-5dfa-4c7e-9910-78cd4e8e9b0c","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102280 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102285 Marionette TRACE 386 <- [1,2,null,{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102291 Marionette DEBUG Closed connection 386
06:48:22 INFO - 1500040102292 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49895
06:48:22 INFO - 1500040102294 Marionette DEBUG Closed connection 387
06:48:22 INFO - 1500040102295 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49896
06:48:22 INFO - 1500040102298 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:22 INFO - 1500040102301 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102328 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102343 Marionette TRACE 388 <- [1,1,null,{"sessionId":"06c34a86-bc72-48d1-843c-8280ac45c18a","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102349 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:48:22 INFO - 1500040102353 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"
06:48:22 INFO - 1500040102362 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"
06:48:22 INFO - 1500040102364 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"
06:48:22 INFO - ++DOMWINDOW == 23 (000000731E4B7800) [pid = 3560] [serial = 46] [outer = 000000732433F000]
06:48:22 INFO - 1500040102420 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:48:22 INFO - 1500040102426 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:48:22 INFO - 1500040102444 Marionette TRACE 388 <- [1,2,null,{}]
06:48:22 INFO - 1500040102447 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:48:22 INFO - 1500040102470 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1acc4b7-6523-4f9b-83b2-a44994e195dc","ELEMENT":"c1acc4b7-6523-4f9b-83b2-a44994e195dc"}}]
06:48:22 INFO - 1500040102472 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c1acc4b7-6523-4f9b-83b2-a44994e195dc"}]
06:48:22 INFO - 1500040102663 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]
06:48:22 INFO - 1500040102667 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}]
06:48:22 INFO - TEST-END: test_click.py TestClick.test_input_file1500040102675 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:48:22 INFO - 1500040102677 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102681 Marionette TRACE 388 <- [1,6,null,{}]
06:48:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 593ms
06:48:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102687 Marionette DEBUG Closed connection 388
06:48:22 INFO - 1500040102689 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49897
06:48:22 INFO - 1500040102690 Marionette DEBUG Closed connection 389
06:48:22 INFO - 1500040102692 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49898
06:48:22 INFO - 1500040102714 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:22 INFO - 1500040102716 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102739 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102758 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9240ff7d-13d0-47ba-b679-ee1966bf65af","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102768 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:48:22 INFO - 1500040102770 Marionette TRACE 390 <- [1,2,null,{}]
06:48:22 INFO - 1500040102772 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:22 INFO - 1500040102774 Marionette TRACE 390 <- [1,3,null,{}]
06:48:22 INFO - 1500040102776 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:22 INFO - 1500040102778 Marionette TRACE 390 <- [1,4,null,{}]
06:48:22 INFO - 1500040102781 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}]
06:48:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500040102788 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:48:22 INFO - 1500040102790 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102794 Marionette TRACE 390 <- [1,6,null,{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102817 Marionette DEBUG Closed connection 390
06:48:22 INFO - 1500040102819 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49899
06:48:22 INFO - 1500040102823 Marionette DEBUG Closed connection 391
06:48:22 INFO - 1500040102824 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49900
06:48:22 INFO - 1500040102826 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:22 INFO - 1500040102828 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102851 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102862 Marionette TRACE 392 <- [1,1,null,{"sessionId":"07494e7a-cb81-4a19-a608-653162a69c39","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102872 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102876 Marionette TRACE 392 <- [1,2,null,{}]
06:48:22 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:22 INFO - 1500040102897 Marionette DEBUG Closed connection 392
06:48:22 INFO - 1500040102899 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49901
06:48:22 INFO - 1500040102903 Marionette DEBUG Closed connection 393
06:48:22 INFO - 1500040102904 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49902
06:48:22 INFO - 1500040102907 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:22 INFO - 1500040102909 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:22 INFO - 1500040102932 Marionette DEBUG Register listener.js for window 15
06:48:22 INFO - 1500040102943 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5acd8e7a-22ec-4331-a4b6-b946721cea3b","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:22 INFO - 1500040102953 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"}]
06:48:22 INFO - 1500040102957 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:48:22 INFO - 1500040102963 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:48:22 INFO - 1500040102964 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:48:22 INFO - ++DOMWINDOW == 24 (0000007323C61000) [pid = 3560] [serial = 47] [outer = 000000732433F000]
06:48:23 INFO - 1500040103020 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"
06:48:23 INFO - 1500040103026 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"
06:48:23 INFO - 1500040103041 Marionette TRACE 394 <- [1,2,null,{}]
06:48:23 INFO - 1500040103047 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:48:23 INFO - 1500040103056 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fd438d38-2102-4eb9-af0c-bee2cb5789e1","ELEMENT":"fd438d38-2102-4eb9-af0c-bee2cb5789e1"}}]
06:48:23 INFO - 1500040103057 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:48:23 INFO - 1500040103064 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ab71778-2e27-4335-9c6a-b648b0fae80e","ELEMENT":"4ab71778-2e27-4335-9c6a-b648b0fae80e"}}]
06:48:23 INFO - 1500040103066 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fd438d38-2102-4eb9-af0c-bee2cb5789e1"}]
06:48:23 INFO - 1500040103965 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:48:23 INFO - 1500040103979 Marionette TRACE 394 <- [1,5,null,{}]
06:48:23 INFO - 1500040103982 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4ab71778-2e27-4335-9c6a-b648b0fae80e"}]
06:48:24 INFO - 1500040104149 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]
06:48:24 INFO - 1500040104152 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:48:24 INFO - 1500040104160 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:48:24 INFO - 1500040104165 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}]
06:48:24 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500040104173 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:48:24 INFO - 1500040104175 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104179 Marionette TRACE 394 <- [1,9,null,{}]
06:48:24 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1497ms
06:48:24 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104185 Marionette DEBUG Closed connection 394
06:48:24 INFO - 1500040104186 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49903
06:48:24 INFO - 1500040104188 Marionette DEBUG Closed connection 395
06:48:24 INFO - 1500040104189 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49904
06:48:24 INFO - 1500040104192 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:24 INFO - 1500040104194 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:24 INFO - 1500040104217 Marionette DEBUG Register listener.js for window 15
06:48:24 INFO - 1500040104227 Marionette TRACE 396 <- [1,1,null,{"sessionId":"be0cdff1-1b0d-4cbf-a1bf-a1154f917264","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:24 INFO - 1500040104256 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:48:24 INFO - 1500040104258 Marionette TRACE 396 <- [1,2,null,{}]
06:48:24 INFO - 1500040104260 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:48:24 INFO - 1500040104261 Marionette TRACE 396 <- [1,3,null,{}]
06:48:24 INFO - 1500040104263 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:48:24 INFO - 1500040104265 Marionette TRACE 396 <- [1,4,null,{}]
06:48:24 INFO - 1500040104268 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}]
06:48:24 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500040104281 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:48:24 INFO - 1500040104283 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104287 Marionette TRACE 396 <- [1,6,null,{}]
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104311 Marionette DEBUG Closed connection 396
06:48:24 INFO - 1500040104312 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49905
06:48:24 INFO - 1500040104314 Marionette DEBUG Closed connection 397
06:48:24 INFO - 1500040104315 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49906
06:48:24 INFO - 1500040104317 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:48:24 INFO - 1500040104319 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:24 INFO - 1500040104342 Marionette DEBUG Register listener.js for window 15
06:48:24 INFO - 1500040104353 Marionette TRACE 398 <- [1,1,null,{"sessionId":"578c499a-c0e1-4acc-9eef-56c57ddcbeb9","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:24 INFO - 1500040104365 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104369 Marionette TRACE 398 <- [1,2,null,{}]
06:48:24 INFO - [Parent 3560] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
06:48:24 INFO - 1500040104390 Marionette DEBUG Closed connection 398
06:48:24 INFO - 1500040104391 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49907
06:48:24 INFO - 1500040104396 Marionette DEBUG Closed connection 399
06:48:24 INFO - 1500040104397 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49908
06:48:24 INFO - 1500040104451 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:48:24 INFO - 1500040104463 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}
06:48:24 INFO - 1500040104516 Marionette DEBUG Register listener.js for window 15
06:48:24 INFO - 1500040104539 Marionette TRACE 400 <- [1,1,null,{"sessionId":"2b9fbbd6-7f3c-474e-aa5c-a2d863266c23","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-224.001\\AppData\\Local\\Temp\\tmpmgeuom.mozrunner","moz:accessibilityChecks":false}}]
06:48:24 INFO - 1500040104557 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"}]
06:48:24 INFO - 1500040104570 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"
06:48:24 INFO - 1500040104590 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"
06:48:24 INFO - 1500040104591 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"
06:48:24 INFO - ++DOMWINDOW == 25 (00000073181C3800) [pid = 3560] [serial = 48] [outer = 000000732433F000]
06:48:24 INFO - 1500040104683 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"
06:48:24 INFO - 1500040104691 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"
06:48:24 INFO - 1500040104719 Marionette TRACE 400 <- [1,2,null,{}]
06:48:24 INFO - 1500040104729 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:48:24 INFO - 1500040104748 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"522380a0-7ac1-4294-ac17-7b0426f6f29a","ELEMENT":"522380a0-7ac1-4294-ac17-7b0426f6f29a"}}]
06:48:24 INFO - 1500040104761 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"522380a0-7ac1-4294-ac17-7b0426f6f29a"}]
06:48:24 INFO - 1500040104977 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:48:25 INFO - 1500040105000 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"522380a0-7ac1-4294-ac17-7b0426f6f29a"}]
06:48:25 INFO - 1500040105333 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element