could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:36:07 INFO - 1500546967763 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
03:36:07 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500546967766 Marionette TRACE 353 <- [1,5,null,{"value":null}]
03:36:07 INFO - 1500546967767 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
03:36:07 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 153ms
03:36:07 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:36:07 INFO - 1500546967769 Marionette TRACE 353 <- [1,6,null,{}]
03:36:07 INFO - 1500546967771 Marionette DEBUG Closed connection 353
03:36:07 INFO - 1500546967771 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49826
03:36:07 INFO - 1500546967772 Marionette DEBUG Closed connection 354
03:36:07 INFO - 1500546967773 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49827
03:36:07 INFO - 1500546967774 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:07 INFO - 1500546967775 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:07 INFO - 1500546967778 Marionette DEBUG Register listener.js for window 6442450945
03:36:07 INFO - 1500546967782 Marionette TRACE 355 <- [1,1,null,{"sessionId":"a65a7622-6f73-4e07-99a5-c3b6f249eb32","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:07 INFO - 1500546967783 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
03:36:07 INFO - 1500546967784 Marionette TRACE 355 <- [1,2,null,{}]
03:36:07 INFO - 1500546967785 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:07 INFO - 1500546967785 Marionette TRACE 355 <- [1,3,null,{}]
03:36:07 INFO - 1500546967787 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:07 INFO - 1500546967787 Marionette TRACE 355 <- [1,4,null,{}]
03:36:07 INFO - 1500546967789 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
03:36:07 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500546967792 Marionette TRACE 355 <- [1,5,null,{"value":null}]
03:36:07 INFO - 1500546967793 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
03:36:07 INFO - 1500546967795 Marionette TRACE 355 <- [1,6,null,{}]
03:36:07 INFO - 1500546967796 Marionette DEBUG Closed connection 355
03:36:07 INFO - 1500546967797 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49828
03:36:07 INFO - 1500546967798 Marionette DEBUG Closed connection 356
03:36:07 INFO - 1500546967799 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49829
03:36:07 INFO - 1500546967800 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:07 INFO - 1500546967801 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:07 INFO - 1500546967804 Marionette DEBUG Register listener.js for window 6442450945
03:36:07 INFO - 1500546967807 Marionette TRACE 357 <- [1,1,null,{"sessionId":"d28d1cbc-5138-411e-b614-9682e7e62fd6","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:07 INFO - 1500546967809 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
03:36:07 INFO - 1500546967810 Marionette TRACE 357 <- [1,2,null,{}]
03:36:07 INFO - 1500546967812 Marionette DEBUG Closed connection 357
03:36:07 INFO - 1500546967813 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49830
03:36:07 INFO - 1500546967813 Marionette DEBUG Closed connection 358
03:36:07 INFO - 1500546967814 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49831
03:36:07 INFO - 1500546967815 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:07 INFO - 1500546967816 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:07 INFO - 1500546967819 Marionette DEBUG Register listener.js for window 6442450945
03:36:07 INFO - 1500546967823 Marionette TRACE 359 <- [1,1,null,{"sessionId":"ec2e693c-f934-4068-b459-a654f30f9a18","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:07 INFO - 1500546967824 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49382/clicks.html"}]
03:36:07 INFO - 1500546967827 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49382/hidden.html"
03:36:07 INFO - 1500546967835 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49382/hidden.html"
03:36:07 INFO - 1500546967835 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49382/hidden.html"
03:36:07 INFO - 1500546967843 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49382/clicks.html"
03:36:07 INFO - 1500546967849 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49382/clicks.html"
03:36:07 INFO - 1500546967852 Marionette TRACE 359 <- [1,2,null,{}]
03:36:07 INFO - 1500546967863 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:36:07 INFO - 1500546967866 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"726dc1fc-c4cb-46ac-b7f4-302b15d014c0","ELEMENT":"726dc1fc-c4cb-46ac-b7f4-302b15d014c0"}}]
03:36:07 INFO - 1500546967868 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"726dc1fc-c4cb-46ac-b7f4-302b15d014c0"}]
03:36:07 INFO - 1500546967911 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49382/clicks.html"
03:36:07 INFO - 1500546967921 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49382/clicks.html"
03:36:07 INFO - 1500546967921 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49382/clicks.html"
03:36:07 INFO - 1500546967931 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49382/test.html"
03:36:07 INFO - 1500546967938 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49382/test.html"
03:36:07 INFO - 1500546967943 Marionette TRACE 359 <- [1,4,null,{}]
03:36:07 INFO - 1500546967948 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:36:07 INFO - 1500546967951 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14d6f978-bcc2-42c1-a28f-17074cc3a622","ELEMENT":"14d6f978-bcc2-42c1-a28f-17074cc3a622"}}]
03:36:07 INFO - 1500546967952 Marionette TRACE 359 -> [0,6,"getTitle",{}]
03:36:07 INFO - 1500546967953 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
03:36:07 INFO - 1500546967955 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
03:36:07 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500546967975 Marionette TRACE 359 <- [1,7,null,{"value":null}]
03:36:07 INFO - 1500546967976 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
03:36:07 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 209ms
03:36:07 INFO - 1500546967979 Marionette TRACE 359 <- [1,8,null,{}]
03:36:07 INFO - TEST-START | test_click.py TestClick.test_container_element
03:36:07 INFO - 1500546967981 Marionette DEBUG Closed connection 359
03:36:07 INFO - 1500546967982 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49834
03:36:07 INFO - 1500546967983 Marionette DEBUG Closed connection 360
03:36:07 INFO - 1500546967983 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49835
03:36:07 INFO - 1500546967984 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:07 INFO - 1500546967985 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:07 INFO - 1500546967988 Marionette DEBUG Register listener.js for window 6442450945
03:36:07 INFO - 1500546967992 Marionette TRACE 361 <- [1,1,null,{"sessionId":"ee2072ad-82b9-436e-8293-e55000013b10","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:07 INFO - 1500546967994 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
03:36:07 INFO - 1500546967994 Marionette TRACE 361 <- [1,2,null,{}]
03:36:07 INFO - 1500546967995 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:07 INFO - 1500546967996 Marionette TRACE 361 <- [1,3,null,{}]
03:36:07 INFO - 1500546967997 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:07 INFO - 1500546967997 Marionette TRACE 361 <- [1,4,null,{}]
03:36:08 INFO - 1500546967999 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
03:36:08 INFO - TEST-START: test_click.py TestClick.test_container_element1500546968003 Marionette TRACE 361 <- [1,5,null,{"value":null}]
03:36:08 INFO - 1500546968004 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
03:36:08 INFO - 1500546968005 Marionette TRACE 361 <- [1,6,null,{}]
03:36:08 INFO - 1500546968007 Marionette DEBUG Closed connection 361
03:36:08 INFO - 1500546968008 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49836
03:36:08 INFO - 1500546968009 Marionette DEBUG Closed connection 362
03:36:08 INFO - 1500546968010 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49837
03:36:08 INFO - 1500546968011 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:08 INFO - 1500546968011 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968014 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968018 Marionette TRACE 363 <- [1,1,null,{"sessionId":"8f927a3e-77a1-4ac7-b994-112f7d5b9534","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968019 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
03:36:08 INFO - 1500546968021 Marionette TRACE 363 <- [1,2,null,{}]
03:36:08 INFO - 1500546968022 Marionette DEBUG Closed connection 363
03:36:08 INFO - 1500546968023 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49838
03:36:08 INFO - 1500546968024 Marionette DEBUG Closed connection 364
03:36:08 INFO - 1500546968025 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49839
03:36:08 INFO - 1500546968026 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:08 INFO - 1500546968027 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968030 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968033 Marionette TRACE 365 <- [1,1,null,{"sessionId":"b15cc844-93c4-4b7e-8710-8b097884834a","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968035 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:36:08 INFO - 1500546968037 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49382/test.html"
03:36:08 INFO - 1500546968039 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49382/test.html"
03:36:08 INFO - 1500546968039 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49382/test.html"
03:36:08 INFO - 1500546968051 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968055 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968064 Marionette TRACE 365 <- [1,2,null,{}]
03:36:08 INFO - 1500546968068 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:36:08 INFO - 1500546968079 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7f4ee12-5f01-41da-8265-195ded6f415b","ELEMENT":"c7f4ee12-5f01-41da-8265-195ded6f415b"}}]
03:36:08 INFO - 1500546968081 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"c7f4ee12-5f01-41da-8265-195ded6f415b"}]
03:36:08 INFO - 1500546968332 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:36:08 INFO - 1500546968333 Marionette TRACE 365 <- [1,4,null,{}]
03:36:08 INFO - 1500546968334 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"c7f4ee12-5f01-41da-8265-195ded6f415b","name":"selected"}]
03:36:08 INFO - 1500546968340 Marionette TRACE 365 <- [1,5,null,{"value":true}]
03:36:08 INFO - 1500546968341 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
03:36:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 368ms
03:36:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:36:08 INFO - TEST-END: test_click.py TestClick.test_container_element1500546968345 Marionette TRACE 365 <- [1,6,null,{"value":null}]
03:36:08 INFO - 1500546968346 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
03:36:08 INFO - 1500546968347 Marionette TRACE 365 <- [1,7,null,{}]
03:36:08 INFO - 1500546968349 Marionette DEBUG Closed connection 365
03:36:08 INFO - 1500546968350 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49840
03:36:08 INFO - 1500546968351 Marionette DEBUG Closed connection 366
03:36:08 INFO - 1500546968352 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49841
03:36:08 INFO - 1500546968353 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:08 INFO - 1500546968353 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968356 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968360 Marionette TRACE 367 <- [1,1,null,{"sessionId":"74f19881-6f6b-457f-ab0d-92a18c72f4e0","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968362 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
03:36:08 INFO - 1500546968362 Marionette TRACE 367 <- [1,2,null,{}]
03:36:08 INFO - 1500546968363 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:08 INFO - 1500546968364 Marionette TRACE 367 <- [1,3,null,{}]
03:36:08 INFO - 1500546968365 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:08 INFO - 1500546968365 Marionette TRACE 367 <- [1,4,null,{}]
03:36:08 INFO - 1500546968367 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
03:36:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500546968370 Marionette TRACE 367 <- [1,5,null,{"value":null}]
03:36:08 INFO - 1500546968371 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
03:36:08 INFO - 1500546968373 Marionette TRACE 367 <- [1,6,null,{}]
03:36:08 INFO - 1500546968375 Marionette DEBUG Closed connection 367
03:36:08 INFO - 1500546968375 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49842
03:36:08 INFO - 1500546968376 Marionette DEBUG Closed connection 368
03:36:08 INFO - 1500546968377 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49843
03:36:08 INFO - 1500546968378 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:08 INFO - 1500546968379 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968382 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968385 Marionette TRACE 369 <- [1,1,null,{"sessionId":"4214e8fe-7a50-43d7-af74-b87dcf2de298","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968386 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
03:36:08 INFO - 1500546968388 Marionette TRACE 369 <- [1,2,null,{}]
03:36:08 INFO - 1500546968390 Marionette DEBUG Closed connection 369
03:36:08 INFO - 1500546968391 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49844
03:36:08 INFO - 1500546968391 Marionette DEBUG Closed connection 370
03:36:08 INFO - 1500546968392 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49845
03:36:08 INFO - 1500546968394 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:08 INFO - 1500546968403 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968406 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968410 Marionette TRACE 371 <- [1,1,null,{"sessionId":"72477ae0-e954-4424-afa8-8285627c196e","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968411 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:36:08 INFO - 1500546968414 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968416 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968416 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968428 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968430 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968441 Marionette TRACE 371 <- [1,2,null,{}]
03:36:08 INFO - 1500546968445 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:36:08 INFO - 1500546968448 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93bea573-1178-4df2-8e17-ac2001c3f2b7","ELEMENT":"93bea573-1178-4df2-8e17-ac2001c3f2b7"}}]
03:36:08 INFO - 1500546968450 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"93bea573-1178-4df2-8e17-ac2001c3f2b7"}]
03:36:08 INFO - 1500546968696 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:36:08 INFO - 1500546968697 Marionette TRACE 371 <- [1,4,null,{}]
03:36:08 INFO - 1500546968698 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"93bea573-1178-4df2-8e17-ac2001c3f2b7","name":"selected"}]
03:36:08 INFO - 1500546968703 Marionette TRACE 371 <- [1,5,null,{"value":true}]
03:36:08 INFO - 1500546968705 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
03:36:08 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500546968708 Marionette TRACE 371 <- [1,6,null,{"value":null}]
03:36:08 INFO - 1500546968710 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
03:36:08 INFO - 1500546968711 Marionette TRACE 371 <- [1,7,null,{}]
03:36:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
03:36:08 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:36:08 INFO - 1500546968713 Marionette DEBUG Closed connection 371
03:36:08 INFO - 1500546968714 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49846
03:36:08 INFO - 1500546968715 Marionette DEBUG Closed connection 372
03:36:08 INFO - 1500546968716 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49847
03:36:08 INFO - 1500546968717 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:08 INFO - 1500546968718 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968721 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968724 Marionette TRACE 373 <- [1,1,null,{"sessionId":"95f239eb-d6eb-4b98-9288-814d8b80defe","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968725 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
03:36:08 INFO - 1500546968726 Marionette TRACE 373 <- [1,2,null,{}]
03:36:08 INFO - 1500546968727 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:08 INFO - 1500546968727 Marionette TRACE 373 <- [1,3,null,{}]
03:36:08 INFO - 1500546968728 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:08 INFO - 1500546968729 Marionette TRACE 373 <- [1,4,null,{}]
03:36:08 INFO - 1500546968731 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
03:36:08 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500546968734 Marionette TRACE 373 <- [1,5,null,{"value":null}]
03:36:08 INFO - 1500546968735 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
03:36:08 INFO - 1500546968737 Marionette TRACE 373 <- [1,6,null,{}]
03:36:08 INFO - 1500546968738 Marionette DEBUG Closed connection 373
03:36:08 INFO - 1500546968739 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49848
03:36:08 INFO - 1500546968740 Marionette DEBUG Closed connection 374
03:36:08 INFO - 1500546968741 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49849
03:36:08 INFO - 1500546968742 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:08 INFO - 1500546968743 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968746 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968750 Marionette TRACE 375 <- [1,1,null,{"sessionId":"c145c4b0-6b6b-44ae-b403-3abf19b6ef3e","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968751 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
03:36:08 INFO - 1500546968753 Marionette TRACE 375 <- [1,2,null,{}]
03:36:08 INFO - 1500546968754 Marionette DEBUG Closed connection 375
03:36:08 INFO - 1500546968755 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49850
03:36:08 INFO - 1500546968756 Marionette DEBUG Closed connection 376
03:36:08 INFO - 1500546968757 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49851
03:36:08 INFO - 1500546968758 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:08 INFO - 1500546968759 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:08 INFO - 1500546968762 Marionette DEBUG Register listener.js for window 6442450945
03:36:08 INFO - 1500546968765 Marionette TRACE 377 <- [1,1,null,{"sessionId":"57879b93-cfa1-41bf-a1f2-2104cf0bb133","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:08 INFO - 1500546968767 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
03:36:08 INFO - 1500546968769 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968771 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968772 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:08 INFO - 1500546968779 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:36:08 INFO - 1500546968781 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:36:08 INFO - 1500546968795 Marionette TRACE 377 <- [1,2,null,{}]
03:36:08 INFO - 1500546968801 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:36:08 INFO - 1500546968804 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"304d5669-ac38-48fd-93da-c4e2794b3424","ELEMENT":"304d5669-ac38-48fd-93da-c4e2794b3424"}}]
03:36:08 INFO - 1500546968805 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"304d5669-ac38-48fd-93da-c4e2794b3424"}]
03:36:09 INFO - 1500546969048 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:36:09 INFO - 1500546969049 Marionette TRACE 377 <- [1,4,null,{}]
03:36:09 INFO - 1500546969051 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
03:36:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500546969055 Marionette TRACE 377 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969056 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
03:36:09 INFO - 1500546969057 Marionette TRACE 377 <- [1,6,null,{}]
03:36:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
03:36:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:36:09 INFO - 1500546969059 Marionette DEBUG Closed connection 377
03:36:09 INFO - 1500546969060 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49852
03:36:09 INFO - 1500546969061 Marionette DEBUG Closed connection 378
03:36:09 INFO - 1500546969062 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49853
03:36:09 INFO - 1500546969063 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969063 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969066 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969070 Marionette TRACE 379 <- [1,1,null,{"sessionId":"a3aef636-d3d1-4a7d-8609-8f91a4a02add","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969071 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
03:36:09 INFO - 1500546969072 Marionette TRACE 379 <- [1,2,null,{}]
03:36:09 INFO - 1500546969073 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:09 INFO - 1500546969074 Marionette TRACE 379 <- [1,3,null,{}]
03:36:09 INFO - 1500546969074 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:09 INFO - 1500546969075 Marionette TRACE 379 <- [1,4,null,{}]
03:36:09 INFO - 1500546969077 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
03:36:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500546969080 Marionette TRACE 379 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969081 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
03:36:09 INFO - 1500546969083 Marionette TRACE 379 <- [1,6,null,{}]
03:36:09 INFO - 1500546969084 Marionette DEBUG Closed connection 379
03:36:09 INFO - 1500546969085 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49854
03:36:09 INFO - 1500546969086 Marionette DEBUG Closed connection 380
03:36:09 INFO - 1500546969087 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49855
03:36:09 INFO - 1500546969088 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969089 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969092 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969095 Marionette TRACE 381 <- [1,1,null,{"sessionId":"44c369e1-de10-423f-bc1a-23f9d571e69f","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969097 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
03:36:09 INFO - 1500546969098 Marionette TRACE 381 <- [1,2,null,{}]
03:36:09 INFO - 1500546969100 Marionette DEBUG Closed connection 381
03:36:09 INFO - 1500546969101 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49856
03:36:09 INFO - 1500546969102 Marionette DEBUG Closed connection 382
03:36:09 INFO - 1500546969103 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49857
03:36:09 INFO - 1500546969104 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:09 INFO - 1500546969105 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969108 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969111 Marionette TRACE 383 <- [1,1,null,{"sessionId":"5d27a676-3f11-4e2e-9208-a3875bd37d6a","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969113 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
03:36:09 INFO - 1500546969115 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:36:09 INFO - 1500546969117 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:36:09 INFO - 1500546969117 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
03:36:09 INFO - 1500546969124 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:09 INFO - 1500546969128 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:09 INFO - 1500546969137 Marionette TRACE 383 <- [1,2,null,{}]
03:36:09 INFO - 1500546969144 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:36:09 INFO - 1500546969149 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f20c76c3-eb7a-4714-9e08-95e15010a476","ELEMENT":"f20c76c3-eb7a-4714-9e08-95e15010a476"}}]
03:36:09 INFO - 1500546969150 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"f20c76c3-eb7a-4714-9e08-95e15010a476"}]
03:36:09 INFO - 1500546969404 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:36:09 INFO - 1500546969405 Marionette TRACE 383 <- [1,4,null,{}]
03:36:09 INFO - 1500546969407 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
03:36:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500546969410 Marionette TRACE 383 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969412 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
03:36:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 356ms
03:36:09 INFO - TEST-START | test_click.py TestClick.test_input_file
03:36:09 INFO - 1500546969413 Marionette TRACE 383 <- [1,6,null,{}]
03:36:09 INFO - 1500546969415 Marionette DEBUG Closed connection 383
03:36:09 INFO - 1500546969416 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49858
03:36:09 INFO - 1500546969417 Marionette DEBUG Closed connection 384
03:36:09 INFO - 1500546969418 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49859
03:36:09 INFO - 1500546969419 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969419 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969423 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969426 Marionette TRACE 385 <- [1,1,null,{"sessionId":"c1965671-2b09-4f0b-b03d-6f7bd07baf99","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969428 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
03:36:09 INFO - 1500546969428 Marionette TRACE 385 <- [1,2,null,{}]
03:36:09 INFO - 1500546969429 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:09 INFO - 1500546969430 Marionette TRACE 385 <- [1,3,null,{}]
03:36:09 INFO - 1500546969431 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:09 INFO - 1500546969431 Marionette TRACE 385 <- [1,4,null,{}]
03:36:09 INFO - 1500546969433 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
03:36:09 INFO - TEST-START: test_click.py TestClick.test_input_file1500546969436 Marionette TRACE 385 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969437 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
03:36:09 INFO - 1500546969439 Marionette TRACE 385 <- [1,6,null,{}]
03:36:09 INFO - 1500546969441 Marionette DEBUG Closed connection 385
03:36:09 INFO - 1500546969441 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49860
03:36:09 INFO - 1500546969442 Marionette DEBUG Closed connection 386
03:36:09 INFO - 1500546969443 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49861
03:36:09 INFO - 1500546969444 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969445 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969448 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969452 Marionette TRACE 387 <- [1,1,null,{"sessionId":"8da1cb66-7cdf-44fe-9d25-49bbe22f9495","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969453 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
03:36:09 INFO - 1500546969455 Marionette TRACE 387 <- [1,2,null,{}]
03:36:09 INFO - 1500546969456 Marionette DEBUG Closed connection 387
03:36:09 INFO - 1500546969457 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49862
03:36:09 INFO - 1500546969458 Marionette DEBUG Closed connection 388
03:36:09 INFO - 1500546969459 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49863
03:36:09 INFO - 1500546969460 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:09 INFO - 1500546969461 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969464 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969467 Marionette TRACE 389 <- [1,1,null,{"sessionId":"b76f69e9-0d36-4def-ac26-65a13fd60be0","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969469 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:36:09 INFO - 1500546969471 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:09 INFO - 1500546969473 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:09 INFO - 1500546969473 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
03:36:09 INFO - 1500546969480 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:36:09 INFO - 1500546969486 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:36:09 INFO - 1500546969487 Marionette TRACE 389 <- [1,2,null,{}]
03:36:09 INFO - 1500546969498 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:36:09 INFO - 1500546969502 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43662242-4f1b-4b85-bfc8-b06bb8b5c3f6","ELEMENT":"43662242-4f1b-4b85-bfc8-b06bb8b5c3f6"}}]
03:36:09 INFO - 1500546969503 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"43662242-4f1b-4b85-bfc8-b06bb8b5c3f6"}]
03:36:09 INFO - 1500546969531 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:36:09 INFO - 1500546969533 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
03:36:09 INFO - TEST-END: test_click.py TestClick.test_input_file1500546969537 Marionette TRACE 389 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969538 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
03:36:09 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 126ms
03:36:09 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:36:09 INFO - 1500546969540 Marionette TRACE 389 <- [1,6,null,{}]
03:36:09 INFO - 1500546969541 Marionette DEBUG Closed connection 389
03:36:09 INFO - 1500546969542 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49864
03:36:09 INFO - 1500546969543 Marionette DEBUG Closed connection 390
03:36:09 INFO - 1500546969544 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49865
03:36:09 INFO - 1500546969545 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969546 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969549 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969553 Marionette TRACE 391 <- [1,1,null,{"sessionId":"15bc7e12-93b4-4252-a1e4-abd67358e9aa","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969554 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
03:36:09 INFO - 1500546969555 Marionette TRACE 391 <- [1,2,null,{}]
03:36:09 INFO - 1500546969556 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:09 INFO - 1500546969556 Marionette TRACE 391 <- [1,3,null,{}]
03:36:09 INFO - 1500546969557 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:09 INFO - 1500546969558 Marionette TRACE 391 <- [1,4,null,{}]
03:36:09 INFO - 1500546969560 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
03:36:09 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500546969563 Marionette TRACE 391 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969564 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
03:36:09 INFO - 1500546969566 Marionette TRACE 391 <- [1,6,null,{}]
03:36:09 INFO - 1500546969568 Marionette DEBUG Closed connection 391
03:36:09 INFO - 1500546969568 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49866
03:36:09 INFO - 1500546969569 Marionette DEBUG Closed connection 392
03:36:09 INFO - 1500546969570 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49867
03:36:09 INFO - 1500546969571 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969572 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969575 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969579 Marionette TRACE 393 <- [1,1,null,{"sessionId":"42464e0b-4543-48d3-b9fa-c1466924384b","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969580 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
03:36:09 INFO - 1500546969582 Marionette TRACE 393 <- [1,2,null,{}]
03:36:09 INFO - 1500546969583 Marionette DEBUG Closed connection 393
03:36:09 INFO - 1500546969584 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49868
03:36:09 INFO - 1500546969585 Marionette DEBUG Closed connection 394
03:36:09 INFO - 1500546969586 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49869
03:36:09 INFO - 1500546969587 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:09 INFO - 1500546969588 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969591 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969595 Marionette TRACE 395 <- [1,1,null,{"sessionId":"7d97887e-b5e9-4040-9eac-014541806efb","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969596 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"}]
03:36:09 INFO - 1500546969598 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:36:09 INFO - 1500546969600 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:36:09 INFO - 1500546969600 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:36:09 INFO - 1500546969610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:36:09 INFO - 1500546969612 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:36:09 INFO - 1500546969621 Marionette TRACE 395 <- [1,2,null,{}]
03:36:09 INFO - 1500546969626 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:36:09 INFO - 1500546969630 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5cb4e678-da44-4d13-b499-0e087d263fb1","ELEMENT":"5cb4e678-da44-4d13-b499-0e087d263fb1"}}]
03:36:09 INFO - 1500546969632 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:36:09 INFO - 1500546969634 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"97ab0813-d826-48e4-8248-524d5be98531","ELEMENT":"97ab0813-d826-48e4-8248-524d5be98531"}}]
03:36:09 INFO - 1500546969636 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"5cb4e678-da44-4d13-b499-0e087d263fb1"}]
03:36:09 INFO - 1500546969878 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:36:09 INFO - 1500546969879 Marionette TRACE 395 <- [1,5,null,{}]
03:36:09 INFO - 1500546969880 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"97ab0813-d826-48e4-8248-524d5be98531"}]
03:36:09 INFO - 1500546969898 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]
03:36:09 INFO - 1500546969900 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
03:36:09 INFO - 1500546969903 Marionette TRACE 395 <- [1,7,null,{"value":false}]
03:36:09 INFO - 1500546969905 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}]
03:36:09 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500546969908 Marionette TRACE 395 <- [1,8,null,{"value":null}]
03:36:09 INFO - 1500546969909 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
03:36:09 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 371ms
03:36:09 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:36:09 INFO - 1500546969911 Marionette TRACE 395 <- [1,9,null,{}]
03:36:09 INFO - 1500546969913 Marionette DEBUG Closed connection 395
03:36:09 INFO - 1500546969914 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49872
03:36:09 INFO - 1500546969915 Marionette DEBUG Closed connection 396
03:36:09 INFO - 1500546969915 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49873
03:36:09 INFO - 1500546969917 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969917 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969920 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969924 Marionette TRACE 397 <- [1,1,null,{"sessionId":"09de17c1-5811-45e8-a608-aa58c61a4abb","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:09 INFO - 1500546969925 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
03:36:09 INFO - 1500546969926 Marionette TRACE 397 <- [1,2,null,{}]
03:36:09 INFO - 1500546969927 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:36:09 INFO - 1500546969927 Marionette TRACE 397 <- [1,3,null,{}]
03:36:09 INFO - 1500546969928 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
03:36:09 INFO - 1500546969928 Marionette TRACE 397 <- [1,4,null,{}]
03:36:09 INFO - 1500546969930 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}]
03:36:09 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500546969933 Marionette TRACE 397 <- [1,5,null,{"value":null}]
03:36:09 INFO - 1500546969934 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
03:36:09 INFO - 1500546969936 Marionette TRACE 397 <- [1,6,null,{}]
03:36:09 INFO - 1500546969938 Marionette DEBUG Closed connection 397
03:36:09 INFO - 1500546969938 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49874
03:36:09 INFO - 1500546969939 Marionette DEBUG Closed connection 398
03:36:09 INFO - 1500546969940 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49875
03:36:09 INFO - 1500546969941 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:36:09 INFO - 1500546969942 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:09 INFO - 1500546969945 Marionette DEBUG Register listener.js for window 6442450945
03:36:09 INFO - 1500546969949 Marionette TRACE 399 <- [1,1,null,{"sessionId":"c725dd7b-e42f-42d3-940b-24a5589548dc","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:10 INFO - 1500546970010 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
03:36:10 INFO - 1500546970014 Marionette TRACE 399 <- [1,2,null,{}]
03:36:10 INFO - 1500546970020 Marionette DEBUG Closed connection 399
03:36:10 INFO - 1500546970021 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49876
03:36:10 INFO - 1500546970025 Marionette DEBUG Closed connection 400
03:36:10 INFO - 1500546970026 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49877
03:36:10 INFO - 1500546970028 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:36:10 INFO - 1500546970032 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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}
03:36:10 INFO - 1500546970035 Marionette DEBUG Register listener.js for window 6442450945
03:36:10 INFO - 1500546970056 Marionette TRACE 401 <- [1,1,null,{"sessionId":"eb070233-bce3-4444-b4ec-a467031f58a1","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":3312,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptndglu.mozrunner","moz:accessibilityChecks":false}}]
03:36:10 INFO - 1500546970059 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"}]
03:36:10 INFO - 1500546970062 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:36:10 INFO - 1500546970065 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:36:10 INFO - 1500546970065 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
03:36:10 INFO - 1500546970074 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
03:36:10 INFO - 1500546970076 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
03:36:10 INFO - 1500546970096 Marionette TRACE 401 <- [1,2,null,{}]
03:36:10 INFO - 1500546970105 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:36:10 INFO - 1500546970115 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22f982a5-e722-4635-8e9c-a76db05164d8","ELEMENT":"22f982a5-e722-4635-8e9c-a76db05164d8"}}]
03:36:10 INFO - 1500546970117 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"22f982a5-e722-4635-8e9c-a76db05164d8"}]
03:36:10 INFO - 1500546970147 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
03:36:10 INFO - 1500546970149 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"22f982a5-e722-4635-8e9c-a76db05164d8"}]
03:36:10 INFO - 1500546970170 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element