could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:05:38 INFO - 1500199538871 Marionette TRACE 353 -> [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}]
03:05:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500199538880 Marionette TRACE 353 <- [1,5,null,{"value":null}]
03:05:38 INFO - 1500199538883 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
03:05:38 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:38 INFO - 1500199538888 Marionette TRACE 353 <- [1,6,null,{}]
03:05:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 841ms
03:05:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:05:38 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:38 INFO - 1500199538894 Marionette DEBUG Closed connection 353
03:05:38 INFO - 1500199538896 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49838
03:05:38 INFO - 1500199538900 Marionette DEBUG Closed connection 354
03:05:38 INFO - 1500199538905 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49839
03:05:38 INFO - 1500199538907 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:38 INFO - 1500199538909 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:38 INFO - 1500199538930 Marionette DEBUG Register listener.js for window 15
03:05:38 INFO - 1500199538944 Marionette TRACE 355 <- [1,1,null,{"sessionId":"969df309-3ecc-47a4-a553-39efe706ab18","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:39 INFO - 1500199539578 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
03:05:39 INFO - 1500199539580 Marionette TRACE 355 <- [1,2,null,{}]
03:05:39 INFO - 1500199539584 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:39 INFO - 1500199539586 Marionette TRACE 355 <- [1,3,null,{}]
03:05:39 INFO - 1500199539589 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:39 INFO - 1500199539590 Marionette TRACE 355 <- [1,4,null,{}]
03:05:39 INFO - 1500199539628 Marionette TRACE 355 -> [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}]
03:05:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500199539640 Marionette TRACE 355 <- [1,5,null,{"value":null}]
03:05:39 INFO - 1500199539691 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
03:05:39 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:39 INFO - 1500199539696 Marionette TRACE 355 <- [1,6,null,{}]
03:05:39 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:39 INFO - 1500199539741 Marionette DEBUG Closed connection 355
03:05:39 INFO - 1500199539743 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49840
03:05:39 INFO - 1500199539763 Marionette DEBUG Closed connection 356
03:05:39 INFO - 1500199539764 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49841
03:05:39 INFO - 1500199539767 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:39 INFO - 1500199539769 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:39 INFO - 1500199539789 Marionette DEBUG Register listener.js for window 15
03:05:39 INFO - 1500199539801 Marionette TRACE 357 <- [1,1,null,{"sessionId":"d45b0562-28e3-4af6-b25e-5734fc1a542a","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:39 INFO - 1500199539807 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
03:05:39 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:39 INFO - 1500199539812 Marionette TRACE 357 <- [1,2,null,{}]
03:05:39 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:39 INFO - 1500199539818 Marionette DEBUG Closed connection 357
03:05:39 INFO - 1500199539819 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49842
03:05:39 INFO - 1500199539821 Marionette DEBUG Closed connection 358
03:05:39 INFO - 1500199539822 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49843
03:05:39 INFO - 1500199539824 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:39 INFO - 1500199539828 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:39 INFO - 1500199539848 Marionette DEBUG Register listener.js for window 15
03:05:39 INFO - 1500199539890 Marionette TRACE 359 <- [1,1,null,{"sessionId":"4f1d7936-0c1a-4c32-b65d-5a446059d7a0","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:39 INFO - 1500199539899 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49371/clicks.html"}]
03:05:39 INFO - 1500199539903 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49371/hidden.html"
03:05:39 INFO - 1500199539917 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49371/hidden.html"
03:05:39 INFO - 1500199539918 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49371/hidden.html"
03:05:39 INFO - ++DOMWINDOW == 19 (000000AE7970E800) [pid = 4944] [serial = 40] [outer = 000000AE06B85000]
03:05:39 INFO - 1500199539986 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49371/clicks.html"
03:05:39 INFO - 1500199539992 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49371/clicks.html"
03:05:40 INFO - 1500199540016 Marionette TRACE 359 <- [1,2,null,{}]
03:05:40 INFO - 1500199540019 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:05:40 INFO - 1500199540031 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68d45622-65de-418f-804e-8c00d094a483","ELEMENT":"68d45622-65de-418f-804e-8c00d094a483"}}]
03:05:40 INFO - 1500199540033 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"68d45622-65de-418f-804e-8c00d094a483"}]
03:05:40 INFO - 1500199540297 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49371/clicks.html"
03:05:40 INFO - [Parent 4944] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
03:05:40 INFO - 1500199540320 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49371/clicks.html"
03:05:40 INFO - 1500199540322 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49371/clicks.html"
03:05:40 INFO - ++DOMWINDOW == 20 (000000AE0D663000) [pid = 4944] [serial = 41] [outer = 000000AE06B85000]
03:05:40 INFO - 1500199540383 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49371/test.html"
03:05:40 INFO - 1500199540389 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49371/test.html"
03:05:40 INFO - 1500199540405 Marionette TRACE 359 <- [1,4,null,{}]
03:05:40 INFO - 1500199540412 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:05:40 INFO - 1500199540420 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f50eb948-9d37-419c-86e1-4e267b673a81","ELEMENT":"f50eb948-9d37-419c-86e1-4e267b673a81"}}]
03:05:40 INFO - 1500199540422 Marionette TRACE 359 -> [0,6,"getTitle",{}]
03:05:40 INFO - 1500199540423 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
03:05:40 INFO - 1500199540428 Marionette TRACE 359 -> [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}]
03:05:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500199540436 Marionette TRACE 359 <- [1,7,null,{"value":null}]
03:05:40 INFO - 1500199540438 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540443 Marionette TRACE 359 <- [1,8,null,{}]
03:05:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1554ms
03:05:40 INFO - TEST-START | test_click.py TestClick.test_container_element
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540449 Marionette DEBUG Closed connection 359
03:05:40 INFO - 1500199540451 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49846
03:05:40 INFO - 1500199540452 Marionette DEBUG Closed connection 360
03:05:40 INFO - 1500199540454 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49847
03:05:40 INFO - 1500199540456 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:40 INFO - 1500199540458 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:40 INFO - 1500199540479 Marionette DEBUG Register listener.js for window 15
03:05:40 INFO - 1500199540490 Marionette TRACE 361 <- [1,1,null,{"sessionId":"c997294c-44db-4ed0-b968-2f791f731460","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:40 INFO - 1500199540519 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
03:05:40 INFO - 1500199540521 Marionette TRACE 361 <- [1,2,null,{}]
03:05:40 INFO - 1500199540523 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:40 INFO - 1500199540525 Marionette TRACE 361 <- [1,3,null,{}]
03:05:40 INFO - 1500199540527 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:40 INFO - 1500199540529 Marionette TRACE 361 <- [1,4,null,{}]
03:05:40 INFO - 1500199540531 Marionette TRACE 361 -> [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}]
03:05:40 INFO - TEST-START: test_click.py TestClick.test_container_element1500199540542 Marionette TRACE 361 <- [1,5,null,{"value":null}]
03:05:40 INFO - 1500199540543 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540548 Marionette TRACE 361 <- [1,6,null,{}]
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540571 Marionette DEBUG Closed connection 361
03:05:40 INFO - 1500199540573 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49848
03:05:40 INFO - 1500199540576 Marionette DEBUG Closed connection 362
03:05:40 INFO - 1500199540578 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49849
03:05:40 INFO - 1500199540580 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:40 INFO - 1500199540582 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:40 INFO - 1500199540603 Marionette DEBUG Register listener.js for window 15
03:05:40 INFO - 1500199540615 Marionette TRACE 363 <- [1,1,null,{"sessionId":"61c5ee14-a097-47bf-ac31-97776cd6fb13","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:40 INFO - 1500199540625 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540629 Marionette TRACE 363 <- [1,2,null,{}]
03:05:40 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:40 INFO - 1500199540650 Marionette DEBUG Closed connection 363
03:05:40 INFO - 1500199540651 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49850
03:05:40 INFO - 1500199540655 Marionette DEBUG Closed connection 364
03:05:40 INFO - 1500199540657 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49851
03:05:40 INFO - 1500199540659 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:40 INFO - 1500199540663 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:40 INFO - 1500199540688 Marionette DEBUG Register listener.js for window 15
03:05:40 INFO - 1500199540703 Marionette TRACE 365 <- [1,1,null,{"sessionId":"ee0177fb-75ec-442a-a0be-566ee3d5bf01","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:40 INFO - 1500199540713 Marionette TRACE 365 -> [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"}]
03:05:40 INFO - 1500199540717 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49371/test.html"
03:05:40 INFO - 1500199540726 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49371/test.html"
03:05:40 INFO - 1500199540727 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49371/test.html"
03:05:40 INFO - ++DOMWINDOW == 21 (000000AE0FC24800) [pid = 4944] [serial = 42] [outer = 000000AE06B85000]
03:05:40 INFO - 1500199540788 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"
03:05:40 INFO - 1500199540795 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"
03:05:40 INFO - 1500199540812 Marionette TRACE 365 <- [1,2,null,{}]
03:05:40 INFO - 1500199540819 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:05:40 INFO - 1500199540829 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59c596e6-425c-4b0b-a428-f5bdb9feeede","ELEMENT":"59c596e6-425c-4b0b-a428-f5bdb9feeede"}}]
03:05:40 INFO - 1500199540834 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"59c596e6-425c-4b0b-a428-f5bdb9feeede"}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:05:41 INFO - 1500199541269 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:05:41 INFO - 1500199541271 Marionette TRACE 365 <- [1,4,null,{}]
03:05:41 INFO - 1500199541273 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"59c596e6-425c-4b0b-a428-f5bdb9feeede","name":"selected"}]
03:05:41 INFO - 1500199541301 Marionette TRACE 365 <- [1,5,null,{"value":true}]
03:05:41 INFO - 1500199541303 Marionette TRACE 365 -> [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}]
03:05:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500199541313 Marionette TRACE 365 <- [1,6,null,{"value":null}]
03:05:41 INFO - 1500199541315 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541320 Marionette TRACE 365 <- [1,7,null,{}]
03:05:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 877ms
03:05:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541350 Marionette DEBUG Closed connection 365
03:05:41 INFO - 1500199541352 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49852
03:05:41 INFO - 1500199541354 Marionette DEBUG Closed connection 366
03:05:41 INFO - 1500199541355 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49853
03:05:41 INFO - 1500199541357 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:41 INFO - 1500199541359 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:41 INFO - 1500199541380 Marionette DEBUG Register listener.js for window 15
03:05:41 INFO - 1500199541392 Marionette TRACE 367 <- [1,1,null,{"sessionId":"a7c7e979-400f-4777-9f0c-f79881f842f9","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:41 INFO - 1500199541404 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
03:05:41 INFO - 1500199541405 Marionette TRACE 367 <- [1,2,null,{}]
03:05:41 INFO - 1500199541407 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:41 INFO - 1500199541409 Marionette TRACE 367 <- [1,3,null,{}]
03:05:41 INFO - 1500199541411 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:41 INFO - 1500199541412 Marionette TRACE 367 <- [1,4,null,{}]
03:05:41 INFO - 1500199541415 Marionette TRACE 367 -> [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}]
03:05:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500199541423 Marionette TRACE 367 <- [1,5,null,{"value":null}]
03:05:41 INFO - 1500199541425 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541429 Marionette TRACE 367 <- [1,6,null,{}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541435 Marionette DEBUG Closed connection 367
03:05:41 INFO - 1500199541436 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49854
03:05:41 INFO - 1500199541438 Marionette DEBUG Closed connection 368
03:05:41 INFO - 1500199541439 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49855
03:05:41 INFO - 1500199541441 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:41 INFO - 1500199541443 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:41 INFO - 1500199541464 Marionette DEBUG Register listener.js for window 15
03:05:41 INFO - 1500199541475 Marionette TRACE 369 <- [1,1,null,{"sessionId":"3d4f5111-ff98-499c-97f3-b76e23154638","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:41 INFO - 1500199541481 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541485 Marionette TRACE 369 <- [1,2,null,{}]
03:05:41 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:41 INFO - 1500199541491 Marionette DEBUG Closed connection 369
03:05:41 INFO - 1500199541492 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49856
03:05:41 INFO - 1500199541494 Marionette DEBUG Closed connection 370
03:05:41 INFO - 1500199541495 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49857
03:05:41 INFO - 1500199541498 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:41 INFO - 1500199541500 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:41 INFO - 1500199541520 Marionette DEBUG Register listener.js for window 15
03:05:41 INFO - 1500199541531 Marionette TRACE 371 <- [1,1,null,{"sessionId":"af4dc121-4831-428f-953e-ecbad7ce22e8","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:41 INFO - 1500199541537 Marionette TRACE 371 -> [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"}]
03:05:41 INFO - 1500199541541 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"
03:05:41 INFO - 1500199541547 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"
03:05:41 INFO - 1500199541548 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"
03:05:41 INFO - ++DOMWINDOW == 22 (000000AE78A40800) [pid = 4944] [serial = 43] [outer = 000000AE06B85000]
03:05:41 INFO - 1500199541597 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"
03:05:41 INFO - 1500199541602 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"
03:05:42 INFO - 1500199542245 Marionette TRACE 371 <- [1,2,null,{}]
03:05:42 INFO - 1500199542308 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:05:42 INFO - 1500199542315 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab58427e-90d2-46aa-abff-334fc6b31363","ELEMENT":"ab58427e-90d2-46aa-abff-334fc6b31363"}}]
03:05:42 INFO - 1500199542317 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"ab58427e-90d2-46aa-abff-334fc6b31363"}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:05:42 INFO - 1500199542780 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:05:42 INFO - 1500199542782 Marionette TRACE 371 <- [1,4,null,{}]
03:05:42 INFO - 1500199542784 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"ab58427e-90d2-46aa-abff-334fc6b31363","name":"selected"}]
03:05:42 INFO - 1500199542813 Marionette TRACE 371 <- [1,5,null,{"value":true}]
03:05:42 INFO - 1500199542822 Marionette TRACE 371 -> [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}]
03:05:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500199542829 Marionette TRACE 371 <- [1,6,null,{"value":null}]
03:05:42 INFO - 1500199542832 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542836 Marionette TRACE 371 <- [1,7,null,{}]
03:05:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1516ms
03:05:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542842 Marionette DEBUG Closed connection 371
03:05:42 INFO - 1500199542844 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49858
03:05:42 INFO - 1500199542845 Marionette DEBUG Closed connection 372
03:05:42 INFO - 1500199542847 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49859
03:05:42 INFO - 1500199542849 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:42 INFO - 1500199542851 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:42 INFO - 1500199542873 Marionette DEBUG Register listener.js for window 15
03:05:42 INFO - 1500199542884 Marionette TRACE 373 <- [1,1,null,{"sessionId":"2e558822-73c1-4fd5-8923-c60d759516f1","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:42 INFO - 1500199542891 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
03:05:42 INFO - 1500199542892 Marionette TRACE 373 <- [1,2,null,{}]
03:05:42 INFO - 1500199542894 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:42 INFO - 1500199542895 Marionette TRACE 373 <- [1,3,null,{}]
03:05:42 INFO - 1500199542897 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:42 INFO - 1500199542899 Marionette TRACE 373 <- [1,4,null,{}]
03:05:42 INFO - 1500199542902 Marionette TRACE 373 -> [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}]
03:05:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500199542909 Marionette TRACE 373 <- [1,5,null,{"value":null}]
03:05:42 INFO - 1500199542911 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542915 Marionette TRACE 373 <- [1,6,null,{}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542921 Marionette DEBUG Closed connection 373
03:05:42 INFO - 1500199542923 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49860
03:05:42 INFO - 1500199542925 Marionette DEBUG Closed connection 374
03:05:42 INFO - 1500199542926 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49861
03:05:42 INFO - 1500199542928 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:42 INFO - 1500199542931 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:42 INFO - 1500199542952 Marionette DEBUG Register listener.js for window 15
03:05:42 INFO - 1500199542964 Marionette TRACE 375 <- [1,1,null,{"sessionId":"0d46edeb-4875-4fd9-9ec5-19f981646a8b","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:42 INFO - 1500199542970 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542974 Marionette TRACE 375 <- [1,2,null,{}]
03:05:42 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:42 INFO - 1500199542980 Marionette DEBUG Closed connection 375
03:05:42 INFO - 1500199542981 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49862
03:05:43 INFO - 1500199543036 Marionette DEBUG Closed connection 376
03:05:43 INFO - 1500199543039 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49863
03:05:43 INFO - 1500199543105 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:43 INFO - 1500199543116 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:43 INFO - 1500199543157 Marionette DEBUG Register listener.js for window 15
03:05:43 INFO - 1500199543180 Marionette TRACE 377 <- [1,1,null,{"sessionId":"c150abb7-5674-49fb-9ff9-05498aca01ed","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:43 INFO - 1500199543197 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"}]
03:05:43 INFO - 1500199543203 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"
03:05:43 INFO - 1500199543226 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"
03:05:43 INFO - 1500199543227 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"
03:05:43 INFO - ++DOMWINDOW == 23 (000000AE0608B000) [pid = 4944] [serial = 44] [outer = 000000AE06B85000]
03:05:43 INFO - 1500199543293 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"
03:05:43 INFO - 1500199543304 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"
03:05:43 INFO - 1500199543327 Marionette TRACE 377 <- [1,2,null,{}]
03:05:43 INFO - 1500199543338 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:05:43 INFO - 1500199543351 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b59db14-49a7-49aa-ab8c-86961bcb8835","ELEMENT":"0b59db14-49a7-49aa-ab8c-86961bcb8835"}}]
03:05:43 INFO - 1500199543355 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"0b59db14-49a7-49aa-ab8c-86961bcb8835"}]
03:05:43 INFO - 1500199543945 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:05:43 INFO - 1500199543948 Marionette TRACE 377 <- [1,4,null,{}]
03:05:43 INFO - 1500199543954 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}]
03:05:43 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500199543967 Marionette TRACE 377 <- [1,5,null,{"value":null}]
03:05:43 INFO - 1500199543972 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
03:05:43 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:43 INFO - 1500199543979 Marionette TRACE 377 <- [1,6,null,{}]
03:05:43 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1144ms
03:05:43 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:05:44 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:44 INFO - 1500199544037 Marionette DEBUG Closed connection 377
03:05:44 INFO - 1500199544040 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49864
03:05:44 INFO - 1500199544060 Marionette DEBUG Closed connection 378
03:05:44 INFO - 1500199544064 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49865
03:05:44 INFO - 1500199544067 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:44 INFO - 1500199544072 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:44 INFO - 1500199544112 Marionette DEBUG Register listener.js for window 15
03:05:44 INFO - 1500199544132 Marionette TRACE 379 <- [1,1,null,{"sessionId":"e7df1fff-bf39-419b-aa4d-89f55cf2e748","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:44 INFO - 1500199544145 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
03:05:44 INFO - 1500199544148 Marionette TRACE 379 <- [1,2,null,{}]
03:05:44 INFO - 1500199544152 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:44 INFO - 1500199544154 Marionette TRACE 379 <- [1,3,null,{}]
03:05:44 INFO - 1500199544157 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:44 INFO - 1500199544159 Marionette TRACE 379 <- [1,4,null,{}]
03:05:44 INFO - 1500199544167 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}]
03:05:44 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500199544193 Marionette TRACE 379 <- [1,5,null,{"value":null}]
03:05:44 INFO - 1500199544198 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
03:05:44 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:44 INFO - 1500199544203 Marionette TRACE 379 <- [1,6,null,{}]
03:05:44 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:44 INFO - 1500199544211 Marionette DEBUG Closed connection 379
03:05:44 INFO - 1500199544214 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49866
03:05:44 INFO - 1500199544239 Marionette DEBUG Closed connection 380
03:05:44 INFO - 1500199544242 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49867
03:05:44 INFO - 1500199544263 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:44 INFO - 1500199544267 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:44 INFO - 1500199544307 Marionette DEBUG Register listener.js for window 15
03:05:44 INFO - 1500199544336 Marionette TRACE 381 <- [1,1,null,{"sessionId":"66bd6e3c-6eac-4519-af29-a8d0dad2f767","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:44 INFO - 1500199544348 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
03:05:44 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:44 INFO - 1500199544354 Marionette TRACE 381 <- [1,2,null,{}]
03:05:44 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:44 INFO - 1500199544376 Marionette DEBUG Closed connection 381
03:05:44 INFO - 1500199544379 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49868
03:05:44 INFO - 1500199544430 Marionette DEBUG Closed connection 382
03:05:44 INFO - 1500199544432 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49869
03:05:44 INFO - 1500199544438 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:44 INFO - 1500199544442 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:44 INFO - 1500199544483 Marionette DEBUG Register listener.js for window 15
03:05:44 INFO - 1500199544498 Marionette TRACE 383 <- [1,1,null,{"sessionId":"55b460d9-af7b-4d7f-90f5-c4fab3a12d85","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:44 INFO - 1500199544510 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"}]
03:05:44 INFO - 1500199544516 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"
03:05:44 INFO - 1500199544525 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"
03:05:44 INFO - 1500199544526 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"
03:05:44 INFO - ++DOMWINDOW == 24 (000000AE7971D800) [pid = 4944] [serial = 45] [outer = 000000AE06B85000]
03:05:44 INFO - 1500199544589 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"
03:05:44 INFO - 1500199544604 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"
03:05:44 INFO - 1500199544633 Marionette TRACE 383 <- [1,2,null,{}]
03:05:44 INFO - 1500199544637 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:05:44 INFO - 1500199544648 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f3199a8-31c0-49d8-a870-95d96d370fc7","ELEMENT":"4f3199a8-31c0-49d8-a870-95d96d370fc7"}}]
03:05:44 INFO - 1500199544651 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"4f3199a8-31c0-49d8-a870-95d96d370fc7"}]
03:05:45 INFO - 1500199545239 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:05:45 INFO - 1500199545242 Marionette TRACE 383 <- [1,4,null,{}]
03:05:45 INFO - 1500199545401 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}]
03:05:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500199545415 Marionette TRACE 383 <- [1,5,null,{"value":null}]
03:05:45 INFO - 1500199545421 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545432 Marionette TRACE 383 <- [1,6,null,{}]
03:05:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1453ms
03:05:45 INFO - TEST-START | test_click.py TestClick.test_input_file
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545441 Marionette DEBUG Closed connection 383
03:05:45 INFO - 1500199545444 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49870
03:05:45 INFO - 1500199545447 Marionette DEBUG Closed connection 384
03:05:45 INFO - 1500199545450 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49871
03:05:45 INFO - 1500199545454 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:45 INFO - 1500199545465 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:45 INFO - 1500199545491 Marionette DEBUG Register listener.js for window 15
03:05:45 INFO - 1500199545513 Marionette TRACE 385 <- [1,1,null,{"sessionId":"9669bb50-68d2-400c-95ce-a9500d7b4260","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:45 INFO - 1500199545563 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
03:05:45 INFO - 1500199545566 Marionette TRACE 385 <- [1,2,null,{}]
03:05:45 INFO - 1500199545593 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:45 INFO - 1500199545596 Marionette TRACE 385 <- [1,3,null,{}]
03:05:45 INFO - 1500199545610 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:45 INFO - 1500199545614 Marionette TRACE 385 <- [1,4,null,{}]
03:05:45 INFO - 1500199545619 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}]
03:05:45 INFO - TEST-START: test_click.py TestClick.test_input_file1500199545632 Marionette TRACE 385 <- [1,5,null,{"value":null}]
03:05:45 INFO - 1500199545637 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545643 Marionette TRACE 385 <- [1,6,null,{}]
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545707 Marionette DEBUG Closed connection 385
03:05:45 INFO - 1500199545710 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49872
03:05:45 INFO - 1500199545714 Marionette DEBUG Closed connection 386
03:05:45 INFO - 1500199545716 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49873
03:05:45 INFO - 1500199545740 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:45 INFO - 1500199545743 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:45 INFO - 1500199545766 Marionette DEBUG Register listener.js for window 15
03:05:45 INFO - 1500199545781 Marionette TRACE 387 <- [1,1,null,{"sessionId":"82f47d3c-352d-4112-b704-5b1a0af3d365","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:45 INFO - 1500199545792 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545799 Marionette TRACE 387 <- [1,2,null,{}]
03:05:45 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:45 INFO - 1500199545824 Marionette DEBUG Closed connection 387
03:05:45 INFO - 1500199545826 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49874
03:05:45 INFO - 1500199545831 Marionette DEBUG Closed connection 388
03:05:45 INFO - 1500199545833 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49875
03:05:45 INFO - 1500199545845 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:45 INFO - 1500199545851 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:45 INFO - 1500199545873 Marionette DEBUG Register listener.js for window 15
03:05:45 INFO - 1500199545889 Marionette TRACE 389 <- [1,1,null,{"sessionId":"9331abd1-6dc2-45c5-b43a-4667b0e5e08c","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:45 INFO - --DOMWINDOW == 23 (000000AE79CF8000) [pid = 4944] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49371/test_accessibility.html]
03:05:45 INFO - --DOMWINDOW == 22 (000000AE0D66F000) [pid = 4944] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
03:05:45 INFO - --DOMWINDOW == 21 (000000AE0A4C1800) [pid = 4944] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
03:05:45 INFO - --DOMWINDOW == 20 (000000AE062A5800) [pid = 4944] [serial = 32] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
03:05:45 INFO - 1500199545905 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:05:45 INFO - 1500199545910 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"
03:05:45 INFO - 1500199545919 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"
03:05:45 INFO - 1500199545921 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"
03:05:45 INFO - ++DOMWINDOW == 21 (000000AE049D6000) [pid = 4944] [serial = 46] [outer = 000000AE06B85000]
03:05:45 INFO - 1500199545982 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:05:46 INFO - 1500199545990 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:05:46 INFO - 1500199546008 Marionette TRACE 389 <- [1,2,null,{}]
03:05:46 INFO - 1500199546019 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:05:46 INFO - 1500199546029 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7625099d-e650-4862-8bc8-08cde30acf56","ELEMENT":"7625099d-e650-4862-8bc8-08cde30acf56"}}]
03:05:46 INFO - 1500199546031 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"7625099d-e650-4862-8bc8-08cde30acf56"}]
03:05:46 INFO - 1500199546187 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:05:46 INFO - 1500199546206 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}]
03:05:46 INFO - TEST-END: test_click.py TestClick.test_input_file1500199546215 Marionette TRACE 389 <- [1,5,null,{"value":null}]
03:05:46 INFO - 1500199546217 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
03:05:46 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:46 INFO - 1500199546222 Marionette TRACE 389 <- [1,6,null,{}]
03:05:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 789ms
03:05:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:05:46 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:46 INFO - 1500199546230 Marionette DEBUG Closed connection 389
03:05:46 INFO - 1500199546232 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49876
03:05:46 INFO - 1500199546253 Marionette DEBUG Closed connection 390
03:05:46 INFO - 1500199546255 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49877
03:05:46 INFO - 1500199546362 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:46 INFO - 1500199546365 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:46 INFO - 1500199546387 Marionette DEBUG Register listener.js for window 15
03:05:46 INFO - 1500199546401 Marionette TRACE 391 <- [1,1,null,{"sessionId":"d9052795-363f-454c-92f7-98c47dddfc85","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:46 INFO - 1500199546412 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
03:05:46 INFO - 1500199546414 Marionette TRACE 391 <- [1,2,null,{}]
03:05:46 INFO - 1500199546417 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:46 INFO - 1500199546419 Marionette TRACE 391 <- [1,3,null,{}]
03:05:46 INFO - 1500199546422 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:46 INFO - 1500199546423 Marionette TRACE 391 <- [1,4,null,{}]
03:05:46 INFO - 1500199546427 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}]
03:05:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500199546437 Marionette TRACE 391 <- [1,5,null,{"value":null}]
03:05:46 INFO - 1500199546440 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
03:05:46 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:46 INFO - 1500199546444 Marionette TRACE 391 <- [1,6,null,{}]
03:05:46 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:46 INFO - 1500199546450 Marionette DEBUG Closed connection 391
03:05:46 INFO - 1500199546452 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49878
03:05:46 INFO - 1500199546454 Marionette DEBUG Closed connection 392
03:05:46 INFO - 1500199546456 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49879
03:05:46 INFO - 1500199546479 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:46 INFO - 1500199546484 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:46 INFO - 1500199546507 Marionette DEBUG Register listener.js for window 15
03:05:46 INFO - 1500199546523 Marionette TRACE 393 <- [1,1,null,{"sessionId":"19783ce7-cbff-4d69-9412-dfa8b55fae71","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:46 INFO - 1500199546532 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
03:05:46 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:46 INFO - 1500199546538 Marionette TRACE 393 <- [1,2,null,{}]
03:05:47 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:47 INFO - 1500199547208 Marionette DEBUG Closed connection 393
03:05:47 INFO - 1500199547213 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49880
03:05:47 INFO - 1500199547240 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49881
03:05:47 INFO - 1500199547241 Marionette DEBUG Closed connection 394
03:05:47 INFO - 1500199547255 Marionette DEBUG Closed connection 395
03:05:47 INFO - 1500199547261 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49882
03:05:47 INFO - 1500199547272 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:47 INFO - 1500199547274 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:47 INFO - 1500199547299 Marionette DEBUG Register listener.js for window 15
03:05:47 INFO - 1500199547358 Marionette TRACE 396 <- [1,1,null,{"sessionId":"941c0359-5c98-423a-ace7-b43dac66c4dc","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:47 INFO - 1500199547371 Marionette TRACE 396 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
03:05:47 INFO - 1500199547381 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:05:47 INFO - 1500199547389 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:05:47 INFO - 1500199547390 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:05:47 INFO - ++DOMWINDOW == 22 (000000AE0D522800) [pid = 4944] [serial = 47] [outer = 000000AE06B85000]
03:05:47 INFO - 1500199547506 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"
03:05:47 INFO - 1500199547513 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"
03:05:47 INFO - 1500199547531 Marionette TRACE 396 <- [1,2,null,{}]
03:05:47 INFO - 1500199547546 Marionette TRACE 396 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:05:47 INFO - 1500199547607 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4cb8931a-3edf-40e4-8492-37afcd6693cf","ELEMENT":"4cb8931a-3edf-40e4-8492-37afcd6693cf"}}]
03:05:47 INFO - 1500199547660 Marionette TRACE 396 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:05:47 INFO - 1500199547668 Marionette TRACE 396 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d264340-41b0-4ef2-b85c-3806ae58cf62","ELEMENT":"9d264340-41b0-4ef2-b85c-3806ae58cf62"}}]
03:05:47 INFO - 1500199547685 Marionette TRACE 396 -> [0,5,"clickElement",{"id":"4cb8931a-3edf-40e4-8492-37afcd6693cf"}]
03:05:48 INFO - 1500199548160 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:05:48 INFO - 1500199548164 Marionette TRACE 396 <- [1,5,null,{}]
03:05:48 INFO - 1500199548166 Marionette TRACE 396 -> [0,6,"clickElement",{"id":"9d264340-41b0-4ef2-b85c-3806ae58cf62"}]
03:05:48 INFO - 1500199548298 Marionette TRACE 396 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:05:48 INFO - 1500199548326 Marionette TRACE 396 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:05:48 INFO - 1500199548334 Marionette TRACE 396 <- [1,7,null,{"value":false}]
03:05:48 INFO - 1500199548337 Marionette TRACE 396 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
03:05:48 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500199548346 Marionette TRACE 396 <- [1,8,null,{"value":null}]
03:05:48 INFO - 1500199548348 Marionette TRACE 396 -> [0,9,"deleteSession",{}]
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548352 Marionette TRACE 396 <- [1,9,null,{}]
03:05:48 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2129ms
03:05:48 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548358 Marionette DEBUG Closed connection 396
03:05:48 INFO - 1500199548360 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49883
03:05:48 INFO - 1500199548361 Marionette DEBUG Closed connection 397
03:05:48 INFO - 1500199548363 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49884
03:05:48 INFO - 1500199548365 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:48 INFO - 1500199548367 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:48 INFO - 1500199548389 Marionette DEBUG Register listener.js for window 15
03:05:48 INFO - 1500199548414 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f15d615c-140a-4f11-9578-e1df09ef148e","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:48 INFO - 1500199548447 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
03:05:48 INFO - 1500199548448 Marionette TRACE 398 <- [1,2,null,{}]
03:05:48 INFO - 1500199548450 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:05:48 INFO - 1500199548452 Marionette TRACE 398 <- [1,3,null,{}]
03:05:48 INFO - 1500199548454 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
03:05:48 INFO - 1500199548456 Marionette TRACE 398 <- [1,4,null,{}]
03:05:48 INFO - 1500199548458 Marionette TRACE 398 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
03:05:48 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500199548466 Marionette TRACE 398 <- [1,5,null,{"value":null}]
03:05:48 INFO - 1500199548468 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548472 Marionette TRACE 398 <- [1,6,null,{}]
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548478 Marionette DEBUG Closed connection 398
03:05:48 INFO - 1500199548481 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49885
03:05:48 INFO - 1500199548482 Marionette DEBUG Closed connection 399
03:05:48 INFO - 1500199548484 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49886
03:05:48 INFO - 1500199548486 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:05:48 INFO - 1500199548490 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:48 INFO - 1500199548513 Marionette DEBUG Register listener.js for window 15
03:05:48 INFO - 1500199548525 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ae028a8c-e26c-472d-aa86-39479958e678","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:48 INFO - 1500199548532 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548536 Marionette TRACE 400 <- [1,2,null,{}]
03:05:48 INFO - [Parent 4944] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:05:48 INFO - 1500199548542 Marionette DEBUG Closed connection 400
03:05:48 INFO - 1500199548543 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49887
03:05:48 INFO - 1500199548545 Marionette DEBUG Closed connection 401
03:05:48 INFO - 1500199548546 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49888
03:05:48 INFO - 1500199548549 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:05:48 INFO - 1500199548551 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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}
03:05:48 INFO - 1500199548573 Marionette DEBUG Register listener.js for window 15
03:05:48 INFO - 1500199548630 Marionette TRACE 402 <- [1,1,null,{"sessionId":"314cec73-1f70-4133-8b9e-db37320c4391","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":4944,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpyz4svv.mozrunner","moz:accessibilityChecks":false}}]
03:05:48 INFO - 1500199548637 Marionette TRACE 402 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
03:05:48 INFO - 1500199548641 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"
03:05:48 INFO - 1500199548649 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"
03:05:48 INFO - 1500199548649 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"
03:05:48 INFO - ++DOMWINDOW == 23 (000000AE78B2C800) [pid = 4944] [serial = 48] [outer = 000000AE06B85000]
03:05:48 INFO - 1500199548707 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"
03:05:48 INFO - 1500199548712 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"
03:05:48 INFO - 1500199548728 Marionette TRACE 402 <- [1,2,null,{}]
03:05:48 INFO - 1500199548735 Marionette TRACE 402 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:05:48 INFO - 1500199548744 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"595a71e8-e69e-48d3-be28-0ff63a64a144","ELEMENT":"595a71e8-e69e-48d3-be28-0ff63a64a144"}}]
03:05:48 INFO - 1500199548746 Marionette TRACE 402 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"595a71e8-e69e-48d3-be28-0ff63a64a144"}]
03:05:48 INFO - 1500199548868 Marionette TRACE 402 <- [1,4,null,{"value":"none"}]
03:05:48 INFO - 1500199548871 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"595a71e8-e69e-48d3-be28-0ff63a64a144"}]
03:05:49 INFO - 1500199549039 Marionette TRACE 402 <- [1,5,{"error":"element click intercepted","message":"Element