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]
09:29:50 INFO - 1500308990288 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}]
09:29:50 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500308990296 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:29:50 INFO - 1500308990300 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990305 Marionette TRACE 354 <- [1,6,null,{}]
09:29:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 623ms
09:29:50 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990313 Marionette DEBUG Closed connection 354
09:29:50 INFO - 1500308990315 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49787
09:29:50 INFO - 1500308990317 Marionette DEBUG Closed connection 355
09:29:50 INFO - 1500308990318 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49788
09:29:50 INFO - 1500308990320 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:50 INFO - 1500308990322 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:50 INFO - 1500308990343 Marionette DEBUG Register listener.js for window 15
09:29:50 INFO - 1500308990353 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9f51ccf6-8598-4022-a976-97427f18e531","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:50 INFO - 1500308990382 Marionette TRACE 356 -> [0,2,"setTimeouts",{"script":30000}]
09:29:50 INFO - 1500308990384 Marionette TRACE 356 <- [1,2,null,{}]
09:29:50 INFO - 1500308990387 Marionette TRACE 356 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:50 INFO - 1500308990389 Marionette TRACE 356 <- [1,3,null,{}]
09:29:50 INFO - 1500308990391 Marionette TRACE 356 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:50 INFO - 1500308990393 Marionette TRACE 356 <- [1,4,null,{}]
09:29:50 INFO - 1500308990395 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}]
09:29:50 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500308990403 Marionette TRACE 356 <- [1,5,null,{"value":null}]
09:29:50 INFO - 1500308990405 Marionette TRACE 356 -> [0,6,"deleteSession",{}]
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990410 Marionette TRACE 356 <- [1,6,null,{}]
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990436 Marionette DEBUG Closed connection 356
09:29:50 INFO - 1500308990437 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49789
09:29:50 INFO - 1500308990442 Marionette DEBUG Closed connection 357
09:29:50 INFO - 1500308990443 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49790
09:29:50 INFO - 1500308990445 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:50 INFO - 1500308990447 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:50 INFO - 1500308990467 Marionette DEBUG Register listener.js for window 15
09:29:50 INFO - 1500308990490 Marionette TRACE 358 <- [1,1,null,{"sessionId":"513fdaf9-dc35-4939-9690-b2b96d967de6","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:50 INFO - 1500308990507 Marionette TRACE 358 -> [0,2,"deleteSession",{}]
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990512 Marionette TRACE 358 <- [1,2,null,{}]
09:29:50 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:50 INFO - 1500308990534 Marionette DEBUG Closed connection 358
09:29:50 INFO - 1500308990535 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49791
09:29:50 INFO - 1500308990537 Marionette DEBUG Closed connection 359
09:29:50 INFO - 1500308990538 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49792
09:29:50 INFO - 1500308990540 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:50 INFO - 1500308990543 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:50 INFO - 1500308990563 Marionette DEBUG Register listener.js for window 15
09:29:50 INFO - 1500308990573 Marionette TRACE 360 <- [1,1,null,{"sessionId":"728ae39d-f123-4657-94cf-d77d2ecad96d","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:50 INFO - 1500308990579 Marionette TRACE 360 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
09:29:50 INFO - 1500308990583 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
09:29:50 INFO - 1500308990602 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
09:29:50 INFO - 1500308990603 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
09:29:50 INFO - ++DOMWINDOW == 29 (00000069DFBB0800) [pid = 2424] [serial = 40] [outer = 00000069DC2A8800]
09:29:50 INFO - 1500308990659 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
09:29:50 INFO - 1500308990663 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
09:29:50 INFO - 1500308990681 Marionette TRACE 360 <- [1,2,null,{}]
09:29:50 INFO - 1500308990684 Marionette TRACE 360 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:29:50 INFO - 1500308990693 Marionette TRACE 360 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"425aa692-9022-450f-ac8f-37493045ebcf","ELEMENT":"425aa692-9022-450f-ac8f-37493045ebcf"}}]
09:29:51 INFO - 1500308991525 Marionette TRACE 360 -> [0,4,"clickElement",{"id":"425aa692-9022-450f-ac8f-37493045ebcf"}]
09:29:51 INFO - 1500308991812 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
09:29:51 INFO - [Parent 2424] 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
09:29:51 INFO - 1500308991835 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
09:29:51 INFO - 1500308991836 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
09:29:51 INFO - ++DOMWINDOW == 30 (00000069E3383800) [pid = 2424] [serial = 41] [outer = 00000069DC2A8800]
09:29:51 INFO - 1500308991974 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
09:29:51 INFO - 1500308991986 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
09:29:52 INFO - 1500308992213 Marionette TRACE 360 <- [1,4,null,{}]
09:29:52 INFO - 1500308992219 Marionette TRACE 360 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:29:52 INFO - 1500308992225 Marionette TRACE 360 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"877acd36-b7a5-46b6-9916-907c5dba6505","ELEMENT":"877acd36-b7a5-46b6-9916-907c5dba6505"}}]
09:29:52 INFO - 1500308992227 Marionette TRACE 360 -> [0,6,"getTitle",{}]
09:29:52 INFO - 1500308992228 Marionette TRACE 360 <- [1,6,null,{"value":"Marionette Test"}]
09:29:52 INFO - 1500308992235 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}]
09:29:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500308992243 Marionette TRACE 360 <- [1,7,null,{"value":null}]
09:29:52 INFO - 1500308992245 Marionette TRACE 360 -> [0,8,"deleteSession",{}]
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992249 Marionette TRACE 360 <- [1,8,null,{}]
09:29:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1943ms
09:29:52 INFO - TEST-START | test_click.py TestClick.test_container_element
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992255 Marionette DEBUG Closed connection 360
09:29:52 INFO - 1500308992257 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49795
09:29:52 INFO - 1500308992258 Marionette DEBUG Closed connection 361
09:29:52 INFO - 1500308992259 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49796
09:29:52 INFO - 1500308992261 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:52 INFO - 1500308992263 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:52 INFO - 1500308992284 Marionette DEBUG Register listener.js for window 15
09:29:52 INFO - 1500308992295 Marionette TRACE 362 <- [1,1,null,{"sessionId":"82630c47-7a5f-4d5d-9c8d-7ebf8a3d4890","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:52 INFO - 1500308992327 Marionette TRACE 362 -> [0,2,"setTimeouts",{"script":30000}]
09:29:52 INFO - 1500308992328 Marionette TRACE 362 <- [1,2,null,{}]
09:29:52 INFO - 1500308992354 Marionette TRACE 362 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:52 INFO - 1500308992356 Marionette TRACE 362 <- [1,3,null,{}]
09:29:52 INFO - 1500308992358 Marionette TRACE 362 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:52 INFO - 1500308992359 Marionette TRACE 362 <- [1,4,null,{}]
09:29:52 INFO - 1500308992391 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}]
09:29:52 INFO - TEST-START: test_click.py TestClick.test_container_element1500308992399 Marionette TRACE 362 <- [1,5,null,{"value":null}]
09:29:52 INFO - 1500308992403 Marionette TRACE 362 -> [0,6,"deleteSession",{}]
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992407 Marionette TRACE 362 <- [1,6,null,{}]
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992429 Marionette DEBUG Closed connection 362
09:29:52 INFO - 1500308992431 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49797
09:29:52 INFO - 1500308992527 Marionette DEBUG Closed connection 363
09:29:52 INFO - 1500308992528 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49798
09:29:52 INFO - 1500308992534 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:52 INFO - 1500308992536 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:52 INFO - 1500308992557 Marionette DEBUG Register listener.js for window 15
09:29:52 INFO - 1500308992570 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9f4f16ca-be11-420e-9dc0-0569823ce57d","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:52 INFO - 1500308992580 Marionette TRACE 364 -> [0,2,"deleteSession",{}]
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992584 Marionette TRACE 364 <- [1,2,null,{}]
09:29:52 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:52 INFO - 1500308992590 Marionette DEBUG Closed connection 364
09:29:52 INFO - 1500308992592 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49799
09:29:52 INFO - 1500308992593 Marionette DEBUG Closed connection 365
09:29:52 INFO - 1500308992595 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49800
09:29:52 INFO - 1500308992597 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:52 INFO - 1500308992599 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:52 INFO - 1500308992620 Marionette DEBUG Register listener.js for window 15
09:29:52 INFO - 1500308992631 Marionette TRACE 366 <- [1,1,null,{"sessionId":"629359b1-c6e2-4881-a91b-410bada407ef","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:52 INFO - 1500308992668 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"}]
09:29:52 INFO - 1500308992672 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
09:29:52 INFO - 1500308992681 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
09:29:52 INFO - 1500308992682 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
09:29:52 INFO - ++DOMWINDOW == 31 (00000069CF4A0000) [pid = 2424] [serial = 42] [outer = 00000069DC2A8800]
09:29:52 INFO - 1500308992751 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"
09:29:52 INFO - 1500308992756 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"
09:29:52 INFO - 1500308992777 Marionette TRACE 366 <- [1,2,null,{}]
09:29:52 INFO - 1500308992808 Marionette TRACE 366 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:29:52 INFO - 1500308992824 Marionette TRACE 366 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7aa21bf-ba05-4462-847f-8e944ee68cd4","ELEMENT":"d7aa21bf-ba05-4462-847f-8e944ee68cd4"}}]
09:29:52 INFO - 1500308992838 Marionette TRACE 366 -> [0,4,"clickElement",{"id":"d7aa21bf-ba05-4462-847f-8e944ee68cd4"}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
09:29:53 INFO - --DOMWINDOW == 30 (00000069D7570800) [pid = 2424] [serial = 21] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 29 (00000069D3FD3800) [pid = 2424] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 28 (00000069CFCE0800) [pid = 2424] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 27 (00000069D3FD2000) [pid = 2424] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 26 (00000069D7565000) [pid = 2424] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 25 (00000069DA225000) [pid = 2424] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 24 (00000069E3A52800) [pid = 2424] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 23 (00000069E54E3800) [pid = 2424] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - --DOMWINDOW == 22 (00000069E419A800) [pid = 2424] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
09:29:53 INFO - 1500308993270 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:29:53 INFO - 1500308993272 Marionette TRACE 366 <- [1,4,null,{}]
09:29:53 INFO - 1500308993276 Marionette TRACE 366 -> [0,5,"getElementProperty",{"id":"d7aa21bf-ba05-4462-847f-8e944ee68cd4","name":"selected"}]
09:29:53 INFO - 1500308993304 Marionette TRACE 366 <- [1,5,null,{"value":true}]
09:29:53 INFO - 1500308993307 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}]
09:29:53 INFO - TEST-END: test_click.py TestClick.test_container_element1500308993314 Marionette TRACE 366 <- [1,6,null,{"value":null}]
09:29:53 INFO - 1500308993316 Marionette TRACE 366 -> [0,7,"deleteSession",{}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993320 Marionette TRACE 366 <- [1,7,null,{}]
09:29:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1070ms
09:29:53 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993326 Marionette DEBUG Closed connection 366
09:29:53 INFO - 1500308993327 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49801
09:29:53 INFO - 1500308993329 Marionette DEBUG Closed connection 367
09:29:53 INFO - 1500308993331 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49802
09:29:53 INFO - 1500308993333 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:53 INFO - 1500308993335 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:53 INFO - 1500308993355 Marionette DEBUG Register listener.js for window 15
09:29:53 INFO - 1500308993366 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cd6bb59a-3004-4f0e-8398-feae7dba1e5c","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:53 INFO - 1500308993372 Marionette TRACE 368 -> [0,2,"setTimeouts",{"script":30000}]
09:29:53 INFO - 1500308993374 Marionette TRACE 368 <- [1,2,null,{}]
09:29:53 INFO - 1500308993375 Marionette TRACE 368 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:53 INFO - 1500308993377 Marionette TRACE 368 <- [1,3,null,{}]
09:29:53 INFO - 1500308993378 Marionette TRACE 368 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:53 INFO - 1500308993380 Marionette TRACE 368 <- [1,4,null,{}]
09:29:53 INFO - 1500308993383 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}]
09:29:53 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500308993406 Marionette TRACE 368 <- [1,5,null,{"value":null}]
09:29:53 INFO - 1500308993408 Marionette TRACE 368 -> [0,6,"deleteSession",{}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993412 Marionette TRACE 368 <- [1,6,null,{}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993418 Marionette DEBUG Closed connection 368
09:29:53 INFO - 1500308993420 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49803
09:29:53 INFO - 1500308993421 Marionette DEBUG Closed connection 369
09:29:53 INFO - 1500308993423 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49804
09:29:53 INFO - 1500308993425 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:53 INFO - 1500308993427 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:53 INFO - 1500308993447 Marionette DEBUG Register listener.js for window 15
09:29:53 INFO - 1500308993459 Marionette TRACE 370 <- [1,1,null,{"sessionId":"4f3487cd-b7a2-4177-8f9c-276c01377512","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:53 INFO - 1500308993464 Marionette TRACE 370 -> [0,2,"deleteSession",{}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993468 Marionette TRACE 370 <- [1,2,null,{}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:53 INFO - 1500308993474 Marionette DEBUG Closed connection 370
09:29:53 INFO - 1500308993476 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49805
09:29:53 INFO - 1500308993477 Marionette DEBUG Closed connection 371
09:29:53 INFO - 1500308993479 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49806
09:29:53 INFO - 1500308993481 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:53 INFO - 1500308993483 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:53 INFO - 1500308993504 Marionette DEBUG Register listener.js for window 15
09:29:53 INFO - 1500308993516 Marionette TRACE 372 <- [1,1,null,{"sessionId":"69db2535-9a2b-4e72-8b1e-4b3ef862c21c","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:53 INFO - 1500308993522 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"}]
09:29:53 INFO - 1500308993528 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"
09:29:53 INFO - 1500308993535 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"
09:29:53 INFO - 1500308993537 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"
09:29:53 INFO - ++DOMWINDOW == 23 (00000069D3FD4800) [pid = 2424] [serial = 43] [outer = 00000069DC2A8800]
09:29:53 INFO - 1500308993585 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"
09:29:53 INFO - 1500308993595 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"
09:29:53 INFO - 1500308993613 Marionette TRACE 372 <- [1,2,null,{}]
09:29:53 INFO - 1500308993616 Marionette TRACE 372 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:29:53 INFO - 1500308993623 Marionette TRACE 372 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"95ab6e30-a7c6-4ffc-b69a-f5e2fcc501ca","ELEMENT":"95ab6e30-a7c6-4ffc-b69a-f5e2fcc501ca"}}]
09:29:53 INFO - 1500308993626 Marionette TRACE 372 -> [0,4,"clickElement",{"id":"95ab6e30-a7c6-4ffc-b69a-f5e2fcc501ca"}]
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
09:29:53 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
09:29:54 INFO - 1500308994057 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:29:54 INFO - 1500308994058 Marionette TRACE 372 <- [1,4,null,{}]
09:29:54 INFO - 1500308994072 Marionette TRACE 372 -> [0,5,"getElementProperty",{"id":"95ab6e30-a7c6-4ffc-b69a-f5e2fcc501ca","name":"selected"}]
09:29:54 INFO - 1500308994099 Marionette TRACE 372 <- [1,5,null,{"value":true}]
09:29:54 INFO - 1500308994102 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}]
09:29:54 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500308994109 Marionette TRACE 372 <- [1,6,null,{"value":null}]
09:29:54 INFO - 1500308994111 Marionette TRACE 372 -> [0,7,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994115 Marionette TRACE 372 <- [1,7,null,{}]
09:29:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 795ms
09:29:54 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994121 Marionette DEBUG Closed connection 372
09:29:54 INFO - 1500308994123 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49807
09:29:54 INFO - 1500308994124 Marionette DEBUG Closed connection 373
09:29:54 INFO - 1500308994125 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49808
09:29:54 INFO - 1500308994127 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:54 INFO - 1500308994129 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:54 INFO - 1500308994150 Marionette DEBUG Register listener.js for window 15
09:29:54 INFO - 1500308994162 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d5faa0cf-1415-43a6-afe9-55d6d4b6dfe2","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:54 INFO - 1500308994168 Marionette TRACE 374 -> [0,2,"setTimeouts",{"script":30000}]
09:29:54 INFO - 1500308994169 Marionette TRACE 374 <- [1,2,null,{}]
09:29:54 INFO - 1500308994171 Marionette TRACE 374 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:54 INFO - 1500308994172 Marionette TRACE 374 <- [1,3,null,{}]
09:29:54 INFO - 1500308994177 Marionette TRACE 374 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:54 INFO - 1500308994178 Marionette TRACE 374 <- [1,4,null,{}]
09:29:54 INFO - 1500308994181 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}]
09:29:54 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500308994188 Marionette TRACE 374 <- [1,5,null,{"value":null}]
09:29:54 INFO - 1500308994190 Marionette TRACE 374 -> [0,6,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994194 Marionette TRACE 374 <- [1,6,null,{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994200 Marionette DEBUG Closed connection 374
09:29:54 INFO - 1500308994202 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49809
09:29:54 INFO - 1500308994203 Marionette DEBUG Closed connection 375
09:29:54 INFO - 1500308994205 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49810
09:29:54 INFO - 1500308994207 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:54 INFO - 1500308994209 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:54 INFO - 1500308994229 Marionette DEBUG Register listener.js for window 15
09:29:54 INFO - 1500308994240 Marionette TRACE 376 <- [1,1,null,{"sessionId":"bbf9083d-b2a8-4541-8c63-d25dc2e33423","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:54 INFO - 1500308994246 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994250 Marionette TRACE 376 <- [1,2,null,{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994256 Marionette DEBUG Closed connection 376
09:29:54 INFO - 1500308994257 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49811
09:29:54 INFO - 1500308994259 Marionette DEBUG Closed connection 377
09:29:54 INFO - 1500308994260 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49812
09:29:54 INFO - 1500308994262 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:54 INFO - 1500308994265 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:54 INFO - 1500308994285 Marionette DEBUG Register listener.js for window 15
09:29:54 INFO - 1500308994296 Marionette TRACE 378 <- [1,1,null,{"sessionId":"54b09ace-3162-47bc-b10f-55c549615e50","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:54 INFO - 1500308994302 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"}]
09:29:54 INFO - 1500308994306 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"
09:29:54 INFO - 1500308994312 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"
09:29:54 INFO - 1500308994313 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"
09:29:54 INFO - ++DOMWINDOW == 24 (00000069D3CE7800) [pid = 2424] [serial = 44] [outer = 00000069DC2A8800]
09:29:54 INFO - 1500308994364 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"
09:29:54 INFO - 1500308994369 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"
09:29:54 INFO - 1500308994378 Marionette TRACE 378 <- [1,2,null,{}]
09:29:54 INFO - 1500308994389 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:29:54 INFO - 1500308994396 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88542951-347c-4b70-bf6c-7dbac38408f4","ELEMENT":"88542951-347c-4b70-bf6c-7dbac38408f4"}}]
09:29:54 INFO - 1500308994399 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"88542951-347c-4b70-bf6c-7dbac38408f4"}]
09:29:54 INFO - 1500308994835 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:29:54 INFO - 1500308994836 Marionette TRACE 378 <- [1,4,null,{}]
09:29:54 INFO - 1500308994839 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}]
09:29:54 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500308994846 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:29:54 INFO - 1500308994848 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994852 Marionette TRACE 378 <- [1,6,null,{}]
09:29:54 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 737ms
09:29:54 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994858 Marionette DEBUG Closed connection 378
09:29:54 INFO - 1500308994860 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49813
09:29:54 INFO - 1500308994861 Marionette DEBUG Closed connection 379
09:29:54 INFO - 1500308994862 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49814
09:29:54 INFO - 1500308994864 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:54 INFO - 1500308994866 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:54 INFO - 1500308994887 Marionette DEBUG Register listener.js for window 15
09:29:54 INFO - 1500308994898 Marionette TRACE 380 <- [1,1,null,{"sessionId":"7f596634-d67d-4b9d-85d9-04426f9f2732","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:54 INFO - 1500308994904 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
09:29:54 INFO - 1500308994905 Marionette TRACE 380 <- [1,2,null,{}]
09:29:54 INFO - 1500308994907 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:54 INFO - 1500308994909 Marionette TRACE 380 <- [1,3,null,{}]
09:29:54 INFO - 1500308994911 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:54 INFO - 1500308994912 Marionette TRACE 380 <- [1,4,null,{}]
09:29:54 INFO - 1500308994915 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}]
09:29:54 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500308994922 Marionette TRACE 380 <- [1,5,null,{"value":null}]
09:29:54 INFO - 1500308994923 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994927 Marionette TRACE 380 <- [1,6,null,{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994933 Marionette DEBUG Closed connection 380
09:29:54 INFO - 1500308994934 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49815
09:29:54 INFO - 1500308994936 Marionette DEBUG Closed connection 381
09:29:54 INFO - 1500308994937 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49816
09:29:54 INFO - 1500308994942 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:54 INFO - 1500308994944 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:54 INFO - 1500308994964 Marionette DEBUG Register listener.js for window 15
09:29:54 INFO - 1500308994975 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ab9ba438-ec18-4033-bdce-1e85dbcbb233","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:54 INFO - 1500308994981 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994985 Marionette TRACE 382 <- [1,2,null,{}]
09:29:54 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:54 INFO - 1500308994991 Marionette DEBUG Closed connection 382
09:29:54 INFO - 1500308994992 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49817
09:29:54 INFO - 1500308994994 Marionette DEBUG Closed connection 383
09:29:54 INFO - 1500308994995 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49818
09:29:55 INFO - 1500308994998 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:55 INFO - 1500308995000 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:55 INFO - 1500308995021 Marionette DEBUG Register listener.js for window 15
09:29:55 INFO - 1500308995033 Marionette TRACE 384 <- [1,1,null,{"sessionId":"48dccb76-58db-4361-ad8d-d0b7d82edb00","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:55 INFO - 1500308995039 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"}]
09:29:55 INFO - 1500308995043 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"
09:29:55 INFO - 1500308995051 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"
09:29:55 INFO - 1500308995052 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"
09:29:55 INFO - ++DOMWINDOW == 25 (00000069D8063800) [pid = 2424] [serial = 45] [outer = 00000069DC2A8800]
09:29:55 INFO - 1500308995103 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"
09:29:55 INFO - 1500308995108 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"
09:29:55 INFO - 1500308995125 Marionette TRACE 384 <- [1,2,null,{}]
09:29:55 INFO - 1500308995128 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:29:55 INFO - 1500308995135 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18d23039-9095-430c-99af-be5d6089087f","ELEMENT":"18d23039-9095-430c-99af-be5d6089087f"}}]
09:29:55 INFO - 1500308995137 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"18d23039-9095-430c-99af-be5d6089087f"}]
09:29:55 INFO - 1500308995569 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:29:55 INFO - 1500308995571 Marionette TRACE 384 <- [1,4,null,{}]
09:29:55 INFO - 1500308995573 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}]
09:29:55 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500308995580 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:29:55 INFO - 1500308995582 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995586 Marionette TRACE 384 <- [1,6,null,{}]
09:29:55 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 735ms
09:29:55 INFO - TEST-START | test_click.py TestClick.test_input_file
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995592 Marionette DEBUG Closed connection 384
09:29:55 INFO - 1500308995593 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49819
09:29:55 INFO - 1500308995595 Marionette DEBUG Closed connection 385
09:29:55 INFO - 1500308995596 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49820
09:29:55 INFO - 1500308995598 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:55 INFO - 1500308995600 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:55 INFO - 1500308995621 Marionette DEBUG Register listener.js for window 15
09:29:55 INFO - 1500308995643 Marionette TRACE 386 <- [1,1,null,{"sessionId":"54e412e1-f8f7-4172-975f-2e8307e614d2","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:55 INFO - 1500308995650 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
09:29:55 INFO - 1500308995651 Marionette TRACE 386 <- [1,2,null,{}]
09:29:55 INFO - 1500308995653 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:55 INFO - 1500308995654 Marionette TRACE 386 <- [1,3,null,{}]
09:29:55 INFO - 1500308995656 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:55 INFO - 1500308995657 Marionette TRACE 386 <- [1,4,null,{}]
09:29:55 INFO - 1500308995660 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}]
09:29:55 INFO - TEST-START: test_click.py TestClick.test_input_file1500308995667 Marionette TRACE 386 <- [1,5,null,{"value":null}]
09:29:55 INFO - 1500308995669 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995673 Marionette TRACE 386 <- [1,6,null,{}]
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995681 Marionette DEBUG Closed connection 386
09:29:55 INFO - 1500308995683 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49821
09:29:55 INFO - 1500308995684 Marionette DEBUG Closed connection 387
09:29:55 INFO - 1500308995686 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49822
09:29:55 INFO - 1500308995688 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:55 INFO - 1500308995690 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:55 INFO - 1500308995711 Marionette DEBUG Register listener.js for window 15
09:29:55 INFO - 1500308995722 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1ba69618-513f-44b2-beeb-9e5286e6dbf5","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:55 INFO - 1500308995728 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995732 Marionette TRACE 388 <- [1,2,null,{}]
09:29:55 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:55 INFO - 1500308995738 Marionette DEBUG Closed connection 388
09:29:55 INFO - 1500308995739 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49823
09:29:55 INFO - 1500308995741 Marionette DEBUG Closed connection 389
09:29:55 INFO - 1500308995742 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49824
09:29:55 INFO - 1500308995744 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:55 INFO - 1500308995746 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:55 INFO - 1500308995768 Marionette DEBUG Register listener.js for window 15
09:29:55 INFO - 1500308995779 Marionette TRACE 390 <- [1,1,null,{"sessionId":"52020bc7-e0e3-4cb1-809b-33f4b88d0bba","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:55 INFO - 1500308995785 Marionette TRACE 390 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:29:55 INFO - 1500308995788 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"
09:29:55 INFO - 1500308995794 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"
09:29:55 INFO - 1500308995796 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"
09:29:55 INFO - ++DOMWINDOW == 26 (00000069DBF3D800) [pid = 2424] [serial = 46] [outer = 00000069DC2A8800]
09:29:55 INFO - 1500308995841 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:29:55 INFO - 1500308995855 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:29:55 INFO - 1500308995869 Marionette TRACE 390 <- [1,2,null,{}]
09:29:55 INFO - 1500308995872 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:29:55 INFO - 1500308995880 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf35a726-e94b-46bc-b416-cef02307fa2c","ELEMENT":"bf35a726-e94b-46bc-b416-cef02307fa2c"}}]
09:29:55 INFO - 1500308995882 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"bf35a726-e94b-46bc-b416-cef02307fa2c"}]
09:29:56 INFO - 1500308996028 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]
09:29:56 INFO - 1500308996031 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}]
09:29:56 INFO - TEST-END: test_click.py TestClick.test_input_file1500308996038 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:29:56 INFO - 1500308996040 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996044 Marionette TRACE 390 <- [1,6,null,{}]
09:29:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 458ms
09:29:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996050 Marionette DEBUG Closed connection 390
09:29:56 INFO - 1500308996052 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49825
09:29:56 INFO - 1500308996053 Marionette DEBUG Closed connection 391
09:29:56 INFO - 1500308996055 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49826
09:29:56 INFO - 1500308996057 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:56 INFO - 1500308996059 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:56 INFO - 1500308996080 Marionette DEBUG Register listener.js for window 15
09:29:56 INFO - 1500308996091 Marionette TRACE 392 <- [1,1,null,{"sessionId":"209a867b-8126-4011-8fd4-56d2e54da4b8","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:56 INFO - 1500308996097 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
09:29:56 INFO - 1500308996098 Marionette TRACE 392 <- [1,2,null,{}]
09:29:56 INFO - 1500308996100 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:56 INFO - 1500308996101 Marionette TRACE 392 <- [1,3,null,{}]
09:29:56 INFO - 1500308996103 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:56 INFO - 1500308996105 Marionette TRACE 392 <- [1,4,null,{}]
09:29:56 INFO - 1500308996107 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}]
09:29:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500308996114 Marionette TRACE 392 <- [1,5,null,{"value":null}]
09:29:56 INFO - 1500308996116 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996120 Marionette TRACE 392 <- [1,6,null,{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996126 Marionette DEBUG Closed connection 392
09:29:56 INFO - 1500308996127 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49827
09:29:56 INFO - 1500308996129 Marionette DEBUG Closed connection 393
09:29:56 INFO - 1500308996130 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49828
09:29:56 INFO - 1500308996132 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:56 INFO - 1500308996134 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:56 INFO - 1500308996156 Marionette DEBUG Register listener.js for window 15
09:29:56 INFO - 1500308996166 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2be95b51-8c69-416d-aa36-b09f95bf02c4","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:56 INFO - 1500308996172 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996176 Marionette TRACE 394 <- [1,2,null,{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996182 Marionette DEBUG Closed connection 394
09:29:56 INFO - 1500308996184 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49829
09:29:56 INFO - 1500308996185 Marionette DEBUG Closed connection 395
09:29:56 INFO - 1500308996187 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49830
09:29:56 INFO - 1500308996189 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:56 INFO - 1500308996191 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:56 INFO - 1500308996213 Marionette DEBUG Register listener.js for window 15
09:29:56 INFO - 1500308996223 Marionette TRACE 396 <- [1,1,null,{"sessionId":"47c7d3ba-dee6-4cbd-9592-9b5dc4dfd165","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:56 INFO - 1500308996229 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"}]
09:29:56 INFO - 1500308996233 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:29:56 INFO - 1500308996240 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:29:56 INFO - 1500308996241 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:29:56 INFO - ++DOMWINDOW == 27 (00000069DC0C7800) [pid = 2424] [serial = 47] [outer = 00000069DC2A8800]
09:29:56 INFO - 1500308996298 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"
09:29:56 INFO - 1500308996303 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"
09:29:56 INFO - 1500308996310 Marionette TRACE 396 <- [1,2,null,{}]
09:29:56 INFO - 1500308996320 Marionette TRACE 396 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:29:56 INFO - 1500308996327 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"39fd87aa-e524-45b3-b11f-3e144558b216","ELEMENT":"39fd87aa-e524-45b3-b11f-3e144558b216"}}]
09:29:56 INFO - 1500308996329 Marionette TRACE 396 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:29:56 INFO - 1500308996335 Marionette TRACE 396 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e253422-f885-4da8-86e7-affea31a23ad","ELEMENT":"6e253422-f885-4da8-86e7-affea31a23ad"}}]
09:29:56 INFO - 1500308996337 Marionette TRACE 396 -> [0,5,"clickElement",{"id":"39fd87aa-e524-45b3-b11f-3e144558b216"}]
09:29:56 INFO - 1500308996773 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:29:56 INFO - 1500308996775 Marionette TRACE 396 <- [1,5,null,{}]
09:29:56 INFO - 1500308996777 Marionette TRACE 396 -> [0,6,"clickElement",{"id":"6e253422-f885-4da8-86e7-affea31a23ad"}]
09:29:56 INFO - 1500308996914 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]
09:29:56 INFO - 1500308996918 Marionette TRACE 396 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:29:56 INFO - 1500308996926 Marionette TRACE 396 <- [1,7,null,{"value":false}]
09:29:56 INFO - 1500308996928 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}]
09:29:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500308996945 Marionette TRACE 396 <- [1,8,null,{"value":null}]
09:29:56 INFO - 1500308996947 Marionette TRACE 396 -> [0,9,"deleteSession",{}]
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996952 Marionette TRACE 396 <- [1,9,null,{}]
09:29:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 909ms
09:29:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:29:56 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:56 INFO - 1500308996959 Marionette DEBUG Closed connection 396
09:29:56 INFO - 1500308996961 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49831
09:29:56 INFO - 1500308996963 Marionette DEBUG Closed connection 397
09:29:56 INFO - 1500308996964 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49832
09:29:56 INFO - 1500308996966 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:56 INFO - 1500308996968 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:56 INFO - 1500308996990 Marionette DEBUG Register listener.js for window 15
09:29:57 INFO - 1500308997001 Marionette TRACE 398 <- [1,1,null,{"sessionId":"84f36943-232a-4bed-8aa2-1c0d41516a2a","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:57 INFO - 1500308997007 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
09:29:57 INFO - 1500308997009 Marionette TRACE 398 <- [1,2,null,{}]
09:29:57 INFO - 1500308997011 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:29:57 INFO - 1500308997012 Marionette TRACE 398 <- [1,3,null,{}]
09:29:57 INFO - 1500308997014 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
09:29:57 INFO - 1500308997015 Marionette TRACE 398 <- [1,4,null,{}]
09:29:57 INFO - 1500308997018 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}]
09:29:57 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500308997026 Marionette TRACE 398 <- [1,5,null,{"value":null}]
09:29:57 INFO - 1500308997028 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
09:29:57 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:57 INFO - 1500308997032 Marionette TRACE 398 <- [1,6,null,{}]
09:29:57 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:57 INFO - 1500308997038 Marionette DEBUG Closed connection 398
09:29:57 INFO - 1500308997039 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49833
09:29:57 INFO - 1500308997041 Marionette DEBUG Closed connection 399
09:29:57 INFO - 1500308997042 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49834
09:29:57 INFO - 1500308997044 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:29:57 INFO - 1500308997046 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:57 INFO - 1500308997068 Marionette DEBUG Register listener.js for window 15
09:29:57 INFO - 1500308997079 Marionette TRACE 400 <- [1,1,null,{"sessionId":"624af480-4675-4bc2-9659-d1f2d9f748c8","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:57 INFO - 1500308997084 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
09:29:57 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:57 INFO - 1500308997088 Marionette TRACE 400 <- [1,2,null,{}]
09:29:57 INFO - [Parent 2424] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
09:29:57 INFO - 1500308997094 Marionette DEBUG Closed connection 400
09:29:57 INFO - 1500308997096 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49835
09:29:57 INFO - 1500308997097 Marionette DEBUG Closed connection 401
09:29:57 INFO - 1500308997099 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49836
09:29:57 INFO - 1500308997101 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:29:57 INFO - 1500308997103 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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}
09:29:57 INFO - 1500308997125 Marionette DEBUG Register listener.js for window 15
09:29:57 INFO - 1500308997135 Marionette TRACE 402 <- [1,1,null,{"sessionId":"0213d50b-c6b1-435b-9ac1-611f5da363e6","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":2424,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-328.001\\AppData\\Local\\Temp\\tmpxj_7zx.mozrunner","moz:accessibilityChecks":false}}]
09:29:57 INFO - 1500308997141 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"}]
09:29:57 INFO - 1500308997145 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"
09:29:57 INFO - 1500308997151 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"
09:29:57 INFO - 1500308997151 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"
09:29:57 INFO - ++DOMWINDOW == 28 (00000069E2A23000) [pid = 2424] [serial = 48] [outer = 00000069DC2A8800]
09:29:57 INFO - 1500308997203 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"
09:29:57 INFO - 1500308997208 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"
09:29:57 INFO - 1500308997224 Marionette TRACE 402 <- [1,2,null,{}]
09:29:57 INFO - 1500308997228 Marionette TRACE 402 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:29:57 INFO - 1500308997234 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb5b2e25-9bb0-4e1f-8d2b-8d4dae1825a8","ELEMENT":"fb5b2e25-9bb0-4e1f-8d2b-8d4dae1825a8"}}]
09:29:57 INFO - 1500308997236 Marionette TRACE 402 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fb5b2e25-9bb0-4e1f-8d2b-8d4dae1825a8"}]
09:29:57 INFO - 1500308997359 Marionette TRACE 402 <- [1,4,null,{"value":"none"}]
09:29:57 INFO - 1500308997362 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"fb5b2e25-9bb0-4e1f-8d2b-8d4dae1825a8"}]
09:29:57 INFO - 1500308997492 Marionette TRACE 402 <- [1,5,{"error":"element click intercepted","message":"Element