could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:11:34 INFO - 1500898294504 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:11:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500898294507 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:11:34 INFO - 1500898294508 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:11:34 INFO - 1500898294509 Marionette TRACE 352 <- [1,6,null,{}]
05:11:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
05:11:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:11:34 INFO - 1500898294511 Marionette DEBUG Closed connection 352
05:11:34 INFO - 1500898294512 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49865
05:11:34 INFO - 1500898294514 Marionette DEBUG Closed connection 353
05:11:34 INFO - 1500898294514 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49866
05:11:34 INFO - 1500898294515 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:34 INFO - 1500898294516 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294518 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294522 Marionette TRACE 354 <- [1,1,null,{"sessionId":"0c85ec8b-a21a-4fdd-b2e0-4c9fac3593e4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294523 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:11:34 INFO - 1500898294523 Marionette TRACE 354 <- [1,2,null,{}]
05:11:34 INFO - 1500898294525 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:34 INFO - 1500898294525 Marionette TRACE 354 <- [1,3,null,{}]
05:11:34 INFO - 1500898294526 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:34 INFO - 1500898294526 Marionette TRACE 354 <- [1,4,null,{}]
05:11:34 INFO - 1500898294528 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:11:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500898294531 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:11:34 INFO - 1500898294532 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:11:34 INFO - 1500898294533 Marionette TRACE 354 <- [1,6,null,{}]
05:11:34 INFO - 1500898294535 Marionette DEBUG Closed connection 354
05:11:34 INFO - 1500898294535 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49867
05:11:34 INFO - 1500898294536 Marionette DEBUG Closed connection 355
05:11:34 INFO - 1500898294537 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49868
05:11:34 INFO - 1500898294538 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:34 INFO - 1500898294538 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294541 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294544 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3a593513-14fe-4cf9-a651-42e12f4729e5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294545 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:11:34 INFO - 1500898294547 Marionette TRACE 356 <- [1,2,null,{}]
05:11:34 INFO - 1500898294548 Marionette DEBUG Closed connection 356
05:11:34 INFO - 1500898294549 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49869
05:11:34 INFO - 1500898294549 Marionette DEBUG Closed connection 357
05:11:34 INFO - 1500898294550 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49870
05:11:34 INFO - 1500898294551 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:34 INFO - 1500898294552 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294554 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294557 Marionette TRACE 358 <- [1,1,null,{"sessionId":"f859f595-99de-42ad-9b6b-c7d3b4de2050","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294558 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49422/clicks.html"}]
05:11:34 INFO - 1500898294560 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49422/hidden.html"
05:11:34 INFO - 1500898294567 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49422/hidden.html"
05:11:34 INFO - 1500898294567 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49422/hidden.html"
05:11:34 INFO - 1500898294581 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49422/clicks.html"
05:11:34 INFO - 1500898294585 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49422/clicks.html"
05:11:34 INFO - 1500898294586 Marionette TRACE 358 <- [1,2,null,{}]
05:11:34 INFO - 1500898294590 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:11:34 INFO - 1500898294593 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0237c64d-d7c1-46d4-b6ae-f6ebc9a25ea4","ELEMENT":"0237c64d-d7c1-46d4-b6ae-f6ebc9a25ea4"}}]
05:11:34 INFO - 1500898294594 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0237c64d-d7c1-46d4-b6ae-f6ebc9a25ea4"}]
05:11:34 INFO - 1500898294632 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49422/clicks.html"
05:11:34 INFO - 1500898294642 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49422/clicks.html"
05:11:34 INFO - 1500898294643 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49422/clicks.html"
05:11:34 INFO - 1500898294651 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49422/test.html"
05:11:34 INFO - 1500898294654 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49422/test.html"
05:11:34 INFO - 1500898294671 Marionette TRACE 358 <- [1,4,null,{}]
05:11:34 INFO - 1500898294682 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:11:34 INFO - 1500898294692 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21722d0f-99e6-4984-9da4-61f11764eb49","ELEMENT":"21722d0f-99e6-4984-9da4-61f11764eb49"}}]
05:11:34 INFO - 1500898294693 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:11:34 INFO - 1500898294693 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:11:34 INFO - 1500898294695 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:11:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500898294698 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:11:34 INFO - 1500898294699 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:11:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 190ms
05:11:34 INFO - TEST-START | test_click.py TestClick.test_container_element
05:11:34 INFO - 1500898294701 Marionette TRACE 358 <- [1,8,null,{}]
05:11:34 INFO - 1500898294702 Marionette DEBUG Closed connection 358
05:11:34 INFO - 1500898294703 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49873
05:11:34 INFO - 1500898294704 Marionette DEBUG Closed connection 359
05:11:34 INFO - 1500898294705 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49874
05:11:34 INFO - 1500898294706 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:34 INFO - 1500898294706 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294709 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294712 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3d1d28a5-13df-436b-86fd-87bd41734406","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294713 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:11:34 INFO - 1500898294713 Marionette TRACE 360 <- [1,2,null,{}]
05:11:34 INFO - 1500898294714 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:34 INFO - 1500898294715 Marionette TRACE 360 <- [1,3,null,{}]
05:11:34 INFO - 1500898294716 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:34 INFO - 1500898294716 Marionette TRACE 360 <- [1,4,null,{}]
05:11:34 INFO - 1500898294718 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:11:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500898294721 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:11:34 INFO - 1500898294722 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:11:34 INFO - 1500898294723 Marionette TRACE 360 <- [1,6,null,{}]
05:11:34 INFO - 1500898294725 Marionette DEBUG Closed connection 360
05:11:34 INFO - 1500898294726 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49875
05:11:34 INFO - 1500898294726 Marionette DEBUG Closed connection 361
05:11:34 INFO - 1500898294727 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49876
05:11:34 INFO - 1500898294728 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:34 INFO - 1500898294729 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294731 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294734 Marionette TRACE 362 <- [1,1,null,{"sessionId":"71511581-d357-4d2f-893b-188fdc971a08","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294735 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:11:34 INFO - 1500898294737 Marionette TRACE 362 <- [1,2,null,{}]
05:11:34 INFO - 1500898294738 Marionette DEBUG Closed connection 362
05:11:34 INFO - 1500898294739 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49877
05:11:34 INFO - 1500898294739 Marionette DEBUG Closed connection 363
05:11:34 INFO - 1500898294740 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49878
05:11:34 INFO - 1500898294741 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:34 INFO - 1500898294742 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:34 INFO - 1500898294744 Marionette DEBUG Register listener.js for window 4294967297
05:11:34 INFO - 1500898294747 Marionette TRACE 364 <- [1,1,null,{"sessionId":"54ef54a3-337e-41e8-97fe-fc7e105b1dc0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:34 INFO - 1500898294749 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:11:34 INFO - 1500898294750 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49422/test.html"
05:11:34 INFO - 1500898294752 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49422/test.html"
05:11:34 INFO - 1500898294753 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49422/test.html"
05:11:34 INFO - 1500898294758 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"
05:11:34 INFO - 1500898294761 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"
05:11:34 INFO - 1500898294768 Marionette TRACE 364 <- [1,2,null,{}]
05:11:34 INFO - 1500898294778 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:11:34 INFO - 1500898294780 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d3223d83-a861-4f9a-a3b9-f5dfd1aeb527","ELEMENT":"d3223d83-a861-4f9a-a3b9-f5dfd1aeb527"}}]
05:11:34 INFO - 1500898294781 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d3223d83-a861-4f9a-a3b9-f5dfd1aeb527"}]
05:11:35 INFO - 1500898295021 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:11:35 INFO - 1500898295022 Marionette TRACE 364 <- [1,4,null,{}]
05:11:35 INFO - 1500898295023 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d3223d83-a861-4f9a-a3b9-f5dfd1aeb527","name":"selected"}]
05:11:35 INFO - 1500898295027 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:11:35 INFO - 1500898295029 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:11:35 INFO - TEST-END: test_click.py TestClick.test_container_element1500898295032 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:11:35 INFO - 1500898295033 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:11:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 333ms
05:11:35 INFO - 1500898295034 Marionette TRACE 364 <- [1,7,null,{}]
05:11:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:11:35 INFO - 1500898295035 Marionette DEBUG Closed connection 364
05:11:35 INFO - 1500898295037 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49879
05:11:35 INFO - 1500898295038 Marionette DEBUG Closed connection 365
05:11:35 INFO - 1500898295039 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49880
05:11:35 INFO - 1500898295040 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295040 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295043 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295046 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0c77b3af-00d2-4ba4-989d-8e9161e7c0b7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295047 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:11:35 INFO - 1500898295047 Marionette TRACE 366 <- [1,2,null,{}]
05:11:35 INFO - 1500898295048 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:35 INFO - 1500898295048 Marionette TRACE 366 <- [1,3,null,{}]
05:11:35 INFO - 1500898295049 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:35 INFO - 1500898295050 Marionette TRACE 366 <- [1,4,null,{}]
05:11:35 INFO - 1500898295052 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:11:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500898295055 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:11:35 INFO - 1500898295056 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:11:35 INFO - 1500898295057 Marionette TRACE 366 <- [1,6,null,{}]
05:11:35 INFO - 1500898295058 Marionette DEBUG Closed connection 366
05:11:35 INFO - 1500898295059 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49881
05:11:35 INFO - 1500898295060 Marionette DEBUG Closed connection 367
05:11:35 INFO - 1500898295060 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49882
05:11:35 INFO - 1500898295061 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295062 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295065 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295068 Marionette TRACE 368 <- [1,1,null,{"sessionId":"888f0c50-681f-48f9-8fed-2153060fe20a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295069 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:11:35 INFO - 1500898295070 Marionette TRACE 368 <- [1,2,null,{}]
05:11:35 INFO - 1500898295072 Marionette DEBUG Closed connection 368
05:11:35 INFO - 1500898295072 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49883
05:11:35 INFO - 1500898295073 Marionette DEBUG Closed connection 369
05:11:35 INFO - 1500898295074 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49884
05:11:35 INFO - 1500898295075 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:35 INFO - 1500898295075 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295078 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295081 Marionette TRACE 370 <- [1,1,null,{"sessionId":"db1a870c-32f7-437a-86f0-242164c894c8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295082 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:11:35 INFO - 1500898295084 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"
05:11:35 INFO - 1500898295086 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"
05:11:35 INFO - 1500898295086 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"
05:11:35 INFO - 1500898295092 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"
05:11:35 INFO - 1500898295095 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"
05:11:35 INFO - 1500898295097 Marionette TRACE 370 <- [1,2,null,{}]
05:11:35 INFO - 1500898295105 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:11:35 INFO - 1500898295110 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"32c48d44-95ec-41ca-a54c-79f54bb27a54","ELEMENT":"32c48d44-95ec-41ca-a54c-79f54bb27a54"}}]
05:11:35 INFO - 1500898295111 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"32c48d44-95ec-41ca-a54c-79f54bb27a54"}]
05:11:35 INFO - 1500898295351 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:11:35 INFO - 1500898295352 Marionette TRACE 370 <- [1,4,null,{}]
05:11:35 INFO - 1500898295353 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"32c48d44-95ec-41ca-a54c-79f54bb27a54","name":"selected"}]
05:11:35 INFO - 1500898295357 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:11:35 INFO - 1500898295359 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:11:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500898295361 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:11:35 INFO - 1500898295363 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:11:35 INFO - 1500898295365 Marionette TRACE 370 <- [1,7,null,{}]
05:11:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 330ms
05:11:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:11:35 INFO - 1500898295366 Marionette DEBUG Closed connection 370
05:11:35 INFO - 1500898295367 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49885
05:11:35 INFO - 1500898295368 Marionette DEBUG Closed connection 371
05:11:35 INFO - 1500898295369 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49886
05:11:35 INFO - 1500898295369 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295370 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295372 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295375 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d79920e6-e667-4826-8da1-30ee9934f8a1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295377 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:11:35 INFO - 1500898295377 Marionette TRACE 372 <- [1,2,null,{}]
05:11:35 INFO - 1500898295378 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:35 INFO - 1500898295378 Marionette TRACE 372 <- [1,3,null,{}]
05:11:35 INFO - 1500898295379 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:35 INFO - 1500898295379 Marionette TRACE 372 <- [1,4,null,{}]
05:11:35 INFO - 1500898295381 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:11:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500898295384 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:11:35 INFO - 1500898295385 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:11:35 INFO - 1500898295386 Marionette TRACE 372 <- [1,6,null,{}]
05:11:35 INFO - 1500898295388 Marionette DEBUG Closed connection 372
05:11:35 INFO - 1500898295388 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49887
05:11:35 INFO - 1500898295389 Marionette DEBUG Closed connection 373
05:11:35 INFO - 1500898295390 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49888
05:11:35 INFO - 1500898295391 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295391 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295393 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295396 Marionette TRACE 374 <- [1,1,null,{"sessionId":"187352f0-e499-4239-92d7-02f1156147a5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295397 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:11:35 INFO - 1500898295399 Marionette TRACE 374 <- [1,2,null,{}]
05:11:35 INFO - 1500898295400 Marionette DEBUG Closed connection 374
05:11:35 INFO - 1500898295401 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49889
05:11:35 INFO - 1500898295401 Marionette DEBUG Closed connection 375
05:11:35 INFO - 1500898295402 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49890
05:11:35 INFO - 1500898295403 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:35 INFO - 1500898295404 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295406 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295417 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5d5e9cf0-3b96-4030-992a-227a48bd05ed","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295419 Marionette TRACE 376 -> [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"}]
05:11:35 INFO - 1500898295421 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"
05:11:35 INFO - 1500898295423 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"
05:11:35 INFO - 1500898295423 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"
05:11:35 INFO - 1500898295429 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"
05:11:35 INFO - 1500898295431 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"
05:11:35 INFO - 1500898295435 Marionette TRACE 376 <- [1,2,null,{}]
05:11:35 INFO - 1500898295445 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:11:35 INFO - 1500898295448 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a95c8ff-831a-49d8-8a7c-34f1caf82dd1","ELEMENT":"4a95c8ff-831a-49d8-8a7c-34f1caf82dd1"}}]
05:11:35 INFO - 1500898295449 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4a95c8ff-831a-49d8-8a7c-34f1caf82dd1"}]
05:11:35 INFO - 1500898295693 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:11:35 INFO - 1500898295694 Marionette TRACE 376 <- [1,4,null,{}]
05:11:35 INFO - 1500898295696 Marionette TRACE 376 -> [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}]
05:11:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500898295699 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:11:35 INFO - 1500898295700 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:11:35 INFO - 1500898295701 Marionette TRACE 376 <- [1,6,null,{}]
05:11:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 337ms
05:11:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:11:35 INFO - 1500898295703 Marionette DEBUG Closed connection 376
05:11:35 INFO - 1500898295704 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49891
05:11:35 INFO - 1500898295705 Marionette DEBUG Closed connection 377
05:11:35 INFO - 1500898295706 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49892
05:11:35 INFO - 1500898295707 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295707 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295710 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295713 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f556ab49-e0b1-45df-ae36-32fd5c8188cf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295714 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:11:35 INFO - 1500898295714 Marionette TRACE 378 <- [1,2,null,{}]
05:11:35 INFO - 1500898295715 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:35 INFO - 1500898295716 Marionette TRACE 378 <- [1,3,null,{}]
05:11:35 INFO - 1500898295717 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:35 INFO - 1500898295717 Marionette TRACE 378 <- [1,4,null,{}]
05:11:35 INFO - 1500898295719 Marionette TRACE 378 -> [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}]
05:11:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500898295722 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:11:35 INFO - 1500898295723 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:11:35 INFO - 1500898295724 Marionette TRACE 378 <- [1,6,null,{}]
05:11:35 INFO - 1500898295725 Marionette DEBUG Closed connection 378
05:11:35 INFO - 1500898295726 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49893
05:11:35 INFO - 1500898295727 Marionette DEBUG Closed connection 379
05:11:35 INFO - 1500898295728 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49894
05:11:35 INFO - 1500898295728 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:35 INFO - 1500898295729 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295731 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295734 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c54fa553-205b-4ccd-9a3b-c56f1f541fd9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295735 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:11:35 INFO - 1500898295736 Marionette TRACE 380 <- [1,2,null,{}]
05:11:35 INFO - 1500898295738 Marionette DEBUG Closed connection 380
05:11:35 INFO - 1500898295738 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49895
05:11:35 INFO - 1500898295739 Marionette DEBUG Closed connection 381
05:11:35 INFO - 1500898295740 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49896
05:11:35 INFO - 1500898295741 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:35 INFO - 1500898295741 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:35 INFO - 1500898295744 Marionette DEBUG Register listener.js for window 4294967297
05:11:35 INFO - 1500898295747 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d5aa446a-f310-40f8-96e4-8c79afd9f112","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:35 INFO - 1500898295748 Marionette TRACE 382 -> [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"}]
05:11:35 INFO - 1500898295750 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"
05:11:35 INFO - 1500898295752 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"
05:11:35 INFO - 1500898295752 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"
05:11:35 INFO - 1500898295758 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"
05:11:35 INFO - 1500898295760 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"
05:11:35 INFO - 1500898295763 Marionette TRACE 382 <- [1,2,null,{}]
05:11:35 INFO - 1500898295766 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:11:35 INFO - 1500898295774 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"74e5745e-a2a8-4537-86d3-52a60ffa330a","ELEMENT":"74e5745e-a2a8-4537-86d3-52a60ffa330a"}}]
05:11:35 INFO - 1500898295775 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"74e5745e-a2a8-4537-86d3-52a60ffa330a"}]
05:11:36 INFO - 1500898296017 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:11:36 INFO - 1500898296018 Marionette TRACE 382 <- [1,4,null,{}]
05:11:36 INFO - 1500898296020 Marionette TRACE 382 -> [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}]
05:11:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500898296023 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:11:36 INFO - 1500898296024 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:11:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
05:11:36 INFO - TEST-START | test_click.py TestClick.test_input_file
05:11:36 INFO - 1500898296025 Marionette TRACE 382 <- [1,6,null,{}]
05:11:36 INFO - 1500898296026 Marionette DEBUG Closed connection 382
05:11:36 INFO - 1500898296027 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49897
05:11:36 INFO - 1500898296028 Marionette DEBUG Closed connection 383
05:11:36 INFO - 1500898296029 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49898
05:11:36 INFO - 1500898296030 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296030 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296033 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296036 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4d01eb3b-7d2e-4b08-9b2c-7f1b9d554447","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296038 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:11:36 INFO - 1500898296038 Marionette TRACE 384 <- [1,2,null,{}]
05:11:36 INFO - 1500898296039 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:36 INFO - 1500898296039 Marionette TRACE 384 <- [1,3,null,{}]
05:11:36 INFO - 1500898296040 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:36 INFO - 1500898296041 Marionette TRACE 384 <- [1,4,null,{}]
05:11:36 INFO - 1500898296043 Marionette TRACE 384 -> [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}]
05:11:36 INFO - TEST-START: test_click.py TestClick.test_input_file1500898296045 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:11:36 INFO - 1500898296046 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:11:36 INFO - 1500898296048 Marionette TRACE 384 <- [1,6,null,{}]
05:11:36 INFO - 1500898296049 Marionette DEBUG Closed connection 384
05:11:36 INFO - 1500898296050 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49899
05:11:36 INFO - 1500898296050 Marionette DEBUG Closed connection 385
05:11:36 INFO - 1500898296051 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49900
05:11:36 INFO - 1500898296053 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296053 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296055 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296058 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4caffe69-1f98-4b9e-9629-52559a0bade7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296059 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:11:36 INFO - 1500898296060 Marionette TRACE 386 <- [1,2,null,{}]
05:11:36 INFO - 1500898296062 Marionette DEBUG Closed connection 386
05:11:36 INFO - 1500898296062 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49901
05:11:36 INFO - 1500898296063 Marionette DEBUG Closed connection 387
05:11:36 INFO - 1500898296064 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49902
05:11:36 INFO - 1500898296066 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:36 INFO - 1500898296066 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296069 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296072 Marionette TRACE 388 <- [1,1,null,{"sessionId":"80e2cb26-a441-4b9e-af17-ca8843b68dff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296073 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:11:36 INFO - 1500898296074 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"
05:11:36 INFO - 1500898296077 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"
05:11:36 INFO - 1500898296078 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"
05:11:36 INFO - 1500898296089 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:11:36 INFO - 1500898296092 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:11:36 INFO - 1500898296095 Marionette TRACE 388 <- [1,2,null,{}]
05:11:36 INFO - 1500898296099 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:11:36 INFO - 1500898296105 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf5fc39d-1ab3-437b-bf5b-009f797c49f8","ELEMENT":"bf5fc39d-1ab3-437b-bf5b-009f797c49f8"}}]
05:11:36 INFO - 1500898296107 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"bf5fc39d-1ab3-437b-bf5b-009f797c49f8"}]
05:11:36 INFO - 1500898296132 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:11:36 INFO - 1500898296134 Marionette TRACE 388 -> [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}]
05:11:36 INFO - TEST-END: test_click.py TestClick.test_input_file1500898296136 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:11:36 INFO - 1500898296137 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:11:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
05:11:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:11:36 INFO - 1500898296139 Marionette TRACE 388 <- [1,6,null,{}]
05:11:36 INFO - 1500898296140 Marionette DEBUG Closed connection 388
05:11:36 INFO - 1500898296141 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49903
05:11:36 INFO - 1500898296142 Marionette DEBUG Closed connection 389
05:11:36 INFO - 1500898296143 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49904
05:11:36 INFO - 1500898296144 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296144 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296146 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296150 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8f8084de-42a9-4c90-83df-b973aa9e3a94","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296151 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:11:36 INFO - 1500898296151 Marionette TRACE 390 <- [1,2,null,{}]
05:11:36 INFO - 1500898296152 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:36 INFO - 1500898296152 Marionette TRACE 390 <- [1,3,null,{}]
05:11:36 INFO - 1500898296153 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:36 INFO - 1500898296154 Marionette TRACE 390 <- [1,4,null,{}]
05:11:36 INFO - 1500898296156 Marionette TRACE 390 -> [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}]
05:11:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500898296159 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:11:36 INFO - 1500898296160 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:11:36 INFO - 1500898296161 Marionette TRACE 390 <- [1,6,null,{}]
05:11:36 INFO - 1500898296162 Marionette DEBUG Closed connection 390
05:11:36 INFO - 1500898296163 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49905
05:11:36 INFO - 1500898296163 Marionette DEBUG Closed connection 391
05:11:36 INFO - 1500898296164 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49906
05:11:36 INFO - 1500898296165 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296166 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296168 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296171 Marionette TRACE 392 <- [1,1,null,{"sessionId":"26ff662d-cf9f-4f0a-8065-88f6f165a1ce","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296172 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:11:36 INFO - 1500898296173 Marionette TRACE 392 <- [1,2,null,{}]
05:11:36 INFO - 1500898296175 Marionette DEBUG Closed connection 392
05:11:36 INFO - 1500898296175 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49907
05:11:36 INFO - 1500898296176 Marionette DEBUG Closed connection 393
05:11:36 INFO - 1500898296177 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49908
05:11:36 INFO - 1500898296178 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:36 INFO - 1500898296179 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296181 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296184 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2fee2a45-159c-4800-9d07-a2fcddeda27b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296185 Marionette TRACE 394 -> [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"}]
05:11:36 INFO - 1500898296187 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:11:36 INFO - 1500898296189 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:11:36 INFO - 1500898296189 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:11:36 INFO - 1500898296196 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"
05:11:36 INFO - 1500898296198 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"
05:11:36 INFO - 1500898296200 Marionette TRACE 394 <- [1,2,null,{}]
05:11:36 INFO - 1500898296209 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:11:36 INFO - 1500898296211 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"beee9ff7-ee2d-4091-9cf7-877f1ea4385a","ELEMENT":"beee9ff7-ee2d-4091-9cf7-877f1ea4385a"}}]
05:11:36 INFO - 1500898296212 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:11:36 INFO - 1500898296215 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9332bd2a-d3e2-4764-9ef8-56c64c64f780","ELEMENT":"9332bd2a-d3e2-4764-9ef8-56c64c64f780"}}]
05:11:36 INFO - 1500898296216 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"beee9ff7-ee2d-4091-9cf7-877f1ea4385a"}]
05:11:36 INFO - 1500898296459 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:11:36 INFO - 1500898296460 Marionette TRACE 394 <- [1,5,null,{}]
05:11:36 INFO - 1500898296461 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9332bd2a-d3e2-4764-9ef8-56c64c64f780"}]
05:11:36 INFO - 1500898296475 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:11:36 INFO - 1500898296477 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:11:36 INFO - 1500898296480 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:11:36 INFO - 1500898296482 Marionette TRACE 394 -> [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}]
05:11:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500898296484 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:11:36 INFO - 1500898296485 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:11:36 INFO - 1500898296486 Marionette TRACE 394 <- [1,9,null,{}]
05:11:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
05:11:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:11:36 INFO - 1500898296488 Marionette DEBUG Closed connection 394
05:11:36 INFO - 1500898296489 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49909
05:11:36 INFO - 1500898296490 Marionette DEBUG Closed connection 395
05:11:36 INFO - 1500898296490 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49910
05:11:36 INFO - 1500898296491 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296492 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296494 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296498 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9390f387-1158-483a-a540-9509cede92b7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296499 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:11:36 INFO - 1500898296499 Marionette TRACE 396 <- [1,2,null,{}]
05:11:36 INFO - 1500898296500 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:11:36 INFO - 1500898296501 Marionette TRACE 396 <- [1,3,null,{}]
05:11:36 INFO - 1500898296502 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:11:36 INFO - 1500898296502 Marionette TRACE 396 <- [1,4,null,{}]
05:11:36 INFO - 1500898296504 Marionette TRACE 396 -> [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}]
05:11:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500898296506 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:11:36 INFO - 1500898296507 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:11:36 INFO - 1500898296509 Marionette TRACE 396 <- [1,6,null,{}]
05:11:36 INFO - 1500898296510 Marionette DEBUG Closed connection 396
05:11:36 INFO - 1500898296511 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49911
05:11:36 INFO - 1500898296511 Marionette DEBUG Closed connection 397
05:11:36 INFO - 1500898296512 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49912
05:11:36 INFO - 1500898296513 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:11:36 INFO - 1500898296514 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296516 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296519 Marionette TRACE 398 <- [1,1,null,{"sessionId":"dd9ed38b-762d-4d47-b8d3-2babfd8b1077","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296520 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:11:36 INFO - 1500898296521 Marionette TRACE 398 <- [1,2,null,{}]
05:11:36 INFO - 1500898296522 Marionette DEBUG Closed connection 398
05:11:36 INFO - 1500898296523 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49913
05:11:36 INFO - 1500898296524 Marionette DEBUG Closed connection 399
05:11:36 INFO - 1500898296525 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49914
05:11:36 INFO - 1500898296526 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:11:36 INFO - 1500898296526 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}
05:11:36 INFO - 1500898296529 Marionette DEBUG Register listener.js for window 4294967297
05:11:36 INFO - 1500898296532 Marionette TRACE 400 <- [1,1,null,{"sessionId":"557eedc1-6f59-4d89-9ff0-a5672502e9da","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3656,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpowquhj.mozrunner","moz:accessibilityChecks":false}}]
05:11:36 INFO - 1500898296533 Marionette TRACE 400 -> [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"}]
05:11:36 INFO - 1500898296536 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"
05:11:36 INFO - 1500898296538 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"
05:11:36 INFO - 1500898296538 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"
05:11:36 INFO - 1500898296544 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"
05:11:36 INFO - 1500898296547 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"
05:11:36 INFO - 1500898296550 Marionette TRACE 400 <- [1,2,null,{}]
05:11:36 INFO - 1500898296560 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:11:36 INFO - 1500898296562 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a289d83d-1d1c-4d4f-970d-909f0e730b5a","ELEMENT":"a289d83d-1d1c-4d4f-970d-909f0e730b5a"}}]
05:11:36 INFO - 1500898296563 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a289d83d-1d1c-4d4f-970d-909f0e730b5a"}]
05:11:36 INFO - 1500898296585 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:11:36 INFO - 1500898296586 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a289d83d-1d1c-4d4f-970d-909f0e730b5a"}]
05:11:36 INFO - 1500898296600 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element