could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:18:59 INFO - 1499278739030 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
11:18:59 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499278739040 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:18:59 INFO - 1499278739042 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739046 Marionette TRACE 352 <- [1,6,null,{}]
11:18:59 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1477ms
11:18:59 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739053 Marionette DEBUG Closed connection 352
11:18:59 INFO - 1499278739054 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49781
11:18:59 INFO - 1499278739056 Marionette DEBUG Closed connection 353
11:18:59 INFO - 1499278739057 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
11:18:59 INFO - 1499278739059 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:18:59 INFO - 1499278739061 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:18:59 INFO - 1499278739082 Marionette DEBUG Register listener.js for window 12
11:18:59 INFO - 1499278739093 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1116f4ee-91e0-4140-ba19-fbddc2e21ba9","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:18:59 INFO - 1499278739199 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:18:59 INFO - 1499278739201 Marionette TRACE 354 <- [1,2,null,{}]
11:18:59 INFO - 1499278739254 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:18:59 INFO - 1499278739256 Marionette TRACE 354 <- [1,3,null,{}]
11:18:59 INFO - 1499278739297 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:18:59 INFO - 1499278739299 Marionette TRACE 354 <- [1,4,null,{}]
11:18:59 INFO - 1499278739302 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
11:18:59 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499278739309 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:18:59 INFO - 1499278739311 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739315 Marionette TRACE 354 <- [1,6,null,{}]
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739321 Marionette DEBUG Closed connection 354
11:18:59 INFO - 1499278739322 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
11:18:59 INFO - 1499278739324 Marionette DEBUG Closed connection 355
11:18:59 INFO - 1499278739325 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
11:18:59 INFO - 1499278739327 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:18:59 INFO - 1499278739330 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:18:59 INFO - 1499278739351 Marionette DEBUG Register listener.js for window 12
11:18:59 INFO - 1499278739363 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bd9b410e-c3da-4926-845e-805ae16fe867","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:18:59 INFO - 1499278739405 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739410 Marionette TRACE 356 <- [1,2,null,{}]
11:18:59 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:18:59 INFO - 1499278739438 Marionette DEBUG Closed connection 356
11:18:59 INFO - 1499278739441 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
11:18:59 INFO - 1499278739465 Marionette DEBUG Closed connection 357
11:18:59 INFO - 1499278739468 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
11:18:59 INFO - 1499278739474 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:18:59 INFO - 1499278739486 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:18:59 INFO - 1499278739529 Marionette DEBUG Register listener.js for window 12
11:18:59 INFO - 1499278739553 Marionette TRACE 358 <- [1,1,null,{"sessionId":"162e929c-5255-4663-a426-3f6a25276f41","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:18:59 INFO - 1499278739572 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
11:18:59 INFO - 1499278739579 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
11:18:59 INFO - 1499278739605 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
11:18:59 INFO - 1499278739607 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
11:18:59 INFO - ++DOMWINDOW == 30 (0000007E83E67800) [pid = 2448] [serial = 44] [outer = 0000007E8539F800]
11:18:59 INFO - 1499278739683 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
11:18:59 INFO - 1499278739691 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
11:18:59 INFO - 1499278739726 Marionette TRACE 358 <- [1,2,null,{}]
11:18:59 INFO - 1499278739735 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:18:59 INFO - 1499278739748 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12ecaf8f-ffa6-4331-8465-439216230587","ELEMENT":"12ecaf8f-ffa6-4331-8465-439216230587"}}]
11:18:59 INFO - 1499278739752 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"12ecaf8f-ffa6-4331-8465-439216230587"}]
11:19:00 INFO - 1499278740146 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
11:19:00 INFO - [2448] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
11:19:00 INFO - 1499278740165 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
11:19:00 INFO - 1499278740167 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
11:19:00 INFO - ++DOMWINDOW == 31 (0000007E84C29800) [pid = 2448] [serial = 45] [outer = 0000007E8539F800]
11:19:00 INFO - 1499278740245 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
11:19:00 INFO - 1499278740252 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
11:19:00 INFO - 1499278740273 Marionette TRACE 358 <- [1,4,null,{}]
11:19:00 INFO - 1499278740289 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:19:00 INFO - 1499278740299 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92f67f36-e3e6-40c7-9c9d-386dfce38a46","ELEMENT":"92f67f36-e3e6-40c7-9c9d-386dfce38a46"}}]
11:19:00 INFO - 1499278740342 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:19:00 INFO - 1499278740345 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:19:00 INFO - 1499278740348 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
11:19:00 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499278740361 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:19:00 INFO - 1499278740364 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740370 Marionette TRACE 358 <- [1,8,null,{}]
11:19:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1324ms
11:19:00 INFO - TEST-START | test_click.py TestClick.test_container_element
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740379 Marionette DEBUG Closed connection 358
11:19:00 INFO - 1499278740382 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49789
11:19:00 INFO - 1499278740387 Marionette DEBUG Closed connection 359
11:19:00 INFO - 1499278740389 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
11:19:00 INFO - 1499278740403 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:00 INFO - 1499278740407 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:00 INFO - 1499278740448 Marionette DEBUG Register listener.js for window 12
11:19:00 INFO - 1499278740470 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b5b3f4d1-aa3f-4e4f-bb5e-aa6123838729","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:00 INFO - 1499278740498 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:19:00 INFO - 1499278740501 Marionette TRACE 360 <- [1,2,null,{}]
11:19:00 INFO - 1499278740558 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:00 INFO - 1499278740564 Marionette TRACE 360 <- [1,3,null,{}]
11:19:00 INFO - 1499278740568 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:00 INFO - 1499278740570 Marionette TRACE 360 <- [1,4,null,{}]
11:19:00 INFO - 1499278740601 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
11:19:00 INFO - TEST-START: test_click.py TestClick.test_container_element1499278740615 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:19:00 INFO - 1499278740619 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740625 Marionette TRACE 360 <- [1,6,null,{}]
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740682 Marionette DEBUG Closed connection 360
11:19:00 INFO - 1499278740684 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
11:19:00 INFO - 1499278740688 Marionette DEBUG Closed connection 361
11:19:00 INFO - 1499278740691 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
11:19:00 INFO - 1499278740712 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:00 INFO - 1499278740716 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:00 INFO - 1499278740756 Marionette DEBUG Register listener.js for window 12
11:19:00 INFO - 1499278740773 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1e7a2002-5ab6-440f-b265-d6540102ea63","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:00 INFO - 1499278740780 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740785 Marionette TRACE 362 <- [1,2,null,{}]
11:19:00 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:00 INFO - 1499278740792 Marionette DEBUG Closed connection 362
11:19:00 INFO - 1499278740794 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
11:19:00 INFO - 1499278740796 Marionette DEBUG Closed connection 363
11:19:00 INFO - 1499278740799 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
11:19:00 INFO - 1499278740802 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:00 INFO - 1499278740805 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:00 INFO - 1499278740848 Marionette DEBUG Register listener.js for window 12
11:19:00 INFO - 1499278740876 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5fe3e1b9-1cf1-4ccb-b4fc-a156389e935c","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:00 INFO - 1499278740926 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:19:00 INFO - 1499278740934 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
11:19:00 INFO - 1499278740942 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
11:19:00 INFO - 1499278740943 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
11:19:00 INFO - ++DOMWINDOW == 32 (0000007EF9070800) [pid = 2448] [serial = 46] [outer = 0000007E8539F800]
11:19:01 INFO - 1499278741023 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"
11:19:01 INFO - 1499278741030 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"
11:19:01 INFO - 1499278741050 Marionette TRACE 364 <- [1,2,null,{}]
11:19:01 INFO - 1499278741058 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:19:01 INFO - 1499278741068 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4d2a0a34-4455-4100-a34e-0147bcae49fa","ELEMENT":"4d2a0a34-4455-4100-a34e-0147bcae49fa"}}]
11:19:01 INFO - 1499278741080 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"4d2a0a34-4455-4100-a34e-0147bcae49fa"}]
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
11:19:01 INFO - 1499278741658 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:19:01 INFO - 1499278741661 Marionette TRACE 364 <- [1,4,null,{}]
11:19:01 INFO - 1499278741667 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"4d2a0a34-4455-4100-a34e-0147bcae49fa","name":"selected"}]
11:19:01 INFO - 1499278741723 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:19:01 INFO - 1499278741732 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
11:19:01 INFO - TEST-END: test_click.py TestClick.test_container_element1499278741746 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:19:01 INFO - 1499278741763 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:01 INFO - 1499278741768 Marionette TRACE 364 <- [1,7,null,{}]
11:19:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1397ms
11:19:01 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:01 INFO - 1499278741775 Marionette DEBUG Closed connection 364
11:19:01 INFO - 1499278741777 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
11:19:01 INFO - 1499278741779 Marionette DEBUG Closed connection 365
11:19:01 INFO - 1499278741781 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
11:19:01 INFO - 1499278741784 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:01 INFO - 1499278741786 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:01 INFO - 1499278741827 Marionette DEBUG Register listener.js for window 12
11:19:01 INFO - 1499278741846 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6a846154-a2bf-413d-b084-da0a50b48674","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:01 INFO - 1499278741873 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:19:01 INFO - 1499278741875 Marionette TRACE 366 <- [1,2,null,{}]
11:19:01 INFO - 1499278741878 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:01 INFO - 1499278741879 Marionette TRACE 366 <- [1,3,null,{}]
11:19:01 INFO - 1499278741893 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:01 INFO - 1499278741895 Marionette TRACE 366 <- [1,4,null,{}]
11:19:01 INFO - 1499278741898 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
11:19:01 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499278741907 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:19:01 INFO - 1499278741936 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:01 INFO - 1499278741941 Marionette TRACE 366 <- [1,6,null,{}]
11:19:01 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:01 INFO - 1499278741975 Marionette DEBUG Closed connection 366
11:19:01 INFO - 1499278741980 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
11:19:01 INFO - 1499278741986 Marionette DEBUG Closed connection 367
11:19:01 INFO - 1499278741988 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
11:19:02 INFO - 1499278742011 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:02 INFO - 1499278742016 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:02 INFO - 1499278742058 Marionette DEBUG Register listener.js for window 12
11:19:02 INFO - 1499278742279 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cd125ed6-7ad7-40af-9237-dfa9868f89ee","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:02 INFO - 1499278742289 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:19:02 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:02 INFO - 1499278742298 Marionette TRACE 368 <- [1,2,null,{}]
11:19:02 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:02 INFO - 1499278742357 Marionette DEBUG Closed connection 368
11:19:02 INFO - 1499278742360 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
11:19:02 INFO - 1499278742387 Marionette DEBUG Closed connection 369
11:19:02 INFO - 1499278742389 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
11:19:02 INFO - 1499278742394 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:02 INFO - 1499278742399 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:02 INFO - 1499278742424 Marionette DEBUG Register listener.js for window 12
11:19:02 INFO - 1499278742446 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7f12c1eb-9191-47fd-8ef5-8a2bf8347e40","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:02 INFO - 1499278742487 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:19:02 INFO - 1499278742494 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"
11:19:02 INFO - 1499278742505 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"
11:19:02 INFO - 1499278742506 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"
11:19:02 INFO - ++DOMWINDOW == 33 (0000007E813A0800) [pid = 2448] [serial = 47] [outer = 0000007E8539F800]
11:19:02 INFO - 1499278742562 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"
11:19:02 INFO - 1499278742578 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"
11:19:02 INFO - 1499278742598 Marionette TRACE 370 <- [1,2,null,{}]
11:19:02 INFO - 1499278742611 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:19:02 INFO - 1499278742623 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f0a321a7-cb6c-4db4-bb38-19a895ca5f45","ELEMENT":"f0a321a7-cb6c-4db4-bb38-19a895ca5f45"}}]
11:19:02 INFO - 1499278742627 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f0a321a7-cb6c-4db4-bb38-19a895ca5f45"}]
11:19:02 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
11:19:02 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
11:19:02 INFO - [2448] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
11:19:02 INFO - --DOMWINDOW == 32 (0000007EF8B25800) [pid = 2448] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 31 (0000007E89D17800) [pid = 2448] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 30 (0000007E8568A800) [pid = 2448] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 29 (0000007E84682000) [pid = 2448] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 28 (0000007E908E1000) [pid = 2448] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 27 (0000007E8466B000) [pid = 2448] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:02 INFO - --DOMWINDOW == 26 (0000007E853AC800) [pid = 2448] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
11:19:03 INFO - 1499278743074 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:19:03 INFO - 1499278743077 Marionette TRACE 370 <- [1,4,null,{}]
11:19:03 INFO - 1499278743079 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f0a321a7-cb6c-4db4-bb38-19a895ca5f45","name":"selected"}]
11:19:03 INFO - 1499278743111 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:19:03 INFO - 1499278743114 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
11:19:03 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499278743124 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:19:03 INFO - 1499278743126 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:19:03 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:03 INFO - 1499278743132 Marionette TRACE 370 <- [1,7,null,{}]
11:19:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1364ms
11:19:03 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:19:03 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:03 INFO - 1499278743141 Marionette DEBUG Closed connection 370
11:19:03 INFO - 1499278743144 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
11:19:03 INFO - 1499278743147 Marionette DEBUG Closed connection 371
11:19:03 INFO - 1499278743149 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
11:19:03 INFO - 1499278743152 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:03 INFO - 1499278743155 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:03 INFO - 1499278743179 Marionette DEBUG Register listener.js for window 12
11:19:03 INFO - 1499278743197 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d9cd1122-1fc6-4b9c-81ea-fa42c9aea385","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:03 INFO - 1499278743201 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:19:03 INFO - 1499278743204 Marionette TRACE 372 <- [1,2,null,{}]
11:19:03 INFO - 1499278743211 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:03 INFO - 1499278743215 Marionette TRACE 372 <- [1,3,null,{}]
11:19:03 INFO - 1499278743218 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:03 INFO - 1499278743220 Marionette TRACE 372 <- [1,4,null,{}]
11:19:03 INFO - 1499278743223 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
11:19:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499278743234 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:19:03 INFO - 1499278743237 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:19:03 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:03 INFO - 1499278743242 Marionette TRACE 372 <- [1,6,null,{}]
11:19:03 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:03 INFO - 1499278743248 Marionette DEBUG Closed connection 372
11:19:03 INFO - 1499278743253 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
11:19:03 INFO - 1499278743310 Marionette DEBUG Closed connection 373
11:19:03 INFO - 1499278743312 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
11:19:03 INFO - 1499278743315 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:03 INFO - 1499278743318 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:03 INFO - 1499278743340 Marionette DEBUG Register listener.js for window 12
11:19:03 INFO - 1499278743355 Marionette TRACE 374 <- [1,1,null,{"sessionId":"33515baf-d264-42c5-a959-0dc5a0734c84","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:03 INFO - 1499278743358 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:19:03 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:03 INFO - 1499278743362 Marionette TRACE 374 <- [1,2,null,{}]
11:19:04 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:04 INFO - 1499278744188 Marionette DEBUG Closed connection 374
11:19:04 INFO - 1499278744190 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
11:19:04 INFO - 1499278744212 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
11:19:04 INFO - 1499278744214 Marionette DEBUG Closed connection 375
11:19:04 INFO - 1499278744260 Marionette DEBUG Closed connection 376
11:19:04 INFO - 1499278744262 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
11:19:04 INFO - 1499278744319 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:04 INFO - 1499278744322 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:04 INFO - 1499278744345 Marionette DEBUG Register listener.js for window 12
11:19:04 INFO - 1499278744360 Marionette TRACE 377 <- [1,1,null,{"sessionId":"b40f4809-d6f9-459f-8616-b2530239700b","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:04 INFO - 1499278744413 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
11:19:04 INFO - 1499278744419 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"
11:19:04 INFO - 1499278744427 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"
11:19:04 INFO - 1499278744428 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"
11:19:04 INFO - ++DOMWINDOW == 27 (0000007E853AC000) [pid = 2448] [serial = 48] [outer = 0000007E8539F800]
11:19:04 INFO - 1499278744540 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"
11:19:04 INFO - 1499278744546 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"
11:19:04 INFO - 1499278744566 Marionette TRACE 377 <- [1,2,null,{}]
11:19:04 INFO - 1499278744619 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:19:04 INFO - 1499278744627 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8d983cf-c3be-42ef-a533-32f92b795b41","ELEMENT":"d8d983cf-c3be-42ef-a533-32f92b795b41"}}]
11:19:04 INFO - 1499278744629 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"d8d983cf-c3be-42ef-a533-32f92b795b41"}]
11:19:05 INFO - 1499278745113 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:19:05 INFO - 1499278745115 Marionette TRACE 377 <- [1,4,null,{}]
11:19:05 INFO - 1499278745118 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
11:19:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499278745126 Marionette TRACE 377 <- [1,5,null,{"value":null}]
11:19:05 INFO - 1499278745129 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745133 Marionette TRACE 377 <- [1,6,null,{}]
11:19:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1999ms
11:19:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745140 Marionette DEBUG Closed connection 377
11:19:05 INFO - 1499278745142 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
11:19:05 INFO - 1499278745145 Marionette DEBUG Closed connection 378
11:19:05 INFO - 1499278745150 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
11:19:05 INFO - 1499278745153 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:05 INFO - 1499278745155 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:05 INFO - 1499278745177 Marionette DEBUG Register listener.js for window 12
11:19:05 INFO - 1499278745196 Marionette TRACE 379 <- [1,1,null,{"sessionId":"52b56b7a-d245-4157-bd66-97f6ab3baf5f","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:05 INFO - 1499278745199 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
11:19:05 INFO - 1499278745200 Marionette TRACE 379 <- [1,2,null,{}]
11:19:05 INFO - 1499278745202 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:05 INFO - 1499278745204 Marionette TRACE 379 <- [1,3,null,{}]
11:19:05 INFO - 1499278745206 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:05 INFO - 1499278745208 Marionette TRACE 379 <- [1,4,null,{}]
11:19:05 INFO - 1499278745210 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
11:19:05 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499278745223 Marionette TRACE 379 <- [1,5,null,{"value":null}]
11:19:05 INFO - 1499278745225 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745229 Marionette TRACE 379 <- [1,6,null,{}]
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745236 Marionette DEBUG Closed connection 379
11:19:05 INFO - 1499278745237 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
11:19:05 INFO - 1499278745239 Marionette DEBUG Closed connection 380
11:19:05 INFO - 1499278745240 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
11:19:05 INFO - 1499278745243 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:05 INFO - 1499278745245 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:05 INFO - 1499278745267 Marionette DEBUG Register listener.js for window 12
11:19:05 INFO - 1499278745281 Marionette TRACE 381 <- [1,1,null,{"sessionId":"b3079c5d-30e4-44a1-a5e4-1f7d2e8ee0e9","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:05 INFO - 1499278745283 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745287 Marionette TRACE 381 <- [1,2,null,{}]
11:19:05 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:05 INFO - 1499278745293 Marionette DEBUG Closed connection 381
11:19:05 INFO - 1499278745295 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
11:19:05 INFO - 1499278745296 Marionette DEBUG Closed connection 382
11:19:05 INFO - 1499278745298 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
11:19:05 INFO - 1499278745300 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:05 INFO - 1499278745303 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:05 INFO - 1499278745328 Marionette DEBUG Register listener.js for window 12
11:19:05 INFO - 1499278745341 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d1e12f1b-210e-4596-900a-9d155466b9bb","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:05 INFO - 1499278745344 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:19:05 INFO - 1499278745347 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"
11:19:05 INFO - 1499278745427 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"
11:19:05 INFO - 1499278745428 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"
11:19:05 INFO - ++DOMWINDOW == 28 (0000007E8DDCE800) [pid = 2448] [serial = 49] [outer = 0000007E8539F800]
11:19:05 INFO - 1499278745507 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"
11:19:05 INFO - 1499278745512 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"
11:19:05 INFO - 1499278745530 Marionette TRACE 383 <- [1,2,null,{}]
11:19:05 INFO - 1499278745545 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:19:05 INFO - 1499278745555 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9151100f-7864-4ad7-8f9c-35b340b6c711","ELEMENT":"9151100f-7864-4ad7-8f9c-35b340b6c711"}}]
11:19:05 INFO - 1499278745557 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"9151100f-7864-4ad7-8f9c-35b340b6c711"}]
11:19:06 INFO - 1499278746036 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:19:06 INFO - 1499278746038 Marionette TRACE 383 <- [1,4,null,{}]
11:19:06 INFO - 1499278746042 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
11:19:06 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499278746050 Marionette TRACE 383 <- [1,5,null,{"value":null}]
11:19:06 INFO - 1499278746051 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746056 Marionette TRACE 383 <- [1,6,null,{}]
11:19:06 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 922ms
11:19:06 INFO - TEST-START | test_click.py TestClick.test_input_file
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746062 Marionette DEBUG Closed connection 383
11:19:06 INFO - 1499278746063 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
11:19:06 INFO - 1499278746065 Marionette DEBUG Closed connection 384
11:19:06 INFO - 1499278746066 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
11:19:06 INFO - 1499278746068 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:06 INFO - 1499278746071 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746094 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746106 Marionette TRACE 385 <- [1,1,null,{"sessionId":"5e8f890b-ec06-4923-a77c-b0aa771d71d3","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746109 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
11:19:06 INFO - 1499278746111 Marionette TRACE 385 <- [1,2,null,{}]
11:19:06 INFO - 1499278746112 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:06 INFO - 1499278746114 Marionette TRACE 385 <- [1,3,null,{}]
11:19:06 INFO - 1499278746116 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:06 INFO - 1499278746118 Marionette TRACE 385 <- [1,4,null,{}]
11:19:06 INFO - 1499278746120 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
11:19:06 INFO - TEST-START: test_click.py TestClick.test_input_file1499278746129 Marionette TRACE 385 <- [1,5,null,{"value":null}]
11:19:06 INFO - 1499278746131 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746135 Marionette TRACE 385 <- [1,6,null,{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746142 Marionette DEBUG Closed connection 385
11:19:06 INFO - 1499278746144 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
11:19:06 INFO - 1499278746146 Marionette DEBUG Closed connection 386
11:19:06 INFO - 1499278746148 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
11:19:06 INFO - 1499278746150 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:06 INFO - 1499278746152 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746176 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746189 Marionette TRACE 387 <- [1,1,null,{"sessionId":"e590a536-a1e6-40f1-9e6e-92e083599d0b","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746192 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746196 Marionette TRACE 387 <- [1,2,null,{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746202 Marionette DEBUG Closed connection 387
11:19:06 INFO - 1499278746203 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
11:19:06 INFO - 1499278746205 Marionette DEBUG Closed connection 388
11:19:06 INFO - 1499278746206 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
11:19:06 INFO - 1499278746209 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:06 INFO - 1499278746211 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746234 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746248 Marionette TRACE 389 <- [1,1,null,{"sessionId":"d4681d45-e43a-4009-9ad1-4ba4e096a90d","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746251 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:19:06 INFO - 1499278746255 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"
11:19:06 INFO - 1499278746264 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"
11:19:06 INFO - 1499278746266 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"
11:19:06 INFO - ++DOMWINDOW == 29 (0000007E8DDD7000) [pid = 2448] [serial = 50] [outer = 0000007E8539F800]
11:19:06 INFO - 1499278746400 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:19:06 INFO - 1499278746405 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:19:06 INFO - 1499278746421 Marionette TRACE 389 <- [1,2,null,{}]
11:19:06 INFO - 1499278746425 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:19:06 INFO - 1499278746434 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a51688f2-fc83-4e39-8557-697d3a1fbd61","ELEMENT":"a51688f2-fc83-4e39-8557-697d3a1fbd61"}}]
11:19:06 INFO - 1499278746441 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"a51688f2-fc83-4e39-8557-697d3a1fbd61"}]
11:19:06 INFO - 1499278746603 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:19:06 INFO - 1499278746621 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
11:19:06 INFO - TEST-END: test_click.py TestClick.test_input_file1499278746630 Marionette TRACE 389 <- [1,5,null,{"value":null}]
11:19:06 INFO - 1499278746634 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746638 Marionette TRACE 389 <- [1,6,null,{}]
11:19:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 582ms
11:19:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746661 Marionette DEBUG Closed connection 389
11:19:06 INFO - 1499278746662 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
11:19:06 INFO - 1499278746666 Marionette DEBUG Closed connection 390
11:19:06 INFO - 1499278746667 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
11:19:06 INFO - 1499278746670 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:06 INFO - 1499278746673 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746700 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746713 Marionette TRACE 391 <- [1,1,null,{"sessionId":"5f2d2dae-0ca1-4d32-af8a-33025c8fbf13","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746721 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
11:19:06 INFO - 1499278746722 Marionette TRACE 391 <- [1,2,null,{}]
11:19:06 INFO - 1499278746724 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:06 INFO - 1499278746726 Marionette TRACE 391 <- [1,3,null,{}]
11:19:06 INFO - 1499278746728 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:06 INFO - 1499278746730 Marionette TRACE 391 <- [1,4,null,{}]
11:19:06 INFO - 1499278746732 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
11:19:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499278746740 Marionette TRACE 391 <- [1,5,null,{"value":null}]
11:19:06 INFO - 1499278746742 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746745 Marionette TRACE 391 <- [1,6,null,{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746770 Marionette DEBUG Closed connection 391
11:19:06 INFO - 1499278746772 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
11:19:06 INFO - 1499278746776 Marionette DEBUG Closed connection 392
11:19:06 INFO - 1499278746777 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
11:19:06 INFO - 1499278746779 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:06 INFO - 1499278746781 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746804 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746816 Marionette TRACE 393 <- [1,1,null,{"sessionId":"92f856c0-9927-4a23-adeb-9dac32b78e78","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746824 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746828 Marionette TRACE 393 <- [1,2,null,{}]
11:19:06 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:06 INFO - 1499278746850 Marionette DEBUG Closed connection 393
11:19:06 INFO - 1499278746851 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
11:19:06 INFO - 1499278746856 Marionette DEBUG Closed connection 394
11:19:06 INFO - 1499278746857 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
11:19:06 INFO - 1499278746860 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:06 INFO - 1499278746870 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:06 INFO - 1499278746893 Marionette DEBUG Register listener.js for window 12
11:19:06 INFO - 1499278746905 Marionette TRACE 395 <- [1,1,null,{"sessionId":"8db77a17-f773-4127-b7b4-7fd413e509cc","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:06 INFO - 1499278746912 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
11:19:06 INFO - 1499278746915 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:19:06 INFO - 1499278746922 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:19:06 INFO - 1499278746923 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:19:06 INFO - ++DOMWINDOW == 30 (0000007E8FB07000) [pid = 2448] [serial = 51] [outer = 0000007E8539F800]
11:19:06 INFO - 1499278746979 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"
11:19:06 INFO - 1499278746985 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"
11:19:07 INFO - 1499278746999 Marionette TRACE 395 <- [1,2,null,{}]
11:19:07 INFO - 1499278747005 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:19:07 INFO - 1499278747014 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"87d3f02b-28b8-462b-ab64-736a2002c494","ELEMENT":"87d3f02b-28b8-462b-ab64-736a2002c494"}}]
11:19:07 INFO - 1499278747016 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:19:07 INFO - 1499278747023 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4694812b-2859-424e-ba83-a73e265506bd","ELEMENT":"4694812b-2859-424e-ba83-a73e265506bd"}}]
11:19:07 INFO - 1499278747025 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"87d3f02b-28b8-462b-ab64-736a2002c494"}]
11:19:07 INFO - 1499278747966 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:19:07 INFO - 1499278747980 Marionette TRACE 395 <- [1,5,null,{}]
11:19:07 INFO - 1499278747986 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"4694812b-2859-424e-ba83-a73e265506bd"}]
11:19:08 INFO - 1499278748259 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:19:08 INFO - 1499278748264 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:19:08 INFO - 1499278748278 Marionette TRACE 395 <- [1,7,null,{"value":false}]
11:19:08 INFO - 1499278748287 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
11:19:08 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499278748300 Marionette TRACE 395 <- [1,8,null,{"value":null}]
11:19:08 INFO - 1499278748312 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748322 Marionette TRACE 395 <- [1,9,null,{}]
11:19:08 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1684ms
11:19:08 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748331 Marionette DEBUG Closed connection 395
11:19:08 INFO - 1499278748334 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
11:19:08 INFO - 1499278748337 Marionette DEBUG Closed connection 396
11:19:08 INFO - 1499278748341 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
11:19:08 INFO - 1499278748368 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:08 INFO - 1499278748379 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:08 INFO - 1499278748424 Marionette DEBUG Register listener.js for window 12
11:19:08 INFO - 1499278748460 Marionette TRACE 397 <- [1,1,null,{"sessionId":"6062bc7e-5da7-45d0-8ed9-d92d516bd000","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:08 INFO - 1499278748468 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
11:19:08 INFO - 1499278748473 Marionette TRACE 397 <- [1,2,null,{}]
11:19:08 INFO - 1499278748476 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:19:08 INFO - 1499278748479 Marionette TRACE 397 <- [1,3,null,{}]
11:19:08 INFO - 1499278748532 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
11:19:08 INFO - 1499278748535 Marionette TRACE 397 <- [1,4,null,{}]
11:19:08 INFO - 1499278748538 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
11:19:08 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499278748551 Marionette TRACE 397 <- [1,5,null,{"value":null}]
11:19:08 INFO - 1499278748555 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748561 Marionette TRACE 397 <- [1,6,null,{}]
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748587 Marionette DEBUG Closed connection 397
11:19:08 INFO - 1499278748591 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
11:19:08 INFO - 1499278748642 Marionette DEBUG Closed connection 398
11:19:08 INFO - 1499278748645 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
11:19:08 INFO - 1499278748651 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:19:08 INFO - 1499278748655 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:08 INFO - 1499278748700 Marionette DEBUG Register listener.js for window 12
11:19:08 INFO - 1499278748719 Marionette TRACE 399 <- [1,1,null,{"sessionId":"4677aa24-e3a3-4c54-97ad-5fd16a32a132","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:08 INFO - 1499278748731 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748740 Marionette TRACE 399 <- [1,2,null,{}]
11:19:08 INFO - [2448] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
11:19:08 INFO - 1499278748797 Marionette DEBUG Closed connection 399
11:19:08 INFO - 1499278748799 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
11:19:08 INFO - 1499278748821 Marionette DEBUG Closed connection 400
11:19:08 INFO - 1499278748823 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49831
11:19:08 INFO - 1499278748826 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:19:08 INFO - 1499278748830 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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}
11:19:08 INFO - 1499278748874 Marionette DEBUG Register listener.js for window 12
11:19:08 INFO - 1499278748892 Marionette TRACE 401 <- [1,1,null,{"sessionId":"bce0ec69-4a61-4736-9641-b632d314c907","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":2448,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-202.001\\AppData\\Local\\Temp\\tmpveucbh.mozrunner","moz:accessibilityChecks":false}}]
11:19:08 INFO - 1499278748924 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
11:19:08 INFO - 1499278748930 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"
11:19:08 INFO - 1499278748941 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"
11:19:08 INFO - 1499278748942 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"
11:19:08 INFO - ++DOMWINDOW == 31 (0000007E89B0E000) [pid = 2448] [serial = 52] [outer = 0000007E8539F800]
11:19:09 INFO - 1499278749012 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"
11:19:09 INFO - 1499278749019 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"
11:19:09 INFO - 1499278749039 Marionette TRACE 401 <- [1,2,null,{}]
11:19:09 INFO - 1499278749042 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:19:09 INFO - 1499278749052 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b5e62db5-7bcf-46c8-b59f-36c3e42b807a","ELEMENT":"b5e62db5-7bcf-46c8-b59f-36c3e42b807a"}}]
11:19:09 INFO - 1499278749055 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b5e62db5-7bcf-46c8-b59f-36c3e42b807a"}]
11:19:09 INFO - 1499278749256 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
11:19:09 INFO - 1499278749273 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"b5e62db5-7bcf-46c8-b59f-36c3e42b807a"}]
11:19:09 INFO - 1499278749574 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element