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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:37:39 INFO - 1500925059038 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}]
12:37:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500925059046 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:37:39 INFO - 1500925059048 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059053 Marionette TRACE 352 <- [1,6,null,{}]
12:37:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 696ms
12:37:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059076 Marionette DEBUG Closed connection 352
12:37:39 INFO - 1500925059078 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49788
12:37:39 INFO - 1500925059079 Marionette DEBUG Closed connection 353
12:37:39 INFO - 1500925059081 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49789
12:37:39 INFO - 1500925059085 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:39 INFO - 1500925059088 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:39 INFO - 1500925059108 Marionette DEBUG Register listener.js for window 12
12:37:39 INFO - 1500925059120 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1bfc58d9-13d8-455a-bb80-735b685c1730","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:39 INFO - 1500925059130 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:37:39 INFO - 1500925059132 Marionette TRACE 354 <- [1,2,null,{}]
12:37:39 INFO - 1500925059134 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:39 INFO - 1500925059136 Marionette TRACE 354 <- [1,3,null,{}]
12:37:39 INFO - 1500925059138 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:39 INFO - 1500925059140 Marionette TRACE 354 <- [1,4,null,{}]
12:37:39 INFO - 1500925059143 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}]
12:37:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500925059152 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:37:39 INFO - 1500925059154 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059158 Marionette TRACE 354 <- [1,6,null,{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059183 Marionette DEBUG Closed connection 354
12:37:39 INFO - 1500925059185 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49790
12:37:39 INFO - 1500925059189 Marionette DEBUG Closed connection 355
12:37:39 INFO - 1500925059190 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49791
12:37:39 INFO - 1500925059193 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:39 INFO - 1500925059196 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:39 INFO - 1500925059221 Marionette DEBUG Register listener.js for window 12
12:37:39 INFO - 1500925059235 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1b818060-4f1d-4259-a5ac-396d1c722428","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:39 INFO - 1500925059247 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059251 Marionette TRACE 356 <- [1,2,null,{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059274 Marionette DEBUG Closed connection 356
12:37:39 INFO - 1500925059275 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49792
12:37:39 INFO - 1500925059280 Marionette DEBUG Closed connection 357
12:37:39 INFO - 1500925059281 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49793
12:37:39 INFO - 1500925059284 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:39 INFO - 1500925059287 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:39 INFO - 1500925059308 Marionette DEBUG Register listener.js for window 12
12:37:39 INFO - 1500925059320 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d4905c21-0c77-44cf-8691-644a9179d254","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:39 INFO - 1500925059330 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
12:37:39 INFO - 1500925059334 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
12:37:39 INFO - 1500925059354 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
12:37:39 INFO - 1500925059355 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
12:37:39 INFO - ++DOMWINDOW == 20 (00000006EDAD3800) [pid = 2672] [serial = 41] [outer = 00000006E4605000]
12:37:39 INFO - 1500925059420 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
12:37:39 INFO - 1500925059426 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
12:37:39 INFO - 1500925059444 Marionette TRACE 358 <- [1,2,null,{}]
12:37:39 INFO - 1500925059451 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:37:39 INFO - 1500925059462 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46decbf3-d0b0-4b64-817b-5755d3251ccc","ELEMENT":"46decbf3-d0b0-4b64-817b-5755d3251ccc"}}]
12:37:39 INFO - 1500925059464 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"46decbf3-d0b0-4b64-817b-5755d3251ccc"}]
12:37:39 INFO - 1500925059712 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
12:37:39 INFO - [Parent 2672] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
12:37:39 INFO - 1500925059727 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
12:37:39 INFO - 1500925059728 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
12:37:39 INFO - ++DOMWINDOW == 21 (00000006EDAE2000) [pid = 2672] [serial = 42] [outer = 00000006E4605000]
12:37:39 INFO - 1500925059802 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
12:37:39 INFO - 1500925059809 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
12:37:39 INFO - 1500925059825 Marionette TRACE 358 <- [1,4,null,{}]
12:37:39 INFO - 1500925059836 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:37:39 INFO - 1500925059843 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80586557-4c9d-41b4-9872-acfef1bd0e33","ELEMENT":"80586557-4c9d-41b4-9872-acfef1bd0e33"}}]
12:37:39 INFO - 1500925059846 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:37:39 INFO - 1500925059847 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:37:39 INFO - 1500925059851 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}]
12:37:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500925059860 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:37:39 INFO - 1500925059866 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059870 Marionette TRACE 358 <- [1,8,null,{}]
12:37:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 818ms
12:37:39 INFO - TEST-START | test_click.py TestClick.test_container_element
12:37:39 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:39 INFO - 1500925059880 Marionette DEBUG Closed connection 358
12:37:39 INFO - 1500925059882 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49796
12:37:39 INFO - 1500925059884 Marionette DEBUG Closed connection 359
12:37:39 INFO - 1500925059885 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49797
12:37:39 INFO - 1500925059888 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:39 INFO - 1500925059890 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:39 INFO - 1500925059912 Marionette DEBUG Register listener.js for window 12
12:37:39 INFO - 1500925059927 Marionette TRACE 360 <- [1,1,null,{"sessionId":"63271915-bedf-4bbb-b648-9c25d2fb0602","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:40 INFO - 1500925060570 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:37:40 INFO - 1500925060573 Marionette TRACE 360 <- [1,2,null,{}]
12:37:40 INFO - 1500925060577 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:40 INFO - 1500925060579 Marionette TRACE 360 <- [1,3,null,{}]
12:37:40 INFO - 1500925060623 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:40 INFO - 1500925060625 Marionette TRACE 360 <- [1,4,null,{}]
12:37:40 INFO - 1500925060642 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}]
12:37:40 INFO - TEST-START: test_click.py TestClick.test_container_element1500925060661 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:37:40 INFO - 1500925060671 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:37:40 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:40 INFO - 1500925060675 Marionette TRACE 360 <- [1,6,null,{}]
12:37:40 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:40 INFO - 1500925060735 Marionette DEBUG Closed connection 360
12:37:40 INFO - 1500925060738 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49798
12:37:40 INFO - 1500925060760 Marionette DEBUG Closed connection 361
12:37:40 INFO - 1500925060763 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49799
12:37:40 INFO - 1500925060770 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:40 INFO - 1500925060780 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:40 INFO - 1500925060821 Marionette DEBUG Register listener.js for window 12
12:37:40 INFO - 1500925060843 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bff5e6d6-e724-43ec-a1a1-2e801c34df29","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:40 INFO - 1500925060858 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:37:40 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:40 INFO - 1500925060865 Marionette TRACE 362 <- [1,2,null,{}]
12:37:40 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:40 INFO - 1500925060906 Marionette DEBUG Closed connection 362
12:37:40 INFO - 1500925060912 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49800
12:37:40 INFO - 1500925060919 Marionette DEBUG Closed connection 363
12:37:40 INFO - 1500925060922 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49801
12:37:40 INFO - 1500925060977 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:40 INFO - 1500925060982 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:41 INFO - 1500925061023 Marionette DEBUG Register listener.js for window 12
12:37:41 INFO - 1500925061044 Marionette TRACE 364 <- [1,1,null,{"sessionId":"608b28dc-ee55-49e5-940a-2115e6a1e006","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:41 INFO - 1500925061058 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"}]
12:37:41 INFO - 1500925061065 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
12:37:41 INFO - 1500925061076 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
12:37:41 INFO - 1500925061077 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
12:37:41 INFO - ++DOMWINDOW == 22 (00000006DA3AD800) [pid = 2672] [serial = 43] [outer = 00000006E4605000]
12:37:41 INFO - 1500925061167 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"
12:37:41 INFO - 1500925061174 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"
12:37:41 INFO - 1500925061199 Marionette TRACE 364 <- [1,2,null,{}]
12:37:41 INFO - 1500925061207 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:37:41 INFO - 1500925061221 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92e0a016-2e84-4e6e-944a-eafabc3ba86c","ELEMENT":"92e0a016-2e84-4e6e-944a-eafabc3ba86c"}}]
12:37:41 INFO - 1500925061225 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"92e0a016-2e84-4e6e-944a-eafabc3ba86c"}]
12:37:41 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:37:41 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:37:41 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:37:41 INFO - 1500925061816 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:37:41 INFO - 1500925061819 Marionette TRACE 364 <- [1,4,null,{}]
12:37:41 INFO - 1500925061858 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"92e0a016-2e84-4e6e-944a-eafabc3ba86c","name":"selected"}]
12:37:41 INFO - 1500925061917 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:37:41 INFO - 1500925061921 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}]
12:37:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500925061933 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:37:41 INFO - 1500925061944 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:37:41 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:41 INFO - 1500925061950 Marionette TRACE 364 <- [1,7,null,{}]
12:37:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 2079ms
12:37:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:37:41 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:41 INFO - 1500925061958 Marionette DEBUG Closed connection 364
12:37:41 INFO - 1500925061960 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49802
12:37:42 INFO - 1500925062118 Marionette DEBUG Closed connection 365
12:37:42 INFO - 1500925062121 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49803
12:37:42 INFO - 1500925062153 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:42 INFO - 1500925062163 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:42 INFO - 1500925062188 Marionette DEBUG Register listener.js for window 12
12:37:42 INFO - 1500925062209 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9fae44e2-4a9c-4f57-b168-926d68b65a26","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:42 INFO - 1500925062244 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:37:42 INFO - 1500925062247 Marionette TRACE 366 <- [1,2,null,{}]
12:37:42 INFO - 1500925062264 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:42 INFO - 1500925062267 Marionette TRACE 366 <- [1,3,null,{}]
12:37:42 INFO - 1500925062272 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:42 INFO - 1500925062276 Marionette TRACE 366 <- [1,4,null,{}]
12:37:42 INFO - 1500925062338 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}]
12:37:42 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500925062352 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:37:42 INFO - 1500925062358 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:42 INFO - 1500925062366 Marionette TRACE 366 <- [1,6,null,{}]
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:42 INFO - 1500925062376 Marionette DEBUG Closed connection 366
12:37:42 INFO - 1500925062378 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49804
12:37:42 INFO - 1500925062401 Marionette DEBUG Closed connection 367
12:37:42 INFO - 1500925062405 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49805
12:37:42 INFO - 1500925062409 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:42 INFO - 1500925062414 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:42 INFO - 1500925062436 Marionette DEBUG Register listener.js for window 12
12:37:42 INFO - 1500925062454 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c22b4d7d-4324-496e-8105-3d56f4c121f3","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:42 INFO - 1500925062464 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:42 INFO - 1500925062470 Marionette TRACE 368 <- [1,2,null,{}]
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:42 INFO - 1500925062479 Marionette DEBUG Closed connection 368
12:37:42 INFO - 1500925062481 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49806
12:37:42 INFO - 1500925062506 Marionette DEBUG Closed connection 369
12:37:42 INFO - 1500925062509 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49807
12:37:42 INFO - 1500925062531 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:42 INFO - 1500925062535 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:42 INFO - 1500925062557 Marionette DEBUG Register listener.js for window 12
12:37:42 INFO - 1500925062574 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b5711fd9-dcf4-4dc7-bd69-a890661162a8","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:42 INFO - --DOMWINDOW == 21 (00000006DB14A800) [pid = 2672] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
12:37:42 INFO - --DOMWINDOW == 20 (00000006ECFC5000) [pid = 2672] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
12:37:42 INFO - --DOMWINDOW == 19 (00000006E3C44800) [pid = 2672] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%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]
12:37:42 INFO - --DOMWINDOW == 18 (00000006E4882000) [pid = 2672] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%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]
12:37:42 INFO - 1500925062592 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"}]
12:37:42 INFO - 1500925062598 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"
12:37:42 INFO - 1500925062608 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"
12:37:42 INFO - 1500925062609 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"
12:37:42 INFO - ++DOMWINDOW == 19 (00000006DD1E0800) [pid = 2672] [serial = 44] [outer = 00000006E4605000]
12:37:42 INFO - 1500925062671 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"
12:37:42 INFO - 1500925062677 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"
12:37:42 INFO - 1500925062699 Marionette TRACE 370 <- [1,2,null,{}]
12:37:42 INFO - 1500925062715 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:37:42 INFO - 1500925062727 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bc84216-440b-466d-ba46-9de5c879e535","ELEMENT":"8bc84216-440b-466d-ba46-9de5c879e535"}}]
12:37:42 INFO - 1500925062729 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8bc84216-440b-466d-ba46-9de5c879e535"}]
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
12:37:42 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
12:37:43 INFO - 1500925063170 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:37:43 INFO - 1500925063173 Marionette TRACE 370 <- [1,4,null,{}]
12:37:43 INFO - 1500925063175 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8bc84216-440b-466d-ba46-9de5c879e535","name":"selected"}]
12:37:43 INFO - 1500925063205 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:37:43 INFO - 1500925063208 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}]
12:37:43 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500925063218 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:37:43 INFO - 1500925063220 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:37:43 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:43 INFO - 1500925063225 Marionette TRACE 370 <- [1,7,null,{}]
12:37:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1274ms
12:37:43 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:37:43 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:43 INFO - 1500925063232 Marionette DEBUG Closed connection 370
12:37:43 INFO - 1500925063234 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49808
12:37:43 INFO - 1500925063236 Marionette DEBUG Closed connection 371
12:37:43 INFO - 1500925063237 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49809
12:37:43 INFO - 1500925063240 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:43 INFO - 1500925063243 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:43 INFO - 1500925063266 Marionette DEBUG Register listener.js for window 12
12:37:43 INFO - 1500925063280 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7fc835b5-97d6-4342-b5d7-339701da332c","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:43 INFO - 1500925063288 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:37:43 INFO - 1500925063290 Marionette TRACE 372 <- [1,2,null,{}]
12:37:43 INFO - 1500925063292 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:43 INFO - 1500925063294 Marionette TRACE 372 <- [1,3,null,{}]
12:37:43 INFO - 1500925063296 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:43 INFO - 1500925063298 Marionette TRACE 372 <- [1,4,null,{}]
12:37:43 INFO - 1500925063301 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}]
12:37:43 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500925063310 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:37:43 INFO - 1500925063312 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:37:43 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:43 INFO - 1500925063317 Marionette TRACE 372 <- [1,6,null,{}]
12:37:43 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:43 INFO - 1500925063323 Marionette DEBUG Closed connection 372
12:37:43 INFO - 1500925063325 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49810
12:37:43 INFO - 1500925063327 Marionette DEBUG Closed connection 373
12:37:43 INFO - 1500925063329 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49811
12:37:43 INFO - 1500925063334 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:43 INFO - 1500925063339 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:43 INFO - 1500925063361 Marionette DEBUG Register listener.js for window 12
12:37:43 INFO - 1500925063381 Marionette TRACE 374 <- [1,1,null,{"sessionId":"003a8a3c-9afc-4040-8aa8-69471aa0d48b","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:43 INFO - 1500925063388 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:37:43 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:43 INFO - 1500925063393 Marionette TRACE 374 <- [1,2,null,{}]
12:37:44 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:44 INFO - 1500925064085 Marionette DEBUG Closed connection 374
12:37:44 INFO - 1500925064087 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49812
12:37:44 INFO - 1500925064113 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49813
12:37:44 INFO - 1500925064117 Marionette DEBUG Closed connection 375
12:37:44 INFO - 1500925064124 Marionette DEBUG Closed connection 376
12:37:44 INFO - 1500925064126 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49814
12:37:44 INFO - 1500925064162 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:44 INFO - 1500925064165 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:44 INFO - 1500925064187 Marionette DEBUG Register listener.js for window 12
12:37:44 INFO - 1500925064201 Marionette TRACE 377 <- [1,1,null,{"sessionId":"bdb13392-0cec-4502-9737-e2722a62dd2c","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:44 INFO - 1500925064249 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
12:37:44 INFO - 1500925064254 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"
12:37:44 INFO - 1500925064263 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"
12:37:44 INFO - 1500925064263 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"
12:37:44 INFO - ++DOMWINDOW == 20 (00000006E3A81000) [pid = 2672] [serial = 45] [outer = 00000006E4605000]
12:37:44 INFO - 1500925064367 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"
12:37:44 INFO - 1500925064374 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"
12:37:44 INFO - 1500925064394 Marionette TRACE 377 <- [1,2,null,{}]
12:37:44 INFO - 1500925064445 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:37:44 INFO - 1500925064453 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"494ea3c8-16ac-4f0e-92ff-fca0e1a3bbc2","ELEMENT":"494ea3c8-16ac-4f0e-92ff-fca0e1a3bbc2"}}]
12:37:44 INFO - 1500925064455 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"494ea3c8-16ac-4f0e-92ff-fca0e1a3bbc2"}]
12:37:44 INFO - 1500925064918 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:37:44 INFO - 1500925064920 Marionette TRACE 377 <- [1,4,null,{}]
12:37:44 INFO - 1500925064925 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
12:37:44 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500925064938 Marionette TRACE 377 <- [1,5,null,{"value":null}]
12:37:44 INFO - 1500925064943 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
12:37:44 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:44 INFO - 1500925064948 Marionette TRACE 377 <- [1,6,null,{}]
12:37:44 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1723ms
12:37:44 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:37:44 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:44 INFO - 1500925064955 Marionette DEBUG Closed connection 377
12:37:44 INFO - 1500925064956 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49815
12:37:44 INFO - 1500925064958 Marionette DEBUG Closed connection 378
12:37:44 INFO - 1500925064959 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49816
12:37:44 INFO - 1500925064962 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:44 INFO - 1500925064964 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:44 INFO - 1500925064985 Marionette DEBUG Register listener.js for window 12
12:37:45 INFO - 1500925064997 Marionette TRACE 379 <- [1,1,null,{"sessionId":"7884c178-4e0d-4782-89ad-51d928053888","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:45 INFO - 1500925065025 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
12:37:45 INFO - 1500925065027 Marionette TRACE 379 <- [1,2,null,{}]
12:37:45 INFO - 1500925065030 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:45 INFO - 1500925065031 Marionette TRACE 379 <- [1,3,null,{}]
12:37:45 INFO - 1500925065033 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:45 INFO - 1500925065035 Marionette TRACE 379 <- [1,4,null,{}]
12:37:45 INFO - 1500925065038 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
12:37:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500925065046 Marionette TRACE 379 <- [1,5,null,{"value":null}]
12:37:45 INFO - 1500925065048 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065052 Marionette TRACE 379 <- [1,6,null,{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065077 Marionette DEBUG Closed connection 379
12:37:45 INFO - 1500925065078 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49817
12:37:45 INFO - 1500925065082 Marionette DEBUG Closed connection 380
12:37:45 INFO - 1500925065083 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49818
12:37:45 INFO - 1500925065086 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:45 INFO - 1500925065089 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:45 INFO - 1500925065111 Marionette DEBUG Register listener.js for window 12
12:37:45 INFO - 1500925065124 Marionette TRACE 381 <- [1,1,null,{"sessionId":"f8fc0f7c-2c61-48a1-b821-2cfa86771b5d","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:45 INFO - 1500925065134 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065139 Marionette TRACE 381 <- [1,2,null,{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065161 Marionette DEBUG Closed connection 381
12:37:45 INFO - 1500925065163 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49819
12:37:45 INFO - 1500925065167 Marionette DEBUG Closed connection 382
12:37:45 INFO - 1500925065169 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49820
12:37:45 INFO - 1500925065171 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:45 INFO - 1500925065175 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:45 INFO - 1500925065198 Marionette DEBUG Register listener.js for window 12
12:37:45 INFO - 1500925065210 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d527bd59-a44b-49da-856c-021405395ed0","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:45 INFO - 1500925065220 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:37:45 INFO - 1500925065225 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"
12:37:45 INFO - 1500925065232 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"
12:37:45 INFO - 1500925065233 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"
12:37:45 INFO - ++DOMWINDOW == 21 (00000006E49D9000) [pid = 2672] [serial = 46] [outer = 00000006E4605000]
12:37:45 INFO - 1500925065304 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"
12:37:45 INFO - 1500925065311 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"
12:37:45 INFO - 1500925065329 Marionette TRACE 383 <- [1,2,null,{}]
12:37:45 INFO - 1500925065336 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:37:45 INFO - 1500925065346 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d83a4edb-d413-4917-b7ea-1497a28b2e16","ELEMENT":"d83a4edb-d413-4917-b7ea-1497a28b2e16"}}]
12:37:45 INFO - 1500925065350 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"d83a4edb-d413-4917-b7ea-1497a28b2e16"}]
12:37:45 INFO - 1500925065799 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:37:45 INFO - 1500925065801 Marionette TRACE 383 <- [1,4,null,{}]
12:37:45 INFO - 1500925065804 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
12:37:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500925065813 Marionette TRACE 383 <- [1,5,null,{"value":null}]
12:37:45 INFO - 1500925065815 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065820 Marionette TRACE 383 <- [1,6,null,{}]
12:37:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 871ms
12:37:45 INFO - TEST-START | test_click.py TestClick.test_input_file
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065826 Marionette DEBUG Closed connection 383
12:37:45 INFO - 1500925065827 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49821
12:37:45 INFO - 1500925065829 Marionette DEBUG Closed connection 384
12:37:45 INFO - 1500925065830 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49822
12:37:45 INFO - 1500925065832 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:45 INFO - 1500925065835 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:45 INFO - 1500925065856 Marionette DEBUG Register listener.js for window 12
12:37:45 INFO - 1500925065867 Marionette TRACE 385 <- [1,1,null,{"sessionId":"40005551-8a23-4489-b38b-6e383b537ebe","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:45 INFO - 1500925065874 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
12:37:45 INFO - 1500925065876 Marionette TRACE 385 <- [1,2,null,{}]
12:37:45 INFO - 1500925065878 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:45 INFO - 1500925065880 Marionette TRACE 385 <- [1,3,null,{}]
12:37:45 INFO - 1500925065882 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:45 INFO - 1500925065883 Marionette TRACE 385 <- [1,4,null,{}]
12:37:45 INFO - 1500925065886 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
12:37:45 INFO - TEST-START: test_click.py TestClick.test_input_file1500925065894 Marionette TRACE 385 <- [1,5,null,{"value":null}]
12:37:45 INFO - 1500925065927 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065931 Marionette TRACE 385 <- [1,6,null,{}]
12:37:45 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:45 INFO - 1500925065954 Marionette DEBUG Closed connection 385
12:37:45 INFO - 1500925065955 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49823
12:37:45 INFO - 1500925065957 Marionette DEBUG Closed connection 386
12:37:45 INFO - 1500925065958 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49824
12:37:45 INFO - 1500925065960 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:45 INFO - 1500925065964 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:45 INFO - 1500925065990 Marionette DEBUG Register listener.js for window 12
12:37:46 INFO - 1500925066004 Marionette TRACE 387 <- [1,1,null,{"sessionId":"79fa7edb-72e0-411b-91d2-21949df1659c","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:46 INFO - 1500925066016 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066021 Marionette TRACE 387 <- [1,2,null,{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066027 Marionette DEBUG Closed connection 387
12:37:46 INFO - 1500925066028 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49825
12:37:46 INFO - 1500925066030 Marionette DEBUG Closed connection 388
12:37:46 INFO - 1500925066031 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49826
12:37:46 INFO - 1500925066033 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:46 INFO - 1500925066036 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:46 INFO - 1500925066059 Marionette DEBUG Register listener.js for window 12
12:37:46 INFO - 1500925066070 Marionette TRACE 389 <- [1,1,null,{"sessionId":"12d7f60f-d125-4099-9f61-bc7ef8039477","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:46 INFO - 1500925066076 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:37:46 INFO - 1500925066081 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"
12:37:46 INFO - 1500925066090 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"
12:37:46 INFO - 1500925066091 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"
12:37:46 INFO - ++DOMWINDOW == 22 (00000006EAD90000) [pid = 2672] [serial = 47] [outer = 00000006E4605000]
12:37:46 INFO - 1500925066152 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:37:46 INFO - 1500925066158 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:37:46 INFO - 1500925066176 Marionette TRACE 389 <- [1,2,null,{}]
12:37:46 INFO - 1500925066208 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:37:46 INFO - 1500925066217 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"44f89737-f3e8-4f71-9206-1be42b696ed6","ELEMENT":"44f89737-f3e8-4f71-9206-1be42b696ed6"}}]
12:37:46 INFO - 1500925066219 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"44f89737-f3e8-4f71-9206-1be42b696ed6"}]
12:37:46 INFO - 1500925066382 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:37:46 INFO - 1500925066400 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
12:37:46 INFO - TEST-END: test_click.py TestClick.test_input_file1500925066408 Marionette TRACE 389 <- [1,5,null,{"value":null}]
12:37:46 INFO - 1500925066410 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066414 Marionette TRACE 389 <- [1,6,null,{}]
12:37:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 594ms
12:37:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066420 Marionette DEBUG Closed connection 389
12:37:46 INFO - 1500925066421 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49827
12:37:46 INFO - 1500925066423 Marionette DEBUG Closed connection 390
12:37:46 INFO - 1500925066424 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49828
12:37:46 INFO - 1500925066426 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:46 INFO - 1500925066428 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:46 INFO - 1500925066450 Marionette DEBUG Register listener.js for window 12
12:37:46 INFO - 1500925066461 Marionette TRACE 391 <- [1,1,null,{"sessionId":"6e2140b2-1b9b-4113-9b32-1c071c4637a6","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:46 INFO - 1500925066491 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
12:37:46 INFO - 1500925066493 Marionette TRACE 391 <- [1,2,null,{}]
12:37:46 INFO - 1500925066495 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:46 INFO - 1500925066496 Marionette TRACE 391 <- [1,3,null,{}]
12:37:46 INFO - 1500925066498 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:46 INFO - 1500925066500 Marionette TRACE 391 <- [1,4,null,{}]
12:37:46 INFO - 1500925066503 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
12:37:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500925066511 Marionette TRACE 391 <- [1,5,null,{"value":null}]
12:37:46 INFO - 1500925066513 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066517 Marionette TRACE 391 <- [1,6,null,{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066523 Marionette DEBUG Closed connection 391
12:37:46 INFO - 1500925066524 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49829
12:37:46 INFO - 1500925066526 Marionette DEBUG Closed connection 392
12:37:46 INFO - 1500925066527 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49830
12:37:46 INFO - 1500925066551 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:46 INFO - 1500925066553 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:46 INFO - 1500925066575 Marionette DEBUG Register listener.js for window 12
12:37:46 INFO - 1500925066586 Marionette TRACE 393 <- [1,1,null,{"sessionId":"6f06c469-331f-4dd3-871a-d100a4ecd54f","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:46 INFO - 1500925066597 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066601 Marionette TRACE 393 <- [1,2,null,{}]
12:37:46 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:46 INFO - 1500925066608 Marionette DEBUG Closed connection 393
12:37:46 INFO - 1500925066609 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49831
12:37:46 INFO - 1500925066610 Marionette DEBUG Closed connection 394
12:37:46 INFO - 1500925066612 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49832
12:37:46 INFO - 1500925066614 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:46 INFO - 1500925066616 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:46 INFO - 1500925066638 Marionette DEBUG Register listener.js for window 12
12:37:46 INFO - 1500925066658 Marionette TRACE 395 <- [1,1,null,{"sessionId":"0528c474-e3f1-426d-ac70-61fa82a16c2d","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:46 INFO - 1500925066687 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:37:46 INFO - 1500925066691 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:37:46 INFO - 1500925066697 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:37:46 INFO - 1500925066698 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:37:46 INFO - ++DOMWINDOW == 23 (00000006EB7F0000) [pid = 2672] [serial = 48] [outer = 00000006E4605000]
12:37:46 INFO - 1500925066756 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"
12:37:46 INFO - 1500925066762 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"
12:37:46 INFO - 1500925066777 Marionette TRACE 395 <- [1,2,null,{}]
12:37:46 INFO - 1500925066784 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:37:46 INFO - 1500925066792 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b900f19f-f9d3-4bec-b195-5996ddc9de65","ELEMENT":"b900f19f-f9d3-4bec-b195-5996ddc9de65"}}]
12:37:46 INFO - 1500925066794 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:37:46 INFO - 1500925066801 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"07bed26f-dd2c-4051-8f1b-018ad90d641e","ELEMENT":"07bed26f-dd2c-4051-8f1b-018ad90d641e"}}]
12:37:46 INFO - 1500925066803 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"b900f19f-f9d3-4bec-b195-5996ddc9de65"}]
12:37:47 INFO - 1500925067690 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:37:47 INFO - 1500925067697 Marionette TRACE 395 <- [1,5,null,{}]
12:37:47 INFO - 1500925067725 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"07bed26f-dd2c-4051-8f1b-018ad90d641e"}]
12:37:47 INFO - 1500925067917 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
12:37:47 INFO - 1500925067925 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:37:47 INFO - 1500925067946 Marionette TRACE 395 <- [1,7,null,{"value":false}]
12:37:47 INFO - 1500925067967 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:37:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500925067986 Marionette TRACE 395 <- [1,8,null,{"value":null}]
12:37:48 INFO - 1500925068001 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068010 Marionette TRACE 395 <- [1,9,null,{}]
12:37:48 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1598ms
12:37:48 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068034 Marionette DEBUG Closed connection 395
12:37:48 INFO - 1500925068037 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49833
12:37:48 INFO - 1500925068040 Marionette DEBUG Closed connection 396
12:37:48 INFO - 1500925068044 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49834
12:37:48 INFO - 1500925068051 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:48 INFO - 1500925068062 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:48 INFO - 1500925068107 Marionette DEBUG Register listener.js for window 12
12:37:48 INFO - 1500925068129 Marionette TRACE 397 <- [1,1,null,{"sessionId":"48601fd4-943f-438e-83d3-92dae3e01c9b","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:48 INFO - 1500925068180 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
12:37:48 INFO - 1500925068184 Marionette TRACE 397 <- [1,2,null,{}]
12:37:48 INFO - 1500925068187 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:37:48 INFO - 1500925068190 Marionette TRACE 397 <- [1,3,null,{}]
12:37:48 INFO - 1500925068205 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
12:37:48 INFO - 1500925068208 Marionette TRACE 397 <- [1,4,null,{}]
12:37:48 INFO - 1500925068212 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:37:48 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500925068225 Marionette TRACE 397 <- [1,5,null,{"value":null}]
12:37:48 INFO - 1500925068229 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068235 Marionette TRACE 397 <- [1,6,null,{}]
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068262 Marionette DEBUG Closed connection 397
12:37:48 INFO - 1500925068266 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49835
12:37:48 INFO - 1500925068317 Marionette DEBUG Closed connection 398
12:37:48 INFO - 1500925068320 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49836
12:37:48 INFO - 1500925068326 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:37:48 INFO - 1500925068329 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:48 INFO - 1500925068375 Marionette DEBUG Register listener.js for window 12
12:37:48 INFO - 1500925068393 Marionette TRACE 399 <- [1,1,null,{"sessionId":"768d70b0-8a7a-454b-a819-5298f90e089f","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:48 INFO - 1500925068411 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068421 Marionette TRACE 399 <- [1,2,null,{}]
12:37:48 INFO - [Parent 2672] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
12:37:48 INFO - 1500925068478 Marionette DEBUG Closed connection 399
12:37:48 INFO - 1500925068480 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49837
12:37:48 INFO - 1500925068502 Marionette DEBUG Closed connection 400
12:37:48 INFO - 1500925068504 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49838
12:37:48 INFO - 1500925068508 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:37:48 INFO - 1500925068513 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}
12:37:48 INFO - 1500925068559 Marionette DEBUG Register listener.js for window 12
12:37:48 INFO - 1500925068575 Marionette TRACE 401 <- [1,1,null,{"sessionId":"bd4fd0c5-b4b8-4288-9666-f18ea3585a8b","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmp5vmpz2.mozrunner","moz:accessibilityChecks":false}}]
12:37:48 INFO - 1500925068607 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:37:48 INFO - 1500925068613 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"
12:37:48 INFO - 1500925068623 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"
12:37:48 INFO - 1500925068624 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"
12:37:48 INFO - ++DOMWINDOW == 24 (00000006E8916800) [pid = 2672] [serial = 49] [outer = 00000006E4605000]
12:37:48 INFO - 1500925068700 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"
12:37:48 INFO - 1500925068707 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"
12:37:48 INFO - 1500925068727 Marionette TRACE 401 <- [1,2,null,{}]
12:37:48 INFO - 1500925068738 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:37:48 INFO - 1500925068750 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0881af73-b82a-40ff-a0ee-fef13a5c69c0","ELEMENT":"0881af73-b82a-40ff-a0ee-fef13a5c69c0"}}]
12:37:48 INFO - 1500925068752 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0881af73-b82a-40ff-a0ee-fef13a5c69c0"}]
12:37:48 INFO - 1500925068959 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
12:37:48 INFO - 1500925068961 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"0881af73-b82a-40ff-a0ee-fef13a5c69c0"}]
12:37:49 INFO - 1500925069249 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element