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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:29:58 INFO - 1499387398718 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}]
17:29:58 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499387398727 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:29:58 INFO - 1499387398729 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398733 Marionette TRACE 352 <- [1,6,null,{}]
17:29:58 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 651ms
17:29:58 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398739 Marionette DEBUG Closed connection 352
17:29:58 INFO - 1499387398740 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49780
17:29:58 INFO - 1499387398742 Marionette DEBUG Closed connection 353
17:29:58 INFO - 1499387398743 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
17:29:58 INFO - 1499387398745 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:29:58 INFO - 1499387398748 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:29:58 INFO - 1499387398770 Marionette DEBUG Register listener.js for window 12
17:29:58 INFO - 1499387398782 Marionette TRACE 354 <- [1,1,null,{"sessionId":"793a4cf4-41d2-461e-9f2e-815d3b19d38a","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:29:58 INFO - 1499387398785 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:29:58 INFO - 1499387398786 Marionette TRACE 354 <- [1,2,null,{}]
17:29:58 INFO - 1499387398788 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:29:58 INFO - 1499387398790 Marionette TRACE 354 <- [1,3,null,{}]
17:29:58 INFO - 1499387398792 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:29:58 INFO - 1499387398794 Marionette TRACE 354 <- [1,4,null,{}]
17:29:58 INFO - 1499387398796 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}]
17:29:58 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499387398804 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:29:58 INFO - 1499387398806 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398810 Marionette TRACE 354 <- [1,6,null,{}]
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398816 Marionette DEBUG Closed connection 354
17:29:58 INFO - 1499387398818 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
17:29:58 INFO - 1499387398819 Marionette DEBUG Closed connection 355
17:29:58 INFO - 1499387398821 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
17:29:58 INFO - 1499387398823 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:29:58 INFO - 1499387398826 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:29:58 INFO - 1499387398852 Marionette DEBUG Register listener.js for window 12
17:29:58 INFO - 1499387398866 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f0eb9293-4752-48c8-8f04-01108ddcd044","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:29:58 INFO - 1499387398869 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398873 Marionette TRACE 356 <- [1,2,null,{}]
17:29:58 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:58 INFO - 1499387398879 Marionette DEBUG Closed connection 356
17:29:58 INFO - 1499387398880 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
17:29:58 INFO - 1499387398882 Marionette DEBUG Closed connection 357
17:29:58 INFO - 1499387398883 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
17:29:58 INFO - 1499387398885 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:29:58 INFO - 1499387398888 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:29:58 INFO - 1499387398909 Marionette DEBUG Register listener.js for window 12
17:29:58 INFO - 1499387398942 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8f3a06d3-8ef8-46fe-a1a9-f6836ee18526","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:29:59 INFO - 1499387399016 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49331/clicks.html"}]
17:29:59 INFO - 1499387399020 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/hidden.html"
17:29:59 INFO - 1499387399033 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/hidden.html"
17:29:59 INFO - 1499387399033 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/hidden.html"
17:29:59 INFO - ++DOMWINDOW == 22 (0000008DB6E1A800) [pid = 2880] [serial = 44] [outer = 0000008DADA11000]
17:29:59 INFO - 1499387399093 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/clicks.html"
17:29:59 INFO - 1499387399098 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/clicks.html"
17:29:59 INFO - 1499387399117 Marionette TRACE 358 <- [1,2,null,{}]
17:29:59 INFO - 1499387399121 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:29:59 INFO - 1499387399131 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43fba9e3-910f-4573-96ac-62a37b99cf3c","ELEMENT":"43fba9e3-910f-4573-96ac-62a37b99cf3c"}}]
17:29:59 INFO - 1499387399139 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"43fba9e3-910f-4573-96ac-62a37b99cf3c"}]
17:29:59 INFO - 1499387399394 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/clicks.html"
17:29:59 INFO - [2880] 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
17:29:59 INFO - 1499387399414 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/clicks.html"
17:29:59 INFO - 1499387399416 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/clicks.html"
17:29:59 INFO - ++DOMWINDOW == 23 (0000008DAD0D1800) [pid = 2880] [serial = 45] [outer = 0000008DADA11000]
17:29:59 INFO - 1499387399489 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/test.html"
17:29:59 INFO - 1499387399495 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/test.html"
17:29:59 INFO - 1499387399512 Marionette TRACE 358 <- [1,4,null,{}]
17:29:59 INFO - 1499387399521 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:29:59 INFO - 1499387399528 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee11a47e-a98b-4158-ba21-b9fb3ec0318a","ELEMENT":"ee11a47e-a98b-4158-ba21-b9fb3ec0318a"}}]
17:29:59 INFO - 1499387399530 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:29:59 INFO - 1499387399532 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:29:59 INFO - 1499387399535 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}]
17:29:59 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499387399544 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:29:59 INFO - 1499387399546 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:29:59 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:59 INFO - 1499387399550 Marionette TRACE 358 <- [1,8,null,{}]
17:29:59 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 816ms
17:29:59 INFO - TEST-START | test_click.py TestClick.test_container_element
17:29:59 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:29:59 INFO - 1499387399557 Marionette DEBUG Closed connection 358
17:29:59 INFO - 1499387399558 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
17:29:59 INFO - 1499387399560 Marionette DEBUG Closed connection 359
17:29:59 INFO - 1499387399561 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
17:29:59 INFO - 1499387399564 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:29:59 INFO - 1499387399566 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:29:59 INFO - 1499387399588 Marionette DEBUG Register listener.js for window 12
17:29:59 INFO - 1499387399600 Marionette TRACE 360 <- [1,1,null,{"sessionId":"fe9ec60f-9f50-4fa6-8b0a-1eb28e37c807","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:00 INFO - 1499387400286 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:30:00 INFO - 1499387400288 Marionette TRACE 360 <- [1,2,null,{}]
17:30:00 INFO - 1499387400292 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:00 INFO - 1499387400294 Marionette TRACE 360 <- [1,3,null,{}]
17:30:00 INFO - 1499387400330 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:00 INFO - 1499387400332 Marionette TRACE 360 <- [1,4,null,{}]
17:30:00 INFO - 1499387400335 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}]
17:30:00 INFO - TEST-START: test_click.py TestClick.test_container_element1499387400342 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:30:00 INFO - 1499387400344 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:30:00 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:00 INFO - 1499387400348 Marionette TRACE 360 <- [1,6,null,{}]
17:30:00 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:00 INFO - 1499387400354 Marionette DEBUG Closed connection 360
17:30:00 INFO - 1499387400356 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
17:30:00 INFO - 1499387400357 Marionette DEBUG Closed connection 361
17:30:00 INFO - 1499387400359 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
17:30:00 INFO - 1499387400361 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:00 INFO - 1499387400363 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:00 INFO - 1499387400384 Marionette DEBUG Register listener.js for window 12
17:30:00 INFO - 1499387400395 Marionette TRACE 362 <- [1,1,null,{"sessionId":"2383d3e5-7804-4ec1-9cfa-b65cac207e9c","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:00 INFO - 1499387400466 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:30:00 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:00 INFO - 1499387400475 Marionette TRACE 362 <- [1,2,null,{}]
17:30:00 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:00 INFO - 1499387400500 Marionette DEBUG Closed connection 362
17:30:00 INFO - 1499387400504 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
17:30:00 INFO - 1499387400509 Marionette DEBUG Closed connection 363
17:30:00 INFO - 1499387400512 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
17:30:00 INFO - 1499387400520 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:00 INFO - 1499387400530 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:00 INFO - 1499387400575 Marionette DEBUG Register listener.js for window 12
17:30:00 INFO - 1499387400597 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4b6a0861-ee49-40e4-a34b-e581d0583a83","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:00 INFO - 1499387400604 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"}]
17:30:00 INFO - 1499387400623 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/test.html"
17:30:00 INFO - 1499387400635 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/test.html"
17:30:00 INFO - 1499387400636 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/test.html"
17:30:00 INFO - ++DOMWINDOW == 24 (0000008DA1597800) [pid = 2880] [serial = 46] [outer = 0000008DADA11000]
17:30:00 INFO - 1499387400705 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"
17:30:00 INFO - 1499387400713 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"
17:30:00 INFO - 1499387400736 Marionette TRACE 364 <- [1,2,null,{}]
17:30:00 INFO - 1499387400741 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:30:00 INFO - 1499387400756 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0e9709b5-00f8-460b-87f2-0bc75a439c3d","ELEMENT":"0e9709b5-00f8-460b-87f2-0bc75a439c3d"}}]
17:30:00 INFO - 1499387400760 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0e9709b5-00f8-460b-87f2-0bc75a439c3d"}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:30:01 INFO - 1499387401367 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:01 INFO - 1499387401370 Marionette TRACE 364 <- [1,4,null,{}]
17:30:01 INFO - 1499387401375 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0e9709b5-00f8-460b-87f2-0bc75a439c3d","name":"selected"}]
17:30:01 INFO - 1499387401433 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:30:01 INFO - 1499387401442 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}]
17:30:01 INFO - TEST-END: test_click.py TestClick.test_container_element1499387401455 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:30:01 INFO - 1499387401458 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401465 Marionette TRACE 364 <- [1,7,null,{}]
17:30:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1914ms
17:30:01 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401491 Marionette DEBUG Closed connection 364
17:30:01 INFO - 1499387401495 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
17:30:01 INFO - 1499387401500 Marionette DEBUG Closed connection 365
17:30:01 INFO - 1499387401502 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
17:30:01 INFO - 1499387401527 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:01 INFO - 1499387401531 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:01 INFO - 1499387401577 Marionette DEBUG Register listener.js for window 12
17:30:01 INFO - 1499387401598 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8b50daa4-3a92-4a09-9d67-215e24a8eb86","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:01 INFO - 1499387401616 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:30:01 INFO - 1499387401619 Marionette TRACE 366 <- [1,2,null,{}]
17:30:01 INFO - 1499387401627 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:01 INFO - 1499387401634 Marionette TRACE 366 <- [1,3,null,{}]
17:30:01 INFO - 1499387401660 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:01 INFO - 1499387401662 Marionette TRACE 366 <- [1,4,null,{}]
17:30:01 INFO - 1499387401666 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}]
17:30:01 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499387401677 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:30:01 INFO - 1499387401722 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401729 Marionette TRACE 366 <- [1,6,null,{}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401754 Marionette DEBUG Closed connection 366
17:30:01 INFO - 1499387401756 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
17:30:01 INFO - 1499387401762 Marionette DEBUG Closed connection 367
17:30:01 INFO - 1499387401764 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
17:30:01 INFO - 1499387401817 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:01 INFO - 1499387401820 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:01 INFO - 1499387401865 Marionette DEBUG Register listener.js for window 12
17:30:01 INFO - 1499387401882 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d2d6e417-f566-418c-93be-fc6c291701ca","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:01 INFO - 1499387401890 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401895 Marionette TRACE 368 <- [1,2,null,{}]
17:30:01 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:01 INFO - 1499387401951 Marionette DEBUG Closed connection 368
17:30:01 INFO - 1499387401953 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
17:30:01 INFO - 1499387401974 Marionette DEBUG Closed connection 369
17:30:01 INFO - 1499387401976 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
17:30:01 INFO - 1499387401980 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:01 INFO - 1499387401983 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:02 INFO - 1499387402030 Marionette DEBUG Register listener.js for window 12
17:30:02 INFO - 1499387402048 Marionette TRACE 370 <- [1,1,null,{"sessionId":"986032c5-4bc9-4c9c-93cb-6b6d30870edc","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:02 INFO - 1499387402070 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"}]
17:30:02 INFO - 1499387402078 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"
17:30:02 INFO - 1499387402088 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"
17:30:02 INFO - 1499387402089 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"
17:30:02 INFO - ++DOMWINDOW == 25 (0000008DAD247000) [pid = 2880] [serial = 47] [outer = 0000008DADA11000]
17:30:02 INFO - 1499387402158 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"
17:30:02 INFO - 1499387402165 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"
17:30:02 INFO - 1499387402187 Marionette TRACE 370 <- [1,2,null,{}]
17:30:02 INFO - 1499387402198 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:30:02 INFO - 1499387402207 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"98fe2d07-d1dc-4600-8e65-777073102d39","ELEMENT":"98fe2d07-d1dc-4600-8e65-777073102d39"}}]
17:30:02 INFO - 1499387402209 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"98fe2d07-d1dc-4600-8e65-777073102d39"}]
17:30:02 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:30:02 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:30:02 INFO - [2880] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:30:02 INFO - 1499387402901 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:02 INFO - 1499387402904 Marionette TRACE 370 <- [1,4,null,{}]
17:30:02 INFO - 1499387402910 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"98fe2d07-d1dc-4600-8e65-777073102d39","name":"selected"}]
17:30:02 INFO - 1499387402949 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:30:02 INFO - 1499387402959 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}]
17:30:02 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499387402972 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:30:02 INFO - 1499387402976 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:30:02 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:02 INFO - 1499387402985 Marionette TRACE 370 <- [1,7,null,{}]
17:30:02 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1519ms
17:30:02 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:30:03 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:03 INFO - 1499387402993 Marionette DEBUG Closed connection 370
17:30:03 INFO - 1499387402996 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
17:30:03 INFO - 1499387403000 Marionette DEBUG Closed connection 371
17:30:03 INFO - 1499387403003 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
17:30:03 INFO - 1499387403010 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:03 INFO - 1499387403020 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:03 INFO - 1499387403046 Marionette DEBUG Register listener.js for window 12
17:30:03 INFO - 1499387403078 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ae27c683-13e5-4037-8031-838dde5a5eba","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:03 INFO - 1499387403083 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:30:03 INFO - 1499387403088 Marionette TRACE 372 <- [1,2,null,{}]
17:30:03 INFO - 1499387403111 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:03 INFO - 1499387403114 Marionette TRACE 372 <- [1,3,null,{}]
17:30:03 INFO - 1499387403143 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:03 INFO - 1499387403145 Marionette TRACE 372 <- [1,4,null,{}]
17:30:03 INFO - 1499387403148 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}]
17:30:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499387403159 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:30:03 INFO - 1499387403163 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:30:03 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:03 INFO - 1499387403168 Marionette TRACE 372 <- [1,6,null,{}]
17:30:03 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:03 INFO - 1499387403175 Marionette DEBUG Closed connection 372
17:30:03 INFO - 1499387403189 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
17:30:03 INFO - 1499387403211 Marionette DEBUG Closed connection 373
17:30:03 INFO - 1499387403213 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
17:30:03 INFO - 1499387403216 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:03 INFO - 1499387403221 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:03 INFO - 1499387403247 Marionette DEBUG Register listener.js for window 12
17:30:03 INFO - 1499387403264 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6133d81a-64e2-4d3f-9e09-b01098bee889","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:03 INFO - 1499387403319 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:30:03 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:03 INFO - 1499387403328 Marionette TRACE 374 <- [1,2,null,{}]
17:30:03 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:03 INFO - 1499387403371 Marionette DEBUG Closed connection 374
17:30:03 INFO - 1499387403373 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
17:30:03 INFO - 1499387403380 Marionette DEBUG Closed connection 375
17:30:03 INFO - 1499387403382 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
17:30:03 INFO - 1499387403388 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:03 INFO - 1499387403392 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:03 INFO - 1499387403414 Marionette DEBUG Register listener.js for window 12
17:30:03 INFO - 1499387403429 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a95faa77-5cd5-4f56-aa35-833676acc2b7","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:03 INFO - 1499387403435 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"}]
17:30:03 INFO - 1499387403440 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"
17:30:03 INFO - 1499387403450 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"
17:30:03 INFO - 1499387403450 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"
17:30:03 INFO - ++DOMWINDOW == 26 (0000008DA1B5E000) [pid = 2880] [serial = 48] [outer = 0000008DADA11000]
17:30:03 INFO - 1499387403509 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"
17:30:03 INFO - 1499387403517 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"
17:30:03 INFO - 1499387403536 Marionette TRACE 376 <- [1,2,null,{}]
17:30:03 INFO - 1499387403545 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:30:03 INFO - 1499387403554 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb63f44f-cf04-4d65-b86a-16d361f3bc46","ELEMENT":"fb63f44f-cf04-4d65-b86a-16d361f3bc46"}}]
17:30:03 INFO - 1499387403556 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"fb63f44f-cf04-4d65-b86a-16d361f3bc46"}]
17:30:03 INFO - --DOMWINDOW == 25 (0000008DB5AC8000) [pid = 2880] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49331/test_accessibility.html]
17:30:03 INFO - --DOMWINDOW == 24 (0000008DA21C4000) [pid = 2880] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49331/test_accessibility.html]
17:30:03 INFO - --DOMWINDOW == 23 (0000008DAC877800) [pid = 2880] [serial = 37] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
17:30:03 INFO - --DOMWINDOW == 22 (0000008DA9CA7800) [pid = 2880] [serial = 36] [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]
17:30:03 INFO - 1499387403986 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:03 INFO - 1499387403988 Marionette TRACE 376 <- [1,4,null,{}]
17:30:03 INFO - 1499387403990 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}]
17:30:04 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499387403999 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:30:04 INFO - 1499387404001 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404005 Marionette TRACE 376 <- [1,6,null,{}]
17:30:04 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1019ms
17:30:04 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404011 Marionette DEBUG Closed connection 376
17:30:04 INFO - 1499387404013 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
17:30:04 INFO - 1499387404015 Marionette DEBUG Closed connection 377
17:30:04 INFO - 1499387404017 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
17:30:04 INFO - 1499387404019 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:04 INFO - 1499387404022 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:04 INFO - 1499387404045 Marionette DEBUG Register listener.js for window 12
17:30:04 INFO - 1499387404062 Marionette TRACE 378 <- [1,1,null,{"sessionId":"b0d3707e-cc11-4c3c-94a6-8da65beb2112","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:04 INFO - 1499387404065 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:30:04 INFO - 1499387404066 Marionette TRACE 378 <- [1,2,null,{}]
17:30:04 INFO - 1499387404068 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:04 INFO - 1499387404071 Marionette TRACE 378 <- [1,3,null,{}]
17:30:04 INFO - 1499387404072 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:04 INFO - 1499387404074 Marionette TRACE 378 <- [1,4,null,{}]
17:30:04 INFO - 1499387404077 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}]
17:30:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499387404086 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:30:04 INFO - 1499387404089 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404094 Marionette TRACE 378 <- [1,6,null,{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404100 Marionette DEBUG Closed connection 378
17:30:04 INFO - 1499387404102 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
17:30:04 INFO - 1499387404104 Marionette DEBUG Closed connection 379
17:30:04 INFO - 1499387404106 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
17:30:04 INFO - 1499387404108 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:04 INFO - 1499387404111 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:04 INFO - 1499387404134 Marionette DEBUG Register listener.js for window 12
17:30:04 INFO - 1499387404150 Marionette TRACE 380 <- [1,1,null,{"sessionId":"663a84b4-21bc-43e4-a12e-19527b956e64","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:04 INFO - 1499387404153 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404158 Marionette TRACE 380 <- [1,2,null,{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404166 Marionette DEBUG Closed connection 380
17:30:04 INFO - 1499387404171 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
17:30:04 INFO - 1499387404173 Marionette DEBUG Closed connection 381
17:30:04 INFO - 1499387404174 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
17:30:04 INFO - 1499387404177 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:04 INFO - 1499387404182 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:04 INFO - 1499387404205 Marionette DEBUG Register listener.js for window 12
17:30:04 INFO - 1499387404220 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ca772125-800e-42d1-a104-1cf42b3da50b","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:04 INFO - 1499387404223 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"}]
17:30:04 INFO - 1499387404227 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"
17:30:04 INFO - 1499387404244 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"
17:30:04 INFO - 1499387404245 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"
17:30:04 INFO - ++DOMWINDOW == 23 (0000008DAA38B800) [pid = 2880] [serial = 49] [outer = 0000008DADA11000]
17:30:04 INFO - 1499387404298 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"
17:30:04 INFO - 1499387404313 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"
17:30:04 INFO - 1499387404331 Marionette TRACE 382 <- [1,2,null,{}]
17:30:04 INFO - 1499387404335 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:30:04 INFO - 1499387404345 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e81f32d6-8624-4a0a-a57a-d9a104382dac","ELEMENT":"e81f32d6-8624-4a0a-a57a-d9a104382dac"}}]
17:30:04 INFO - 1499387404347 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e81f32d6-8624-4a0a-a57a-d9a104382dac"}]
17:30:04 INFO - 1499387404812 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:04 INFO - 1499387404814 Marionette TRACE 382 <- [1,4,null,{}]
17:30:04 INFO - 1499387404817 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}]
17:30:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499387404826 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:30:04 INFO - 1499387404828 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404832 Marionette TRACE 382 <- [1,6,null,{}]
17:30:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 827ms
17:30:04 INFO - TEST-START | test_click.py TestClick.test_input_file
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404838 Marionette DEBUG Closed connection 382
17:30:04 INFO - 1499387404839 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
17:30:04 INFO - 1499387404841 Marionette DEBUG Closed connection 383
17:30:04 INFO - 1499387404842 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
17:30:04 INFO - 1499387404845 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:04 INFO - 1499387404847 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:04 INFO - 1499387404870 Marionette DEBUG Register listener.js for window 12
17:30:04 INFO - 1499387404882 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e8627d99-1945-41ab-bd85-f7c4fc156fa4","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:04 INFO - 1499387404885 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:30:04 INFO - 1499387404887 Marionette TRACE 384 <- [1,2,null,{}]
17:30:04 INFO - 1499387404889 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:04 INFO - 1499387404890 Marionette TRACE 384 <- [1,3,null,{}]
17:30:04 INFO - 1499387404892 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:04 INFO - 1499387404894 Marionette TRACE 384 <- [1,4,null,{}]
17:30:04 INFO - 1499387404896 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}]
17:30:04 INFO - TEST-START: test_click.py TestClick.test_input_file1499387404905 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:30:04 INFO - 1499387404907 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404911 Marionette TRACE 384 <- [1,6,null,{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404919 Marionette DEBUG Closed connection 384
17:30:04 INFO - 1499387404920 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
17:30:04 INFO - 1499387404922 Marionette DEBUG Closed connection 385
17:30:04 INFO - 1499387404923 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
17:30:04 INFO - 1499387404925 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:04 INFO - 1499387404928 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:04 INFO - 1499387404950 Marionette DEBUG Register listener.js for window 12
17:30:04 INFO - 1499387404962 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6528a468-bd38-48ce-92c7-67e76a96a072","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:04 INFO - 1499387404965 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404969 Marionette TRACE 386 <- [1,2,null,{}]
17:30:04 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:04 INFO - 1499387404975 Marionette DEBUG Closed connection 386
17:30:04 INFO - 1499387404976 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
17:30:04 INFO - 1499387404979 Marionette DEBUG Closed connection 387
17:30:04 INFO - 1499387404981 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
17:30:04 INFO - 1499387404983 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:04 INFO - 1499387404987 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:05 INFO - 1499387405010 Marionette DEBUG Register listener.js for window 12
17:30:05 INFO - 1499387405022 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9b3fe59c-5b73-4b68-8924-29f2ab1b77bd","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:05 INFO - 1499387405025 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:30:05 INFO - 1499387405029 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"
17:30:05 INFO - 1499387405036 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"
17:30:05 INFO - 1499387405037 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"
17:30:05 INFO - ++DOMWINDOW == 24 (0000008DAD4AA800) [pid = 2880] [serial = 50] [outer = 0000008DADA11000]
17:30:05 INFO - 1499387405097 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:05 INFO - 1499387405103 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:05 INFO - 1499387405119 Marionette TRACE 388 <- [1,2,null,{}]
17:30:05 INFO - 1499387405122 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:30:05 INFO - 1499387405130 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ada63838-0111-4446-bfd6-d7fbb9dc896f","ELEMENT":"ada63838-0111-4446-bfd6-d7fbb9dc896f"}}]
17:30:05 INFO - 1499387405133 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ada63838-0111-4446-bfd6-d7fbb9dc896f"}]
17:30:05 INFO - 1499387405284 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:30:05 INFO - 1499387405287 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}]
17:30:05 INFO - TEST-END: test_click.py TestClick.test_input_file1499387405296 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:30:05 INFO - 1499387405298 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405302 Marionette TRACE 388 <- [1,6,null,{}]
17:30:05 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 470ms
17:30:05 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405308 Marionette DEBUG Closed connection 388
17:30:05 INFO - 1499387405309 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
17:30:05 INFO - 1499387405310 Marionette DEBUG Closed connection 389
17:30:05 INFO - 1499387405312 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
17:30:05 INFO - 1499387405314 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:05 INFO - 1499387405316 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:05 INFO - 1499387405339 Marionette DEBUG Register listener.js for window 12
17:30:05 INFO - 1499387405351 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d61476da-5add-4075-9fcd-2f56a8fe3550","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:05 INFO - 1499387405354 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:30:05 INFO - 1499387405355 Marionette TRACE 390 <- [1,2,null,{}]
17:30:05 INFO - 1499387405357 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:05 INFO - 1499387405359 Marionette TRACE 390 <- [1,3,null,{}]
17:30:05 INFO - 1499387405361 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:05 INFO - 1499387405362 Marionette TRACE 390 <- [1,4,null,{}]
17:30:05 INFO - 1499387405365 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}]
17:30:05 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499387405373 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:30:05 INFO - 1499387405375 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405379 Marionette TRACE 390 <- [1,6,null,{}]
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405385 Marionette DEBUG Closed connection 390
17:30:05 INFO - 1499387405386 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
17:30:05 INFO - 1499387405388 Marionette DEBUG Closed connection 391
17:30:05 INFO - 1499387405390 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
17:30:05 INFO - 1499387405392 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:05 INFO - 1499387405395 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:05 INFO - 1499387405423 Marionette DEBUG Register listener.js for window 12
17:30:05 INFO - 1499387405437 Marionette TRACE 392 <- [1,1,null,{"sessionId":"428ffd7d-22b0-4b6b-8ee8-8c16cbdc609e","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:05 INFO - 1499387405439 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405443 Marionette TRACE 392 <- [1,2,null,{}]
17:30:05 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:05 INFO - 1499387405449 Marionette DEBUG Closed connection 392
17:30:05 INFO - 1499387405450 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
17:30:05 INFO - 1499387405452 Marionette DEBUG Closed connection 393
17:30:05 INFO - 1499387405453 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
17:30:05 INFO - 1499387405455 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:05 INFO - 1499387405458 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:05 INFO - 1499387405482 Marionette DEBUG Register listener.js for window 12
17:30:05 INFO - 1499387405494 Marionette TRACE 394 <- [1,1,null,{"sessionId":"348e393a-13c5-4339-b9ee-61b4493be950","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:05 INFO - 1499387405497 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"}]
17:30:05 INFO - 1499387405501 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:05 INFO - 1499387405510 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:05 INFO - 1499387405511 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:30:05 INFO - ++DOMWINDOW == 25 (0000008DB0D09800) [pid = 2880] [serial = 51] [outer = 0000008DADA11000]
17:30:05 INFO - 1499387405573 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"
17:30:05 INFO - 1499387405579 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"
17:30:05 INFO - 1499387405588 Marionette TRACE 394 <- [1,2,null,{}]
17:30:05 INFO - 1499387405599 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:30:05 INFO - 1499387405607 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40f5647a-9561-49d9-ac21-ad81d6724bc8","ELEMENT":"40f5647a-9561-49d9-ac21-ad81d6724bc8"}}]
17:30:05 INFO - 1499387405608 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:30:05 INFO - 1499387405615 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce187186-4550-43c5-9347-45700a73b18d","ELEMENT":"ce187186-4550-43c5-9347-45700a73b18d"}}]
17:30:05 INFO - 1499387405617 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"40f5647a-9561-49d9-ac21-ad81d6724bc8"}]
17:30:06 INFO - 1499387406058 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:30:06 INFO - 1499387406060 Marionette TRACE 394 <- [1,5,null,{}]
17:30:06 INFO - 1499387406062 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ce187186-4550-43c5-9347-45700a73b18d"}]
17:30:06 INFO - 1499387406194 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:30:06 INFO - 1499387406198 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:30:06 INFO - 1499387406206 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:30:06 INFO - 1499387406208 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}]
17:30:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499387406215 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:30:06 INFO - 1499387406217 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406221 Marionette TRACE 394 <- [1,9,null,{}]
17:30:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 919ms
17:30:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406227 Marionette DEBUG Closed connection 394
17:30:06 INFO - 1499387406229 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
17:30:06 INFO - 1499387406230 Marionette DEBUG Closed connection 395
17:30:06 INFO - 1499387406232 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
17:30:06 INFO - 1499387406234 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:06 INFO - 1499387406236 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:06 INFO - 1499387406260 Marionette DEBUG Register listener.js for window 12
17:30:06 INFO - 1499387406272 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9c7c2165-defd-46e9-9fc1-10e665b03b2f","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:06 INFO - 1499387406275 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:30:06 INFO - 1499387406277 Marionette TRACE 396 <- [1,2,null,{}]
17:30:06 INFO - 1499387406279 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:30:06 INFO - 1499387406281 Marionette TRACE 396 <- [1,3,null,{}]
17:30:06 INFO - 1499387406283 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:30:06 INFO - 1499387406284 Marionette TRACE 396 <- [1,4,null,{}]
17:30:06 INFO - 1499387406287 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}]
17:30:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499387406298 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:30:06 INFO - 1499387406300 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406305 Marionette TRACE 396 <- [1,6,null,{}]
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406311 Marionette DEBUG Closed connection 396
17:30:06 INFO - 1499387406312 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
17:30:06 INFO - 1499387406314 Marionette DEBUG Closed connection 397
17:30:06 INFO - 1499387406316 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
17:30:06 INFO - 1499387406319 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:30:06 INFO - 1499387406321 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:06 INFO - 1499387406346 Marionette DEBUG Register listener.js for window 12
17:30:06 INFO - 1499387406357 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5051da15-0229-49ea-85d7-6363757ba4e3","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:06 INFO - 1499387406360 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406363 Marionette TRACE 398 <- [1,2,null,{}]
17:30:06 INFO - [2880] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:30:06 INFO - 1499387406369 Marionette DEBUG Closed connection 398
17:30:06 INFO - 1499387406371 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
17:30:06 INFO - 1499387406372 Marionette DEBUG Closed connection 399
17:30:06 INFO - 1499387406374 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
17:30:06 INFO - 1499387406376 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:30:06 INFO - 1499387406378 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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}
17:30:06 INFO - 1499387406403 Marionette DEBUG Register listener.js for window 12
17:30:06 INFO - 1499387406414 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ec96bfdd-8520-4143-925c-0b2abc1b3aef","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":2880,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-339.001\\AppData\\Local\\Temp\\tmpadqrcc.mozrunner","moz:accessibilityChecks":false}}]
17:30:06 INFO - 1499387406417 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"}]
17:30:06 INFO - 1499387406421 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"
17:30:06 INFO - 1499387406427 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"
17:30:06 INFO - 1499387406428 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"
17:30:06 INFO - ++DOMWINDOW == 26 (0000008DB574B800) [pid = 2880] [serial = 52] [outer = 0000008DADA11000]
17:30:06 INFO - 1499387406483 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"
17:30:06 INFO - 1499387406489 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"
17:30:06 INFO - 1499387406504 Marionette TRACE 400 <- [1,2,null,{}]
17:30:06 INFO - 1499387406914 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:30:06 INFO - 1499387406921 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d742f48f-1b42-4d26-b50d-8372099cae8b","ELEMENT":"d742f48f-1b42-4d26-b50d-8372099cae8b"}}]
17:30:06 INFO - 1499387406923 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d742f48f-1b42-4d26-b50d-8372099cae8b"}]
17:30:07 INFO - 1499387407045 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:30:07 INFO - 1499387407049 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d742f48f-1b42-4d26-b50d-8372099cae8b"}]
17:30:07 INFO - 1499387407188 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element