could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:56:44 INFO - 1500677804003 Marionette TRACE 354 -> [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}]
15:56:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500677804013 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:56:44 INFO - 1500677804016 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804020 Marionette TRACE 354 <- [1,6,null,{}]
15:56:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1675ms
15:56:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804027 Marionette DEBUG Closed connection 354
15:56:44 INFO - 1500677804029 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49787
15:56:44 INFO - 1500677804051 Marionette DEBUG Closed connection 355
15:56:44 INFO - 1500677804052 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49788
15:56:44 INFO - 1500677804055 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:44 INFO - 1500677804057 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:44 INFO - 1500677804079 Marionette DEBUG Register listener.js for window 15
15:56:44 INFO - 1500677804090 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e4883913-b8a8-40f3-83b7-1dce002959f8","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:44 INFO - 1500677804109 Marionette TRACE 356 -> [0,2,"setTimeouts",{"script":30000}]
15:56:44 INFO - 1500677804111 Marionette TRACE 356 <- [1,2,null,{}]
15:56:44 INFO - 1500677804113 Marionette TRACE 356 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:44 INFO - 1500677804114 Marionette TRACE 356 <- [1,3,null,{}]
15:56:44 INFO - 1500677804168 Marionette TRACE 356 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:44 INFO - 1500677804173 Marionette TRACE 356 <- [1,4,null,{}]
15:56:44 INFO - 1500677804177 Marionette TRACE 356 -> [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}]
15:56:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500677804190 Marionette TRACE 356 <- [1,5,null,{"value":null}]
15:56:44 INFO - 1500677804196 Marionette TRACE 356 -> [0,6,"deleteSession",{}]
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804208 Marionette TRACE 356 <- [1,6,null,{}]
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804218 Marionette DEBUG Closed connection 356
15:56:44 INFO - 1500677804220 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49789
15:56:44 INFO - 1500677804224 Marionette DEBUG Closed connection 357
15:56:44 INFO - 1500677804226 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49790
15:56:44 INFO - 1500677804232 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:44 INFO - 1500677804243 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:44 INFO - 1500677804289 Marionette DEBUG Register listener.js for window 15
15:56:44 INFO - 1500677804322 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0fd318ea-7931-4797-b9cd-f5d01a936598","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:44 INFO - 1500677804364 Marionette TRACE 358 -> [0,2,"deleteSession",{}]
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804371 Marionette TRACE 358 <- [1,2,null,{}]
15:56:44 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:44 INFO - 1500677804430 Marionette DEBUG Closed connection 358
15:56:44 INFO - 1500677804434 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49791
15:56:44 INFO - 1500677804456 Marionette DEBUG Closed connection 359
15:56:44 INFO - 1500677804459 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49792
15:56:44 INFO - 1500677804463 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:44 INFO - 1500677804469 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:44 INFO - 1500677804517 Marionette DEBUG Register listener.js for window 15
15:56:44 INFO - 1500677804535 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d27eafca-7159-4426-8792-e77e12066fa6","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:44 INFO - 1500677804562 Marionette TRACE 360 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
15:56:44 INFO - 1500677804569 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
15:56:44 INFO - 1500677804585 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
15:56:44 INFO - 1500677804586 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
15:56:44 INFO - ++DOMWINDOW == 29 (000000CD8933D000) [pid = 2968] [serial = 40] [outer = 000000CD94FB4800]
15:56:44 INFO - 1500677804691 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
15:56:44 INFO - 1500677804698 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
15:56:44 INFO - 1500677804728 Marionette TRACE 360 <- [1,2,null,{}]
15:56:44 INFO - 1500677804737 Marionette TRACE 360 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:56:44 INFO - 1500677804747 Marionette TRACE 360 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ddf68d9-0925-4884-8fc9-cf349aa66fc9","ELEMENT":"2ddf68d9-0925-4884-8fc9-cf349aa66fc9"}}]
15:56:44 INFO - 1500677804750 Marionette TRACE 360 -> [0,4,"clickElement",{"id":"2ddf68d9-0925-4884-8fc9-cf349aa66fc9"}]
15:56:45 INFO - 1500677805153 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
15:56:45 INFO - [Parent 2968] 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
15:56:45 INFO - 1500677805174 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
15:56:45 INFO - 1500677805176 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
15:56:45 INFO - ++DOMWINDOW == 30 (000000CD8EDD7800) [pid = 2968] [serial = 41] [outer = 000000CD94FB4800]
15:56:45 INFO - 1500677805252 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
15:56:45 INFO - 1500677805259 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
15:56:45 INFO - 1500677805279 Marionette TRACE 360 <- [1,4,null,{}]
15:56:45 INFO - 1500677805295 Marionette TRACE 360 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:56:45 INFO - 1500677805306 Marionette TRACE 360 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79ece220-8aa5-4bf0-abf0-3488d1163873","ELEMENT":"79ece220-8aa5-4bf0-abf0-3488d1163873"}}]
15:56:45 INFO - 1500677805313 Marionette TRACE 360 -> [0,6,"getTitle",{}]
15:56:45 INFO - 1500677805319 Marionette TRACE 360 <- [1,6,null,{"value":"Marionette Test"}]
15:56:45 INFO - 1500677805322 Marionette TRACE 360 -> [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}]
15:56:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500677805332 Marionette TRACE 360 <- [1,7,null,{"value":null}]
15:56:45 INFO - 1500677805334 Marionette TRACE 360 -> [0,8,"deleteSession",{}]
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805341 Marionette TRACE 360 <- [1,8,null,{}]
15:56:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1321ms
15:56:45 INFO - TEST-START | test_click.py TestClick.test_container_element
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805405 Marionette DEBUG Closed connection 360
15:56:45 INFO - 1500677805408 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49795
15:56:45 INFO - 1500677805421 Marionette DEBUG Closed connection 361
15:56:45 INFO - 1500677805424 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49796
15:56:45 INFO - 1500677805426 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:45 INFO - 1500677805430 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:45 INFO - 1500677805476 Marionette DEBUG Register listener.js for window 15
15:56:45 INFO - 1500677805493 Marionette TRACE 362 <- [1,1,null,{"sessionId":"151b7ecb-7efa-4d5f-8c93-af0479fe1351","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:45 INFO - 1500677805508 Marionette TRACE 362 -> [0,2,"setTimeouts",{"script":30000}]
15:56:45 INFO - 1500677805510 Marionette TRACE 362 <- [1,2,null,{}]
15:56:45 INFO - 1500677805584 Marionette TRACE 362 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:45 INFO - 1500677805586 Marionette TRACE 362 <- [1,3,null,{}]
15:56:45 INFO - 1500677805590 Marionette TRACE 362 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:45 INFO - 1500677805592 Marionette TRACE 362 <- [1,4,null,{}]
15:56:45 INFO - 1500677805635 Marionette TRACE 362 -> [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}]
15:56:45 INFO - TEST-START: test_click.py TestClick.test_container_element1500677805646 Marionette TRACE 362 <- [1,5,null,{"value":null}]
15:56:45 INFO - 1500677805650 Marionette TRACE 362 -> [0,6,"deleteSession",{}]
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805655 Marionette TRACE 362 <- [1,6,null,{}]
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805679 Marionette DEBUG Closed connection 362
15:56:45 INFO - 1500677805681 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49797
15:56:45 INFO - 1500677805705 Marionette DEBUG Closed connection 363
15:56:45 INFO - 1500677805707 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49798
15:56:45 INFO - 1500677805892 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:45 INFO - 1500677805907 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:45 INFO - 1500677805935 Marionette DEBUG Register listener.js for window 15
15:56:45 INFO - 1500677805959 Marionette TRACE 364 <- [1,1,null,{"sessionId":"86ef48eb-a6a3-4362-9427-e9fcf2e1020c","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:45 INFO - 1500677805972 Marionette TRACE 364 -> [0,2,"deleteSession",{}]
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805979 Marionette TRACE 364 <- [1,2,null,{}]
15:56:45 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:45 INFO - 1500677805988 Marionette DEBUG Closed connection 364
15:56:45 INFO - 1500677805991 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49799
15:56:45 INFO - 1500677805994 Marionette DEBUG Closed connection 365
15:56:46 INFO - 1500677805996 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49800
15:56:46 INFO - 1500677806002 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:46 INFO - 1500677806009 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:46 INFO - 1500677806035 Marionette DEBUG Register listener.js for window 15
15:56:46 INFO - 1500677806054 Marionette TRACE 366 <- [1,1,null,{"sessionId":"822aac8a-d8cb-40c1-9267-659ed26a657d","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:46 INFO - 1500677806104 Marionette TRACE 366 -> [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"}]
15:56:46 INFO - 1500677806111 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
15:56:46 INFO - 1500677806119 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
15:56:46 INFO - 1500677806120 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
15:56:46 INFO - ++DOMWINDOW == 31 (000000CD88E41000) [pid = 2968] [serial = 42] [outer = 000000CD94FB4800]
15:56:46 INFO - 1500677806187 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"
15:56:46 INFO - 1500677806193 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"
15:56:46 INFO - 1500677806216 Marionette TRACE 366 <- [1,2,null,{}]
15:56:46 INFO - 1500677806231 Marionette TRACE 366 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:56:46 INFO - 1500677806243 Marionette TRACE 366 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3a0d5bb0-093a-40f9-8267-c8db3b5f64b2","ELEMENT":"3a0d5bb0-093a-40f9-8267-c8db3b5f64b2"}}]
15:56:46 INFO - 1500677806246 Marionette TRACE 366 -> [0,4,"clickElement",{"id":"3a0d5bb0-093a-40f9-8267-c8db3b5f64b2"}]
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:56:46 INFO - 1500677806717 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:56:46 INFO - 1500677806720 Marionette TRACE 366 <- [1,4,null,{}]
15:56:46 INFO - 1500677806725 Marionette TRACE 366 -> [0,5,"getElementProperty",{"id":"3a0d5bb0-093a-40f9-8267-c8db3b5f64b2","name":"selected"}]
15:56:46 INFO - 1500677806760 Marionette TRACE 366 <- [1,5,null,{"value":true}]
15:56:46 INFO - 1500677806769 Marionette TRACE 366 -> [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}]
15:56:46 INFO - TEST-END: test_click.py TestClick.test_container_element1500677806780 Marionette TRACE 366 <- [1,6,null,{"value":null}]
15:56:46 INFO - 1500677806798 Marionette TRACE 366 -> [0,7,"deleteSession",{}]
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:46 INFO - 1500677806803 Marionette TRACE 366 <- [1,7,null,{}]
15:56:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1464ms
15:56:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:46 INFO - 1500677806813 Marionette DEBUG Closed connection 366
15:56:46 INFO - 1500677806815 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49801
15:56:46 INFO - 1500677806840 Marionette DEBUG Closed connection 367
15:56:46 INFO - 1500677806842 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49802
15:56:46 INFO - 1500677806845 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:46 INFO - 1500677806849 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:46 INFO - 1500677806873 Marionette DEBUG Register listener.js for window 15
15:56:46 INFO - 1500677806892 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b2fdc82f-1504-497d-ad2e-2876167241f7","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:46 INFO - 1500677806907 Marionette TRACE 368 -> [0,2,"setTimeouts",{"script":30000}]
15:56:46 INFO - 1500677806913 Marionette TRACE 368 <- [1,2,null,{}]
15:56:46 INFO - 1500677806915 Marionette TRACE 368 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:46 INFO - 1500677806917 Marionette TRACE 368 <- [1,3,null,{}]
15:56:46 INFO - 1500677806919 Marionette TRACE 368 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:46 INFO - 1500677806921 Marionette TRACE 368 <- [1,4,null,{}]
15:56:46 INFO - 1500677806923 Marionette TRACE 368 -> [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}]
15:56:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500677806933 Marionette TRACE 368 <- [1,5,null,{"value":null}]
15:56:46 INFO - 1500677806935 Marionette TRACE 368 -> [0,6,"deleteSession",{}]
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:46 INFO - 1500677806940 Marionette TRACE 368 <- [1,6,null,{}]
15:56:46 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:46 INFO - 1500677806968 Marionette DEBUG Closed connection 368
15:56:46 INFO - 1500677806970 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49803
15:56:46 INFO - 1500677806975 Marionette DEBUG Closed connection 369
15:56:46 INFO - 1500677806976 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49804
15:56:46 INFO - 1500677806979 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:46 INFO - 1500677806981 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:47 INFO - 1500677807006 Marionette DEBUG Register listener.js for window 15
15:56:47 INFO - 1500677807019 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d5f17ea1-a268-4613-a5aa-d4589154cf56","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:47 INFO - 1500677807034 Marionette TRACE 370 -> [0,2,"deleteSession",{}]
15:56:47 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:47 INFO - 1500677807039 Marionette TRACE 370 <- [1,2,null,{}]
15:56:47 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:47 INFO - 1500677807054 Marionette DEBUG Closed connection 370
15:56:47 INFO - 1500677807056 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49805
15:56:47 INFO - --DOMWINDOW == 30 (000000CD9CF53000) [pid = 2968] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 29 (000000CD94979000) [pid = 2968] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 28 (000000CD948C1800) [pid = 2968] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 27 (000000CD9F653800) [pid = 2968] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 26 (000000CD937A0800) [pid = 2968] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 25 (000000CD8D2AF000) [pid = 2968] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 24 (000000CD99A06000) [pid = 2968] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - --DOMWINDOW == 23 (000000CD8EB21000) [pid = 2968] [serial = 21] [outer = 0000000000000000] [url = http://127.0.0.1:49335/test_accessibility.html]
15:56:47 INFO - 1500677807091 Marionette DEBUG Closed connection 371
15:56:47 INFO - 1500677807093 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49806
15:56:47 INFO - 1500677807096 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:47 INFO - 1500677807102 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:47 INFO - 1500677807128 Marionette DEBUG Register listener.js for window 15
15:56:47 INFO - 1500677807162 Marionette TRACE 372 <- [1,1,null,{"sessionId":"024a7cf2-2716-4674-b50c-19a54f7700ee","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:47 INFO - 1500677807176 Marionette TRACE 372 -> [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"}]
15:56:47 INFO - 1500677807181 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"
15:56:47 INFO - 1500677807188 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"
15:56:47 INFO - 1500677807188 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"
15:56:47 INFO - ++DOMWINDOW == 24 (000000CD88E3A800) [pid = 2968] [serial = 43] [outer = 000000CD94FB4800]
15:56:47 INFO - 1500677807252 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"
15:56:47 INFO - 1500677807258 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"
15:56:47 INFO - 1500677807278 Marionette TRACE 372 <- [1,2,null,{}]
15:56:47 INFO - 1500677807283 Marionette TRACE 372 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:56:47 INFO - 1500677807291 Marionette TRACE 372 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0560203-2052-4298-88c4-dc725e648d48","ELEMENT":"b0560203-2052-4298-88c4-dc725e648d48"}}]
15:56:47 INFO - 1500677807293 Marionette TRACE 372 -> [0,4,"clickElement",{"id":"b0560203-2052-4298-88c4-dc725e648d48"}]
15:56:47 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:56:47 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:56:47 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:56:48 INFO - 1500677808435 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:56:48 INFO - 1500677808457 Marionette TRACE 372 <- [1,4,null,{}]
15:56:48 INFO - 1500677808462 Marionette TRACE 372 -> [0,5,"getElementProperty",{"id":"b0560203-2052-4298-88c4-dc725e648d48","name":"selected"}]
15:56:48 INFO - 1500677808492 Marionette TRACE 372 <- [1,5,null,{"value":true}]
15:56:48 INFO - 1500677808538 Marionette TRACE 372 -> [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}]
15:56:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500677808553 Marionette TRACE 372 <- [1,6,null,{"value":null}]
15:56:48 INFO - 1500677808555 Marionette TRACE 372 -> [0,7,"deleteSession",{}]
15:56:48 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:48 INFO - 1500677808560 Marionette TRACE 372 <- [1,7,null,{}]
15:56:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1754ms
15:56:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:56:48 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:48 INFO - 1500677808614 Marionette DEBUG Closed connection 372
15:56:48 INFO - 1500677808616 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49807
15:56:48 INFO - 1500677808665 Marionette DEBUG Closed connection 373
15:56:48 INFO - 1500677808667 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49808
15:56:48 INFO - 1500677808719 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:48 INFO - 1500677808722 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:48 INFO - 1500677808746 Marionette DEBUG Register listener.js for window 15
15:56:48 INFO - 1500677808760 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3960569b-0fe4-46b5-b41c-4b09c5e917be","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:48 INFO - 1500677808818 Marionette TRACE 374 -> [0,2,"setTimeouts",{"script":30000}]
15:56:48 INFO - 1500677808820 Marionette TRACE 374 <- [1,2,null,{}]
15:56:48 INFO - 1500677808867 Marionette TRACE 374 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:48 INFO - 1500677808869 Marionette TRACE 374 <- [1,3,null,{}]
15:56:48 INFO - 1500677808890 Marionette TRACE 374 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:48 INFO - 1500677808892 Marionette TRACE 374 <- [1,4,null,{}]
15:56:48 INFO - 1500677808896 Marionette TRACE 374 -> [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}]
15:56:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500677808904 Marionette TRACE 374 <- [1,5,null,{"value":null}]
15:56:48 INFO - 1500677808908 Marionette TRACE 374 -> [0,6,"deleteSession",{}]
15:56:48 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:48 INFO - 1500677808913 Marionette TRACE 374 <- [1,6,null,{}]
15:56:48 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:48 INFO - 1500677808937 Marionette DEBUG Closed connection 374
15:56:48 INFO - 1500677808942 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49809
15:56:48 INFO - 1500677808946 Marionette DEBUG Closed connection 375
15:56:48 INFO - 1500677808948 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49810
15:56:48 INFO - 1500677808950 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:48 INFO - 1500677808953 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:48 INFO - 1500677808976 Marionette DEBUG Register listener.js for window 15
15:56:48 INFO - 1500677808988 Marionette TRACE 376 <- [1,1,null,{"sessionId":"765731d6-5074-41c5-b74a-a5ce8ef2d1b4","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:49 INFO - 1500677808999 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809003 Marionette TRACE 376 <- [1,2,null,{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809024 Marionette DEBUG Closed connection 376
15:56:49 INFO - 1500677809026 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49811
15:56:49 INFO - 1500677809030 Marionette DEBUG Closed connection 377
15:56:49 INFO - 1500677809031 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49812
15:56:49 INFO - 1500677809034 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:49 INFO - 1500677809037 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:49 INFO - 1500677809061 Marionette DEBUG Register listener.js for window 15
15:56:49 INFO - 1500677809073 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6f31026c-025e-4941-932a-be01f4084df3","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:49 INFO - 1500677809083 Marionette TRACE 378 -> [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"}]
15:56:49 INFO - 1500677809087 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"
15:56:49 INFO - 1500677809093 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"
15:56:49 INFO - 1500677809094 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"
15:56:49 INFO - ++DOMWINDOW == 25 (000000CD8EA51800) [pid = 2968] [serial = 44] [outer = 000000CD94FB4800]
15:56:49 INFO - 1500677809153 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"
15:56:49 INFO - 1500677809159 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"
15:56:49 INFO - 1500677809177 Marionette TRACE 378 <- [1,2,null,{}]
15:56:49 INFO - 1500677809183 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:56:49 INFO - 1500677809193 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e720ffb-3835-45cd-abdb-97c168e816c7","ELEMENT":"4e720ffb-3835-45cd-abdb-97c168e816c7"}}]
15:56:49 INFO - 1500677809195 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"4e720ffb-3835-45cd-abdb-97c168e816c7"}]
15:56:49 INFO - 1500677809681 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:56:49 INFO - 1500677809683 Marionette TRACE 378 <- [1,4,null,{}]
15:56:49 INFO - 1500677809686 Marionette TRACE 378 -> [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}]
15:56:49 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500677809694 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:56:49 INFO - 1500677809696 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809701 Marionette TRACE 378 <- [1,6,null,{}]
15:56:49 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1142ms
15:56:49 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809708 Marionette DEBUG Closed connection 378
15:56:49 INFO - 1500677809710 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49813
15:56:49 INFO - 1500677809712 Marionette DEBUG Closed connection 379
15:56:49 INFO - 1500677809713 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49814
15:56:49 INFO - 1500677809715 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:49 INFO - 1500677809718 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:49 INFO - 1500677809742 Marionette DEBUG Register listener.js for window 15
15:56:49 INFO - 1500677809753 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ecee1695-8c17-4ce8-bc84-4819be38bd33","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:49 INFO - 1500677809761 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
15:56:49 INFO - 1500677809763 Marionette TRACE 380 <- [1,2,null,{}]
15:56:49 INFO - 1500677809765 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:49 INFO - 1500677809767 Marionette TRACE 380 <- [1,3,null,{}]
15:56:49 INFO - 1500677809794 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:49 INFO - 1500677809796 Marionette TRACE 380 <- [1,4,null,{}]
15:56:49 INFO - 1500677809798 Marionette TRACE 380 -> [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}]
15:56:49 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500677809808 Marionette TRACE 380 <- [1,5,null,{"value":null}]
15:56:49 INFO - 1500677809810 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809814 Marionette TRACE 380 <- [1,6,null,{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809820 Marionette DEBUG Closed connection 380
15:56:49 INFO - 1500677809822 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49815
15:56:49 INFO - 1500677809823 Marionette DEBUG Closed connection 381
15:56:49 INFO - 1500677809825 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49816
15:56:49 INFO - 1500677809827 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:49 INFO - 1500677809829 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:49 INFO - 1500677809853 Marionette DEBUG Register listener.js for window 15
15:56:49 INFO - 1500677809865 Marionette TRACE 382 <- [1,1,null,{"sessionId":"16a245b2-6be6-4e41-956f-4bca1186f1b3","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:49 INFO - 1500677809871 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809875 Marionette TRACE 382 <- [1,2,null,{}]
15:56:49 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:49 INFO - 1500677809881 Marionette DEBUG Closed connection 382
15:56:49 INFO - 1500677809882 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49817
15:56:49 INFO - 1500677809884 Marionette DEBUG Closed connection 383
15:56:49 INFO - 1500677809885 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49818
15:56:49 INFO - 1500677809888 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:49 INFO - 1500677809892 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:49 INFO - 1500677809921 Marionette DEBUG Register listener.js for window 15
15:56:49 INFO - 1500677809935 Marionette TRACE 384 <- [1,1,null,{"sessionId":"99107053-eea1-445f-acf6-23ad0ed5d194","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:49 INFO - 1500677809941 Marionette TRACE 384 -> [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"}]
15:56:49 INFO - 1500677809945 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"
15:56:49 INFO - 1500677809951 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"
15:56:49 INFO - 1500677809951 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"
15:56:49 INFO - ++DOMWINDOW == 26 (000000CD9149B800) [pid = 2968] [serial = 45] [outer = 000000CD94FB4800]
15:56:50 INFO - 1500677810004 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"
15:56:50 INFO - 1500677810012 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"
15:56:50 INFO - 1500677810030 Marionette TRACE 384 <- [1,2,null,{}]
15:56:50 INFO - 1500677810084 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:56:50 INFO - 1500677810104 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88a17996-1afc-466a-89cd-50b43eacc2ce","ELEMENT":"88a17996-1afc-466a-89cd-50b43eacc2ce"}}]
15:56:50 INFO - 1500677810108 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"88a17996-1afc-466a-89cd-50b43eacc2ce"}]
15:56:50 INFO - 1500677810552 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:56:50 INFO - 1500677810554 Marionette TRACE 384 <- [1,4,null,{}]
15:56:50 INFO - 1500677810559 Marionette TRACE 384 -> [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}]
15:56:50 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500677810567 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:56:50 INFO - 1500677810569 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810574 Marionette TRACE 384 <- [1,6,null,{}]
15:56:50 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 872ms
15:56:50 INFO - TEST-START | test_click.py TestClick.test_input_file
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810599 Marionette DEBUG Closed connection 384
15:56:50 INFO - 1500677810600 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49819
15:56:50 INFO - 1500677810602 Marionette DEBUG Closed connection 385
15:56:50 INFO - 1500677810603 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49820
15:56:50 INFO - 1500677810605 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:50 INFO - 1500677810607 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:50 INFO - 1500677810632 Marionette DEBUG Register listener.js for window 15
15:56:50 INFO - 1500677810643 Marionette TRACE 386 <- [1,1,null,{"sessionId":"0a1d4fb2-d0eb-48cc-932a-3102b58fba0e","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:50 INFO - 1500677810655 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
15:56:50 INFO - 1500677810657 Marionette TRACE 386 <- [1,2,null,{}]
15:56:50 INFO - 1500677810659 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:50 INFO - 1500677810661 Marionette TRACE 386 <- [1,3,null,{}]
15:56:50 INFO - 1500677810663 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:50 INFO - 1500677810664 Marionette TRACE 386 <- [1,4,null,{}]
15:56:50 INFO - 1500677810667 Marionette TRACE 386 -> [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}]
15:56:50 INFO - TEST-START: test_click.py TestClick.test_input_file1500677810674 Marionette TRACE 386 <- [1,5,null,{"value":null}]
15:56:50 INFO - 1500677810676 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810681 Marionette TRACE 386 <- [1,6,null,{}]
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810686 Marionette DEBUG Closed connection 386
15:56:50 INFO - 1500677810688 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49821
15:56:50 INFO - 1500677810689 Marionette DEBUG Closed connection 387
15:56:50 INFO - 1500677810691 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49822
15:56:50 INFO - 1500677810693 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:50 INFO - 1500677810695 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:50 INFO - 1500677810719 Marionette DEBUG Register listener.js for window 15
15:56:50 INFO - 1500677810730 Marionette TRACE 388 <- [1,1,null,{"sessionId":"fb0669af-4103-48aa-bfbf-f1ff5f6727db","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:50 INFO - 1500677810736 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810740 Marionette TRACE 388 <- [1,2,null,{}]
15:56:50 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:50 INFO - 1500677810746 Marionette DEBUG Closed connection 388
15:56:50 INFO - 1500677810747 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49823
15:56:50 INFO - 1500677810748 Marionette DEBUG Closed connection 389
15:56:50 INFO - 1500677810750 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49824
15:56:50 INFO - 1500677810752 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:50 INFO - 1500677810754 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:50 INFO - 1500677810779 Marionette DEBUG Register listener.js for window 15
15:56:50 INFO - 1500677810799 Marionette TRACE 390 <- [1,1,null,{"sessionId":"81ba39a7-1c73-42bf-a401-a9acd76fe703","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:50 INFO - 1500677810805 Marionette TRACE 390 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:56:50 INFO - 1500677810809 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"
15:56:50 INFO - 1500677810815 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"
15:56:50 INFO - 1500677810816 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"
15:56:50 INFO - ++DOMWINDOW == 27 (000000CD8A378000) [pid = 2968] [serial = 46] [outer = 000000CD94FB4800]
15:56:50 INFO - 1500677810922 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:56:50 INFO - 1500677810927 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:56:50 INFO - 1500677810944 Marionette TRACE 390 <- [1,2,null,{}]
15:56:50 INFO - 1500677810947 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:56:50 INFO - 1500677810954 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"44de1845-353f-4dae-82c9-2a97efc6995e","ELEMENT":"44de1845-353f-4dae-82c9-2a97efc6995e"}}]
15:56:50 INFO - 1500677810956 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"44de1845-353f-4dae-82c9-2a97efc6995e"}]
15:56:51 INFO - 1500677811112 Marionette TRACE 390 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:56:51 INFO - 1500677811754 Marionette TRACE 390 -> [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}]
15:56:51 INFO - TEST-END: test_click.py TestClick.test_input_file1500677811763 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:56:51 INFO - 1500677811767 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:56:51 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:51 INFO - 1500677811771 Marionette TRACE 390 <- [1,6,null,{}]
15:56:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1197ms
15:56:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:56:51 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:51 INFO - 1500677811811 Marionette DEBUG Closed connection 390
15:56:51 INFO - 1500677811813 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49825
15:56:51 INFO - 1500677811910 Marionette DEBUG Closed connection 391
15:56:51 INFO - 1500677811913 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49826
15:56:51 INFO - 1500677811918 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:51 INFO - 1500677811928 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:51 INFO - 1500677811980 Marionette DEBUG Register listener.js for window 15
15:56:52 INFO - 1500677812005 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e881a950-7f8b-430e-afa6-76e3fcc31457","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:52 INFO - 1500677812023 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
15:56:52 INFO - 1500677812026 Marionette TRACE 392 <- [1,2,null,{}]
15:56:52 INFO - 1500677812030 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:52 INFO - 1500677812033 Marionette TRACE 392 <- [1,3,null,{}]
15:56:52 INFO - 1500677812037 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:52 INFO - 1500677812042 Marionette TRACE 392 <- [1,4,null,{}]
15:56:52 INFO - 1500677812047 Marionette TRACE 392 -> [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}]
15:56:52 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500677812062 Marionette TRACE 392 <- [1,5,null,{"value":null}]
15:56:52 INFO - 1500677812099 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
15:56:52 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:52 INFO - 1500677812107 Marionette TRACE 392 <- [1,6,null,{}]
15:56:52 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:52 INFO - 1500677812135 Marionette DEBUG Closed connection 392
15:56:52 INFO - 1500677812138 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49827
15:56:52 INFO - 1500677812143 Marionette DEBUG Closed connection 393
15:56:52 INFO - 1500677812147 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49828
15:56:52 INFO - 1500677812200 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:52 INFO - 1500677812205 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:52 INFO - 1500677812256 Marionette DEBUG Register listener.js for window 15
15:56:52 INFO - 1500677812273 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4168a7b0-4412-46a5-bdfc-8d5311cc837e","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:52 INFO - 1500677812287 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
15:56:52 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:52 INFO - 1500677812293 Marionette TRACE 394 <- [1,2,null,{}]
15:56:52 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:52 INFO - 1500677812302 Marionette DEBUG Closed connection 394
15:56:52 INFO - 1500677812304 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49829
15:56:52 INFO - 1500677812357 Marionette DEBUG Closed connection 395
15:56:52 INFO - 1500677812360 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49830
15:56:52 INFO - 1500677812381 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:52 INFO - 1500677812386 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:52 INFO - 1500677812437 Marionette DEBUG Register listener.js for window 15
15:56:52 INFO - 1500677812453 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c925fc6f-e41e-40d0-a375-4449449d0db8","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:52 INFO - 1500677812465 Marionette TRACE 396 -> [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"}]
15:56:52 INFO - 1500677812471 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:56:52 INFO - 1500677812480 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:56:52 INFO - 1500677812481 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:56:52 INFO - ++DOMWINDOW == 28 (000000CD88E3E000) [pid = 2968] [serial = 47] [outer = 000000CD94FB4800]
15:56:52 INFO - 1500677812566 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"
15:56:52 INFO - 1500677812574 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"
15:56:52 INFO - 1500677812594 Marionette TRACE 396 <- [1,2,null,{}]
15:56:52 INFO - 1500677812607 Marionette TRACE 396 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:56:52 INFO - 1500677812620 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9fbb4069-d4e4-4ac2-ac19-f8566d6158b0","ELEMENT":"9fbb4069-d4e4-4ac2-ac19-f8566d6158b0"}}]
15:56:52 INFO - 1500677812623 Marionette TRACE 396 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:56:52 INFO - 1500677812633 Marionette TRACE 396 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b4252c3-4058-4ee7-ae16-ada8661e9e5a","ELEMENT":"7b4252c3-4058-4ee7-ae16-ada8661e9e5a"}}]
15:56:52 INFO - 1500677812635 Marionette TRACE 396 -> [0,5,"clickElement",{"id":"9fbb4069-d4e4-4ac2-ac19-f8566d6158b0"}]
15:56:53 INFO - 1500677813267 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:56:53 INFO - 1500677813270 Marionette TRACE 396 <- [1,5,null,{}]
15:56:53 INFO - 1500677813275 Marionette TRACE 396 -> [0,6,"clickElement",{"id":"7b4252c3-4058-4ee7-ae16-ada8661e9e5a"}]
15:56:53 INFO - 1500677813600 Marionette TRACE 396 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:56:53 INFO - 1500677813608 Marionette TRACE 396 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
15:56:53 INFO - 1500677813618 Marionette TRACE 396 <- [1,7,null,{"value":false}]
15:56:53 INFO - 1500677813621 Marionette TRACE 396 -> [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}]
15:56:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500677813631 Marionette TRACE 396 <- [1,8,null,{"value":null}]
15:56:53 INFO - 1500677813633 Marionette TRACE 396 -> [0,9,"deleteSession",{}]
15:56:53 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:53 INFO - 1500677813639 Marionette TRACE 396 <- [1,9,null,{}]
15:56:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1868ms
15:56:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:56:53 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:53 INFO - 1500677813690 Marionette DEBUG Closed connection 396
15:56:53 INFO - 1500677813692 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49831
15:56:53 INFO - 1500677813708 Marionette DEBUG Closed connection 397
15:56:53 INFO - 1500677813710 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49832
15:56:53 INFO - 1500677813713 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:53 INFO - 1500677813716 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:53 INFO - 1500677813768 Marionette DEBUG Register listener.js for window 15
15:56:53 INFO - 1500677813784 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1202bb3b-4307-4fed-8e22-71f95e8977cd","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:53 INFO - 1500677813836 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
15:56:53 INFO - 1500677813838 Marionette TRACE 398 <- [1,2,null,{}]
15:56:53 INFO - 1500677813844 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:56:53 INFO - 1500677813846 Marionette TRACE 398 <- [1,3,null,{}]
15:56:53 INFO - 1500677813848 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
15:56:53 INFO - 1500677813850 Marionette TRACE 398 <- [1,4,null,{}]
15:56:54 INFO - 1500677814017 Marionette TRACE 398 -> [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}]
15:56:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500677814029 Marionette TRACE 398 <- [1,5,null,{"value":null}]
15:56:54 INFO - 1500677814035 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
15:56:54 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:54 INFO - 1500677814045 Marionette TRACE 398 <- [1,6,null,{}]
15:56:54 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:54 INFO - 1500677814071 Marionette DEBUG Closed connection 398
15:56:54 INFO - 1500677814074 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49833
15:56:54 INFO - 1500677814127 Marionette DEBUG Closed connection 399
15:56:54 INFO - 1500677814130 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49834
15:56:54 INFO - 1500677814140 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:56:54 INFO - 1500677814149 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:54 INFO - 1500677814179 Marionette DEBUG Register listener.js for window 15
15:56:54 INFO - 1500677814207 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d4550e40-4914-4241-af09-82d0ce13d4a3","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:54 INFO - 1500677814234 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
15:56:54 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:54 INFO - 1500677814241 Marionette TRACE 400 <- [1,2,null,{}]
15:56:54 INFO - [Parent 2968] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:56:54 INFO - 1500677814259 Marionette DEBUG Closed connection 400
15:56:54 INFO - 1500677814262 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49835
15:56:54 INFO - 1500677814269 Marionette DEBUG Closed connection 401
15:56:54 INFO - 1500677814272 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49836
15:56:54 INFO - 1500677814319 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:56:54 INFO - 1500677814323 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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}
15:56:54 INFO - 1500677814352 Marionette DEBUG Register listener.js for window 15
15:56:54 INFO - 1500677814369 Marionette TRACE 402 <- [1,1,null,{"sessionId":"0f786f45-6a4e-4d4f-9b41-ff396cfa9aab","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":2968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-115.000\\AppData\\Local\\Temp\\tmpsmwg77.mozrunner","moz:accessibilityChecks":false}}]
15:56:54 INFO - 1500677814385 Marionette TRACE 402 -> [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"}]
15:56:54 INFO - 1500677814406 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"
15:56:54 INFO - 1500677814414 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"
15:56:54 INFO - 1500677814415 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"
15:56:54 INFO - ++DOMWINDOW == 29 (000000CD8F7C3000) [pid = 2968] [serial = 48] [outer = 000000CD94FB4800]
15:56:54 INFO - 1500677814500 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"
15:56:54 INFO - 1500677814506 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"
15:56:54 INFO - 1500677814526 Marionette TRACE 402 <- [1,2,null,{}]
15:56:54 INFO - 1500677814537 Marionette TRACE 402 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:56:54 INFO - 1500677814546 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4b038381-5ee1-4367-8d14-0270a5a26922","ELEMENT":"4b038381-5ee1-4367-8d14-0270a5a26922"}}]
15:56:54 INFO - 1500677814549 Marionette TRACE 402 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4b038381-5ee1-4367-8d14-0270a5a26922"}]
15:56:54 INFO - 1500677814702 Marionette TRACE 402 <- [1,4,null,{"value":"none"}]
15:56:54 INFO - 1500677814706 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"4b038381-5ee1-4367-8d14-0270a5a26922"}]
15:56:54 INFO - 1500677814864 Marionette TRACE 402 <- [1,5,{"error":"element click intercepted","message":"Element