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]
06:53:51 INFO - 1500645231477 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}]
06:53:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500645231480 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:53:51 INFO - 1500645231481 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:53:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 135ms
06:53:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:53:51 INFO - 1500645231483 Marionette TRACE 353 <- [1,6,null,{}]
06:53:51 INFO - 1500645231484 Marionette DEBUG Closed connection 353
06:53:51 INFO - 1500645231485 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49836
06:53:51 INFO - 1500645231486 Marionette DEBUG Closed connection 354
06:53:51 INFO - 1500645231487 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49837
06:53:51 INFO - 1500645231488 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:51 INFO - 1500645231489 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231491 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231495 Marionette TRACE 355 <- [1,1,null,{"sessionId":"5ab147d5-561d-4c7a-b916-72b1c8df5b0f","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231496 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:53:51 INFO - 1500645231497 Marionette TRACE 355 <- [1,2,null,{}]
06:53:51 INFO - 1500645231498 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:51 INFO - 1500645231498 Marionette TRACE 355 <- [1,3,null,{}]
06:53:51 INFO - 1500645231499 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:51 INFO - 1500645231499 Marionette TRACE 355 <- [1,4,null,{}]
06:53:51 INFO - 1500645231501 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}]
06:53:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500645231504 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:53:51 INFO - 1500645231505 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:53:51 INFO - 1500645231508 Marionette TRACE 355 <- [1,6,null,{}]
06:53:51 INFO - 1500645231510 Marionette DEBUG Closed connection 355
06:53:51 INFO - 1500645231511 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49838
06:53:51 INFO - 1500645231511 Marionette DEBUG Closed connection 356
06:53:51 INFO - 1500645231512 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49839
06:53:51 INFO - 1500645231513 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:51 INFO - 1500645231514 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231516 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231520 Marionette TRACE 357 <- [1,1,null,{"sessionId":"83367266-3eb7-41e9-ac4e-ebd35f4ac729","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231521 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:53:51 INFO - 1500645231522 Marionette TRACE 357 <- [1,2,null,{}]
06:53:51 INFO - 1500645231524 Marionette DEBUG Closed connection 357
06:53:51 INFO - 1500645231524 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49840
06:53:51 INFO - 1500645231525 Marionette DEBUG Closed connection 358
06:53:51 INFO - 1500645231526 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49841
06:53:51 INFO - 1500645231527 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:51 INFO - 1500645231527 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231530 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231533 Marionette TRACE 359 <- [1,1,null,{"sessionId":"9ff34ae8-ba54-4fc5-9c0c-0364cb19ea80","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231535 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49397/clicks.html"}]
06:53:51 INFO - 1500645231537 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49397/hidden.html"
06:53:51 INFO - 1500645231547 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49397/hidden.html"
06:53:51 INFO - 1500645231547 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49397/hidden.html"
06:53:51 INFO - 1500645231554 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49397/clicks.html"
06:53:51 INFO - 1500645231558 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49397/clicks.html"
06:53:51 INFO - 1500645231562 Marionette TRACE 359 <- [1,2,null,{}]
06:53:51 INFO - 1500645231568 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:53:51 INFO - 1500645231571 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7ade5de-23ca-47b5-a7b6-8eb449d7dbbd","ELEMENT":"b7ade5de-23ca-47b5-a7b6-8eb449d7dbbd"}}]
06:53:51 INFO - 1500645231572 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"b7ade5de-23ca-47b5-a7b6-8eb449d7dbbd"}]
06:53:51 INFO - 1500645231613 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49397/clicks.html"
06:53:51 INFO - 1500645231622 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49397/clicks.html"
06:53:51 INFO - 1500645231622 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49397/clicks.html"
06:53:51 INFO - 1500645231630 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49397/test.html"
06:53:51 INFO - 1500645231633 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49397/test.html"
06:53:51 INFO - 1500645231638 Marionette TRACE 359 <- [1,4,null,{}]
06:53:51 INFO - 1500645231647 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:53:51 INFO - 1500645231650 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"345972d9-f381-4767-8c22-caa2dac459bc","ELEMENT":"345972d9-f381-4767-8c22-caa2dac459bc"}}]
06:53:51 INFO - 1500645231651 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:53:51 INFO - 1500645231652 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:53:51 INFO - 1500645231654 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}]
06:53:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500645231658 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:53:51 INFO - 1500645231659 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:53:51 INFO - 1500645231661 Marionette TRACE 359 <- [1,8,null,{}]
06:53:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 177ms
06:53:51 INFO - TEST-START | test_click.py TestClick.test_container_element
06:53:51 INFO - 1500645231662 Marionette DEBUG Closed connection 359
06:53:51 INFO - 1500645231663 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49844
06:53:51 INFO - 1500645231664 Marionette DEBUG Closed connection 360
06:53:51 INFO - 1500645231665 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49845
06:53:51 INFO - 1500645231666 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:51 INFO - 1500645231666 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231669 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231673 Marionette TRACE 361 <- [1,1,null,{"sessionId":"3133f201-ae76-4a94-9521-72dbc7740ca1","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231674 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:53:51 INFO - 1500645231675 Marionette TRACE 361 <- [1,2,null,{}]
06:53:51 INFO - 1500645231675 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:51 INFO - 1500645231676 Marionette TRACE 361 <- [1,3,null,{}]
06:53:51 INFO - 1500645231677 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:51 INFO - 1500645231677 Marionette TRACE 361 <- [1,4,null,{}]
06:53:51 INFO - 1500645231679 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}]
06:53:51 INFO - TEST-START: test_click.py TestClick.test_container_element1500645231682 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:53:51 INFO - 1500645231683 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:53:51 INFO - 1500645231684 Marionette TRACE 361 <- [1,6,null,{}]
06:53:51 INFO - 1500645231686 Marionette DEBUG Closed connection 361
06:53:51 INFO - 1500645231687 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49846
06:53:51 INFO - 1500645231688 Marionette DEBUG Closed connection 362
06:53:51 INFO - 1500645231689 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49847
06:53:51 INFO - 1500645231690 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:51 INFO - 1500645231690 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231693 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231696 Marionette TRACE 363 <- [1,1,null,{"sessionId":"de43ea05-b84a-4894-a03f-04441691b5e5","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231697 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:53:51 INFO - 1500645231699 Marionette TRACE 363 <- [1,2,null,{}]
06:53:51 INFO - 1500645231700 Marionette DEBUG Closed connection 363
06:53:51 INFO - 1500645231701 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49848
06:53:51 INFO - 1500645231701 Marionette DEBUG Closed connection 364
06:53:51 INFO - 1500645231702 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49849
06:53:51 INFO - 1500645231703 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:51 INFO - 1500645231704 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:51 INFO - 1500645231707 Marionette DEBUG Register listener.js for window 6442450945
06:53:51 INFO - 1500645231710 Marionette TRACE 365 <- [1,1,null,{"sessionId":"c44f07f3-a17e-4060-b131-e6af74d210f5","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:51 INFO - 1500645231711 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"}]
06:53:51 INFO - 1500645231713 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49397/test.html"
06:53:51 INFO - 1500645231715 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49397/test.html"
06:53:51 INFO - 1500645231715 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49397/test.html"
06:53:51 INFO - 1500645231721 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"
06:53:51 INFO - 1500645231724 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"
06:53:51 INFO - 1500645231739 Marionette TRACE 365 <- [1,2,null,{}]
06:53:51 INFO - 1500645231748 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:51 INFO - 1500645231751 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3a30f41-e6d9-4b01-af7a-369a7dd3a9a1","ELEMENT":"a3a30f41-e6d9-4b01-af7a-369a7dd3a9a1"}}]
06:53:51 INFO - 1500645231752 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a3a30f41-e6d9-4b01-af7a-369a7dd3a9a1"}]
06:53:52 INFO - 1500645231994 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:52 INFO - 1500645231995 Marionette TRACE 365 <- [1,4,null,{}]
06:53:52 INFO - 1500645231996 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"a3a30f41-e6d9-4b01-af7a-369a7dd3a9a1","name":"selected"}]
06:53:52 INFO - 1500645232000 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:53:52 INFO - 1500645232002 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}]
06:53:52 INFO - TEST-END: test_click.py TestClick.test_container_element1500645232005 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:53:52 INFO - 1500645232006 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:53:52 INFO - 1500645232007 Marionette TRACE 365 <- [1,7,null,{}]
06:53:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 346ms
06:53:52 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:53:52 INFO - 1500645232009 Marionette DEBUG Closed connection 365
06:53:52 INFO - 1500645232010 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49850
06:53:52 INFO - 1500645232011 Marionette DEBUG Closed connection 366
06:53:52 INFO - 1500645232012 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49851
06:53:52 INFO - 1500645232013 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232014 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232016 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232019 Marionette TRACE 367 <- [1,1,null,{"sessionId":"50f75a7d-8bf0-4611-9ef3-cb899d5ff0c1","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232021 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:53:52 INFO - 1500645232021 Marionette TRACE 367 <- [1,2,null,{}]
06:53:52 INFO - 1500645232022 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:52 INFO - 1500645232022 Marionette TRACE 367 <- [1,3,null,{}]
06:53:52 INFO - 1500645232023 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:52 INFO - 1500645232024 Marionette TRACE 367 <- [1,4,null,{}]
06:53:52 INFO - 1500645232026 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}]
06:53:52 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500645232029 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1500645232030 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:53:52 INFO - 1500645232031 Marionette TRACE 367 <- [1,6,null,{}]
06:53:52 INFO - 1500645232032 Marionette DEBUG Closed connection 367
06:53:52 INFO - 1500645232033 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49852
06:53:52 INFO - 1500645232034 Marionette DEBUG Closed connection 368
06:53:52 INFO - 1500645232035 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49853
06:53:52 INFO - 1500645232036 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232036 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232039 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232042 Marionette TRACE 369 <- [1,1,null,{"sessionId":"ab32bd0b-4fc2-463b-911b-68cbcb8a145e","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232043 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:53:52 INFO - 1500645232045 Marionette TRACE 369 <- [1,2,null,{}]
06:53:52 INFO - 1500645232046 Marionette DEBUG Closed connection 369
06:53:52 INFO - 1500645232047 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49854
06:53:52 INFO - 1500645232047 Marionette DEBUG Closed connection 370
06:53:52 INFO - 1500645232048 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49855
06:53:52 INFO - 1500645232049 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:52 INFO - 1500645232050 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232053 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232056 Marionette TRACE 371 <- [1,1,null,{"sessionId":"9e68cea5-e892-4e3b-9827-2c32a008b9ac","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232057 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"}]
06:53:52 INFO - 1500645232059 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"
06:53:52 INFO - 1500645232061 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"
06:53:52 INFO - 1500645232061 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"
06:53:52 INFO - 1500645232067 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"
06:53:52 INFO - 1500645232070 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"
06:53:52 INFO - 1500645232081 Marionette TRACE 371 <- [1,2,null,{}]
06:53:52 INFO - 1500645232085 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:52 INFO - 1500645232088 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4b017817-8362-4920-a52f-853ec00ce143","ELEMENT":"4b017817-8362-4920-a52f-853ec00ce143"}}]
06:53:52 INFO - 1500645232094 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"4b017817-8362-4920-a52f-853ec00ce143"}]
06:53:52 INFO - 1500645232345 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:52 INFO - 1500645232346 Marionette TRACE 371 <- [1,4,null,{}]
06:53:52 INFO - 1500645232347 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"4b017817-8362-4920-a52f-853ec00ce143","name":"selected"}]
06:53:52 INFO - 1500645232351 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:53:52 INFO - 1500645232353 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}]
06:53:52 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500645232356 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:53:52 INFO - 1500645232357 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:53:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 349ms
06:53:52 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:53:52 INFO - 1500645232358 Marionette TRACE 371 <- [1,7,null,{}]
06:53:52 INFO - 1500645232360 Marionette DEBUG Closed connection 371
06:53:52 INFO - 1500645232361 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49856
06:53:52 INFO - 1500645232361 Marionette DEBUG Closed connection 372
06:53:52 INFO - 1500645232362 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49857
06:53:52 INFO - 1500645232363 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232364 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232366 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232369 Marionette TRACE 373 <- [1,1,null,{"sessionId":"6cd6d5b4-3d1c-454b-a7a6-f1a3d8d084d8","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232371 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:53:52 INFO - 1500645232371 Marionette TRACE 373 <- [1,2,null,{}]
06:53:52 INFO - 1500645232372 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:52 INFO - 1500645232372 Marionette TRACE 373 <- [1,3,null,{}]
06:53:52 INFO - 1500645232374 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:52 INFO - 1500645232374 Marionette TRACE 373 <- [1,4,null,{}]
06:53:52 INFO - 1500645232376 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}]
06:53:52 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500645232379 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1500645232380 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:53:52 INFO - 1500645232381 Marionette TRACE 373 <- [1,6,null,{}]
06:53:52 INFO - 1500645232383 Marionette DEBUG Closed connection 373
06:53:52 INFO - 1500645232383 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49858
06:53:52 INFO - 1500645232384 Marionette DEBUG Closed connection 374
06:53:52 INFO - 1500645232385 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49859
06:53:52 INFO - 1500645232386 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232387 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232389 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232392 Marionette TRACE 375 <- [1,1,null,{"sessionId":"5a367bc1-8043-473b-9523-098394f1e1ac","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232394 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:53:52 INFO - 1500645232395 Marionette TRACE 375 <- [1,2,null,{}]
06:53:52 INFO - 1500645232397 Marionette DEBUG Closed connection 375
06:53:52 INFO - 1500645232397 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49860
06:53:52 INFO - 1500645232398 Marionette DEBUG Closed connection 376
06:53:52 INFO - 1500645232399 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49861
06:53:52 INFO - 1500645232400 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:52 INFO - 1500645232401 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232408 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232421 Marionette TRACE 377 <- [1,1,null,{"sessionId":"aedf2654-c356-4afe-b427-d10990de2ac0","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232422 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"}]
06:53:52 INFO - 1500645232424 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"
06:53:52 INFO - 1500645232426 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"
06:53:52 INFO - 1500645232427 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"
06:53:52 INFO - 1500645232433 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"
06:53:52 INFO - 1500645232435 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"
06:53:52 INFO - 1500645232438 Marionette TRACE 377 <- [1,2,null,{}]
06:53:52 INFO - 1500645232448 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:53:52 INFO - 1500645232452 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93ba5c08-c17f-43c1-b16f-6b9db4dd0e88","ELEMENT":"93ba5c08-c17f-43c1-b16f-6b9db4dd0e88"}}]
06:53:52 INFO - 1500645232453 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"93ba5c08-c17f-43c1-b16f-6b9db4dd0e88"}]
06:53:52 INFO - 1500645232690 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:52 INFO - 1500645232691 Marionette TRACE 377 <- [1,4,null,{}]
06:53:52 INFO - 1500645232693 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}]
06:53:52 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500645232696 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1500645232697 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:53:52 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 339ms
06:53:52 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:53:52 INFO - 1500645232698 Marionette TRACE 377 <- [1,6,null,{}]
06:53:52 INFO - 1500645232699 Marionette DEBUG Closed connection 377
06:53:52 INFO - 1500645232700 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49864
06:53:52 INFO - 1500645232701 Marionette DEBUG Closed connection 378
06:53:52 INFO - 1500645232702 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49865
06:53:52 INFO - 1500645232703 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232704 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232706 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232709 Marionette TRACE 379 <- [1,1,null,{"sessionId":"c8c4199d-b1ef-46ac-803e-a74ee18c3f19","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232710 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:53:52 INFO - 1500645232711 Marionette TRACE 379 <- [1,2,null,{}]
06:53:52 INFO - 1500645232711 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:52 INFO - 1500645232712 Marionette TRACE 379 <- [1,3,null,{}]
06:53:52 INFO - 1500645232713 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:52 INFO - 1500645232713 Marionette TRACE 379 <- [1,4,null,{}]
06:53:52 INFO - 1500645232715 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}]
06:53:52 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500645232718 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:53:52 INFO - 1500645232719 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:53:52 INFO - 1500645232720 Marionette TRACE 379 <- [1,6,null,{}]
06:53:52 INFO - 1500645232721 Marionette DEBUG Closed connection 379
06:53:52 INFO - 1500645232722 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49866
06:53:52 INFO - 1500645232723 Marionette DEBUG Closed connection 380
06:53:52 INFO - 1500645232724 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49867
06:53:52 INFO - 1500645232725 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:52 INFO - 1500645232725 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232728 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232731 Marionette TRACE 381 <- [1,1,null,{"sessionId":"f249dd84-6ef8-4a44-b4c6-6bdb788ca576","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232732 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:53:52 INFO - 1500645232733 Marionette TRACE 381 <- [1,2,null,{}]
06:53:52 INFO - 1500645232734 Marionette DEBUG Closed connection 381
06:53:52 INFO - 1500645232735 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49868
06:53:52 INFO - 1500645232736 Marionette DEBUG Closed connection 382
06:53:52 INFO - 1500645232737 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49869
06:53:52 INFO - 1500645232737 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:52 INFO - 1500645232738 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:52 INFO - 1500645232740 Marionette DEBUG Register listener.js for window 6442450945
06:53:52 INFO - 1500645232743 Marionette TRACE 383 <- [1,1,null,{"sessionId":"4ff4349a-54c0-4966-815f-e1a952fa14cc","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:52 INFO - 1500645232745 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"}]
06:53:52 INFO - 1500645232747 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"
06:53:52 INFO - 1500645232748 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"
06:53:52 INFO - 1500645232748 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"
06:53:52 INFO - 1500645232754 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"
06:53:52 INFO - 1500645232756 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"
06:53:52 INFO - 1500645232765 Marionette TRACE 383 <- [1,2,null,{}]
06:53:52 INFO - 1500645232768 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:53:52 INFO - 1500645232772 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42301884-7187-4ec6-8e6f-a9f7283e00c9","ELEMENT":"42301884-7187-4ec6-8e6f-a9f7283e00c9"}}]
06:53:52 INFO - 1500645232778 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"42301884-7187-4ec6-8e6f-a9f7283e00c9"}]
06:53:53 INFO - 1500645233020 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:53 INFO - 1500645233021 Marionette TRACE 383 <- [1,4,null,{}]
06:53:53 INFO - 1500645233023 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}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500645233026 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1500645233027 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 329ms
06:53:53 INFO - TEST-START | test_click.py TestClick.test_input_file
06:53:53 INFO - 1500645233028 Marionette TRACE 383 <- [1,6,null,{}]
06:53:53 INFO - 1500645233029 Marionette DEBUG Closed connection 383
06:53:53 INFO - 1500645233030 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49870
06:53:53 INFO - 1500645233031 Marionette DEBUG Closed connection 384
06:53:53 INFO - 1500645233032 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49871
06:53:53 INFO - 1500645233033 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233033 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233036 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233039 Marionette TRACE 385 <- [1,1,null,{"sessionId":"d4aa9ded-ade8-482b-b5a8-4e88ca1839da","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233040 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1500645233040 Marionette TRACE 385 <- [1,2,null,{}]
06:53:53 INFO - 1500645233041 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1500645233042 Marionette TRACE 385 <- [1,3,null,{}]
06:53:53 INFO - 1500645233043 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1500645233043 Marionette TRACE 385 <- [1,4,null,{}]
06:53:53 INFO - 1500645233045 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}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_input_file1500645233048 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1500645233049 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1500645233050 Marionette TRACE 385 <- [1,6,null,{}]
06:53:53 INFO - 1500645233052 Marionette DEBUG Closed connection 385
06:53:53 INFO - 1500645233052 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49872
06:53:53 INFO - 1500645233053 Marionette DEBUG Closed connection 386
06:53:53 INFO - 1500645233054 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49873
06:53:53 INFO - 1500645233055 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233055 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233058 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233061 Marionette TRACE 387 <- [1,1,null,{"sessionId":"b3bfa060-7981-4a9d-96dc-035870f4061e","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233062 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1500645233063 Marionette TRACE 387 <- [1,2,null,{}]
06:53:53 INFO - 1500645233065 Marionette DEBUG Closed connection 387
06:53:53 INFO - 1500645233065 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49874
06:53:53 INFO - 1500645233066 Marionette DEBUG Closed connection 388
06:53:53 INFO - 1500645233067 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49875
06:53:53 INFO - 1500645233068 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1500645233069 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233071 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233074 Marionette TRACE 389 <- [1,1,null,{"sessionId":"262fa828-acf7-46ae-97cb-a9b5c1960662","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233075 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:53:53 INFO - 1500645233077 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"
06:53:53 INFO - 1500645233080 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"
06:53:53 INFO - 1500645233081 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"
06:53:53 INFO - 1500645233089 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:53 INFO - 1500645233095 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:53 INFO - 1500645233098 Marionette TRACE 389 <- [1,2,null,{}]
06:53:53 INFO - 1500645233102 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:53:53 INFO - 1500645233105 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20db5eae-aa58-4da4-992a-3a59db572ebc","ELEMENT":"20db5eae-aa58-4da4-992a-3a59db572ebc"}}]
06:53:53 INFO - 1500645233106 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"20db5eae-aa58-4da4-992a-3a59db572ebc"}]
06:53:53 INFO - 1500645233136 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]
06:53:53 INFO - 1500645233139 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}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_input_file1500645233142 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
06:53:53 INFO - 1500645233143 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:53:53 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:53:53 INFO - 1500645233144 Marionette TRACE 389 <- [1,6,null,{}]
06:53:53 INFO - 1500645233146 Marionette DEBUG Closed connection 389
06:53:53 INFO - 1500645233147 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49876
06:53:53 INFO - 1500645233148 Marionette DEBUG Closed connection 390
06:53:53 INFO - 1500645233149 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49877
06:53:53 INFO - 1500645233150 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233150 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233153 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233156 Marionette TRACE 391 <- [1,1,null,{"sessionId":"4d12d6a2-7431-438e-a57d-b00a8aed808b","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233157 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1500645233158 Marionette TRACE 391 <- [1,2,null,{}]
06:53:53 INFO - 1500645233159 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1500645233159 Marionette TRACE 391 <- [1,3,null,{}]
06:53:53 INFO - 1500645233160 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1500645233160 Marionette TRACE 391 <- [1,4,null,{}]
06:53:53 INFO - 1500645233162 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}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500645233165 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1500645233166 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1500645233167 Marionette TRACE 391 <- [1,6,null,{}]
06:53:53 INFO - 1500645233169 Marionette DEBUG Closed connection 391
06:53:53 INFO - 1500645233169 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49878
06:53:53 INFO - 1500645233170 Marionette DEBUG Closed connection 392
06:53:53 INFO - 1500645233171 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49879
06:53:53 INFO - 1500645233172 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233173 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233175 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233178 Marionette TRACE 393 <- [1,1,null,{"sessionId":"6859027e-1655-4699-b1fa-60ce85a9a307","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233179 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1500645233181 Marionette TRACE 393 <- [1,2,null,{}]
06:53:53 INFO - 1500645233182 Marionette DEBUG Closed connection 393
06:53:53 INFO - 1500645233183 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49880
06:53:53 INFO - 1500645233183 Marionette DEBUG Closed connection 394
06:53:53 INFO - 1500645233184 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49881
06:53:53 INFO - 1500645233185 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1500645233186 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233188 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233191 Marionette TRACE 395 <- [1,1,null,{"sessionId":"689f1eb3-81af-4c7d-8c8e-18e8bf46e4ad","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233192 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:53:53 INFO - 1500645233194 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:53 INFO - 1500645233196 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:53 INFO - 1500645233196 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:53 INFO - 1500645233210 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"
06:53:53 INFO - 1500645233213 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"
06:53:53 INFO - 1500645233219 Marionette TRACE 395 <- [1,2,null,{}]
06:53:53 INFO - 1500645233223 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:53:53 INFO - 1500645233229 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"561f9896-b7b5-40ad-902d-a551497ee62e","ELEMENT":"561f9896-b7b5-40ad-902d-a551497ee62e"}}]
06:53:53 INFO - 1500645233230 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:53:53 INFO - 1500645233233 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3a4f38a0-09e6-4993-9e92-5b1be6d2e467","ELEMENT":"3a4f38a0-09e6-4993-9e92-5b1be6d2e467"}}]
06:53:53 INFO - 1500645233234 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"561f9896-b7b5-40ad-902d-a551497ee62e"}]
06:53:53 INFO - 1500645233471 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:53 INFO - 1500645233472 Marionette TRACE 395 <- [1,5,null,{}]
06:53:53 INFO - 1500645233473 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"3a4f38a0-09e6-4993-9e92-5b1be6d2e467"}]
06:53:53 INFO - 1500645233488 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:53:53 INFO - 1500645233490 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
06:53:53 INFO - 1500645233493 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:53:53 INFO - 1500645233495 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:53:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500645233498 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:53:53 INFO - 1500645233500 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:53:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 356ms
06:53:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:53:53 INFO - 1500645233501 Marionette TRACE 395 <- [1,9,null,{}]
06:53:53 INFO - 1500645233502 Marionette DEBUG Closed connection 395
06:53:53 INFO - 1500645233503 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49882
06:53:53 INFO - 1500645233504 Marionette DEBUG Closed connection 396
06:53:53 INFO - 1500645233505 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49883
06:53:53 INFO - 1500645233506 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233507 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233509 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233512 Marionette TRACE 397 <- [1,1,null,{"sessionId":"a643a35a-425b-4126-ae83-02b1d88c4440","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233514 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:53:53 INFO - 1500645233514 Marionette TRACE 397 <- [1,2,null,{}]
06:53:53 INFO - 1500645233515 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:53 INFO - 1500645233515 Marionette TRACE 397 <- [1,3,null,{}]
06:53:53 INFO - 1500645233516 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:53 INFO - 1500645233517 Marionette TRACE 397 <- [1,4,null,{}]
06:53:53 INFO - 1500645233519 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:53:53 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500645233522 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:53:53 INFO - 1500645233523 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:53:53 INFO - 1500645233524 Marionette TRACE 397 <- [1,6,null,{}]
06:53:53 INFO - 1500645233526 Marionette DEBUG Closed connection 397
06:53:53 INFO - 1500645233526 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49884
06:53:53 INFO - 1500645233527 Marionette DEBUG Closed connection 398
06:53:53 INFO - 1500645233528 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49885
06:53:53 INFO - 1500645233529 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:53 INFO - 1500645233530 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233532 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233536 Marionette TRACE 399 <- [1,1,null,{"sessionId":"16323e67-07dc-48c0-9f56-5582435dbc0f","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233537 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:53:53 INFO - 1500645233538 Marionette TRACE 399 <- [1,2,null,{}]
06:53:53 INFO - 1500645233540 Marionette DEBUG Closed connection 399
06:53:53 INFO - 1500645233541 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49886
06:53:53 INFO - 1500645233541 Marionette DEBUG Closed connection 400
06:53:53 INFO - 1500645233542 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49887
06:53:53 INFO - 1500645233543 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:53 INFO - 1500645233544 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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}
06:53:53 INFO - 1500645233547 Marionette DEBUG Register listener.js for window 6442450945
06:53:53 INFO - 1500645233550 Marionette TRACE 401 <- [1,1,null,{"sessionId":"94fc186f-9ace-4dbf-9efd-04369b56b0de","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":5588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptq_yq8.mozrunner","moz:accessibilityChecks":false}}]
06:53:53 INFO - 1500645233551 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:53:53 INFO - 1500645233553 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"
06:53:53 INFO - 1500645233555 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"
06:53:53 INFO - 1500645233555 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"
06:53:53 INFO - 1500645233562 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"
06:53:53 INFO - 1500645233564 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"
06:53:53 INFO - 1500645233568 Marionette TRACE 401 <- [1,2,null,{}]
06:53:53 INFO - 1500645233571 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:53:53 INFO - 1500645233579 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f777ecbb-e7ef-424e-b97f-2c87ff211929","ELEMENT":"f777ecbb-e7ef-424e-b97f-2c87ff211929"}}]
06:53:53 INFO - 1500645233580 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f777ecbb-e7ef-424e-b97f-2c87ff211929"}]
06:53:53 INFO - 1500645233605 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:53:53 INFO - 1500645233606 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"f777ecbb-e7ef-424e-b97f-2c87ff211929"}]
06:53:53 INFO - 1500645233621 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element