could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:26:54 INFO - 1501061214581 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
02:26:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501061214584 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:26:54 INFO - 1501061214585 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:26:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 162ms
02:26:54 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:26:54 INFO - 1501061214587 Marionette TRACE 352 <- [1,6,null,{}]
02:26:54 INFO - 1501061214589 Marionette DEBUG Closed connection 352
02:26:54 INFO - 1501061214590 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49810
02:26:54 INFO - 1501061214592 Marionette DEBUG Closed connection 353
02:26:54 INFO - 1501061214592 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49811
02:26:54 INFO - 1501061214593 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:54 INFO - 1501061214594 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214596 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214606 Marionette TRACE 354 <- [1,1,null,{"sessionId":"16e293e7-fe0d-4374-b3e2-fa5f2445cbe2","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214608 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:26:54 INFO - 1501061214608 Marionette TRACE 354 <- [1,2,null,{}]
02:26:54 INFO - 1501061214609 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:54 INFO - 1501061214610 Marionette TRACE 354 <- [1,3,null,{}]
02:26:54 INFO - 1501061214611 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:54 INFO - 1501061214611 Marionette TRACE 354 <- [1,4,null,{}]
02:26:54 INFO - 1501061214613 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
02:26:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501061214616 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:26:54 INFO - 1501061214617 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:26:54 INFO - 1501061214619 Marionette TRACE 354 <- [1,6,null,{}]
02:26:54 INFO - 1501061214622 Marionette DEBUG Closed connection 354
02:26:54 INFO - 1501061214622 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49812
02:26:54 INFO - 1501061214623 Marionette DEBUG Closed connection 355
02:26:54 INFO - 1501061214624 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49813
02:26:54 INFO - 1501061214625 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:54 INFO - 1501061214626 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214628 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214631 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4722e4f9-2b94-4dce-96d0-7c45ae986923","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214633 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:26:54 INFO - 1501061214635 Marionette TRACE 356 <- [1,2,null,{}]
02:26:54 INFO - 1501061214638 Marionette DEBUG Closed connection 356
02:26:54 INFO - 1501061214639 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49814
02:26:54 INFO - 1501061214640 Marionette DEBUG Closed connection 357
02:26:54 INFO - 1501061214641 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49815
02:26:54 INFO - 1501061214642 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:54 INFO - 1501061214642 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214644 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214647 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7b3647d5-349b-49d5-95ae-24b5c3a5bbc6","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214650 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49377/clicks.html"}]
02:26:54 INFO - 1501061214651 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49377/hidden.html"
02:26:54 INFO - 1501061214657 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49377/hidden.html"
02:26:54 INFO - 1501061214658 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49377/hidden.html"
02:26:54 INFO - 1501061214678 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49377/clicks.html"
02:26:54 INFO - 1501061214686 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49377/clicks.html"
02:26:54 INFO - 1501061214690 Marionette TRACE 358 <- [1,2,null,{}]
02:26:54 INFO - 1501061214691 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:26:54 INFO - 1501061214694 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f30a1155-c0b3-41da-8f59-80ac9bbcabad","ELEMENT":"f30a1155-c0b3-41da-8f59-80ac9bbcabad"}}]
02:26:54 INFO - 1501061214703 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f30a1155-c0b3-41da-8f59-80ac9bbcabad"}]
02:26:54 INFO - 1501061214746 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49377/clicks.html"
02:26:54 INFO - 1501061214758 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49377/clicks.html"
02:26:54 INFO - 1501061214759 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49377/clicks.html"
02:26:54 INFO - 1501061214794 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49377/test.html"
02:26:54 INFO - 1501061214798 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49377/test.html"
02:26:54 INFO - 1501061214811 Marionette TRACE 358 <- [1,4,null,{}]
02:26:54 INFO - 1501061214816 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:26:54 INFO - 1501061214819 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"76728a5a-a40c-4975-a944-db2947044588","ELEMENT":"76728a5a-a40c-4975-a944-db2947044588"}}]
02:26:54 INFO - 1501061214820 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:26:54 INFO - 1501061214821 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:26:54 INFO - 1501061214822 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
02:26:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501061214825 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:26:54 INFO - 1501061214826 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:26:54 INFO - 1501061214828 Marionette TRACE 358 <- [1,8,null,{}]
02:26:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 242ms
02:26:54 INFO - TEST-START | test_click.py TestClick.test_container_element
02:26:54 INFO - 1501061214831 Marionette DEBUG Closed connection 358
02:26:54 INFO - 1501061214831 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49818
02:26:54 INFO - 1501061214832 Marionette DEBUG Closed connection 359
02:26:54 INFO - 1501061214833 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49819
02:26:54 INFO - 1501061214834 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:54 INFO - 1501061214835 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214837 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214840 Marionette TRACE 360 <- [1,1,null,{"sessionId":"13d0d7ae-00f3-4cfc-ab51-f23aa6e1d5af","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214842 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:26:54 INFO - 1501061214843 Marionette TRACE 360 <- [1,2,null,{}]
02:26:54 INFO - 1501061214844 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:54 INFO - 1501061214844 Marionette TRACE 360 <- [1,3,null,{}]
02:26:54 INFO - 1501061214845 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:54 INFO - 1501061214846 Marionette TRACE 360 <- [1,4,null,{}]
02:26:54 INFO - 1501061214848 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
02:26:54 INFO - TEST-START: test_click.py TestClick.test_container_element1501061214850 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:26:54 INFO - 1501061214851 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:26:54 INFO - 1501061214853 Marionette TRACE 360 <- [1,6,null,{}]
02:26:54 INFO - 1501061214855 Marionette DEBUG Closed connection 360
02:26:54 INFO - 1501061214856 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49820
02:26:54 INFO - 1501061214857 Marionette DEBUG Closed connection 361
02:26:54 INFO - 1501061214857 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49821
02:26:54 INFO - 1501061214858 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:54 INFO - 1501061214859 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214861 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214864 Marionette TRACE 362 <- [1,1,null,{"sessionId":"59064529-d508-4899-bc80-6dbf1ac0cca7","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214866 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:26:54 INFO - 1501061214868 Marionette TRACE 362 <- [1,2,null,{}]
02:26:54 INFO - 1501061214870 Marionette DEBUG Closed connection 362
02:26:54 INFO - 1501061214871 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49822
02:26:54 INFO - 1501061214872 Marionette DEBUG Closed connection 363
02:26:54 INFO - 1501061214872 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49823
02:26:54 INFO - 1501061214873 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:54 INFO - 1501061214874 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:54 INFO - 1501061214876 Marionette DEBUG Register listener.js for window 12
02:26:54 INFO - 1501061214879 Marionette TRACE 364 <- [1,1,null,{"sessionId":"cf6673a3-d6fa-462d-b1e3-b81108a9d575","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:54 INFO - 1501061214883 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:26:54 INFO - 1501061214884 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49377/test.html"
02:26:54 INFO - 1501061214887 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49377/test.html"
02:26:54 INFO - 1501061214887 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49377/test.html"
02:26:54 INFO - 1501061214918 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"
02:26:54 INFO - 1501061214919 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"
02:26:54 INFO - 1501061214923 Marionette TRACE 364 <- [1,2,null,{}]
02:26:54 INFO - 1501061214925 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:26:54 INFO - 1501061214928 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7de36304-ec09-4cab-a556-e4adfea0b147","ELEMENT":"7de36304-ec09-4cab-a556-e4adfea0b147"}}]
02:26:54 INFO - 1501061214936 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7de36304-ec09-4cab-a556-e4adfea0b147"}]
02:26:55 INFO - 1501061215179 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:26:55 INFO - 1501061215180 Marionette TRACE 364 <- [1,4,null,{}]
02:26:55 INFO - 1501061215181 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7de36304-ec09-4cab-a556-e4adfea0b147","name":"selected"}]
02:26:55 INFO - 1501061215186 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:26:55 INFO - 1501061215187 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
02:26:55 INFO - TEST-END: test_click.py TestClick.test_container_element1501061215190 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:26:55 INFO - 1501061215191 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:26:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 364ms
02:26:55 INFO - 1501061215193 Marionette TRACE 364 <- [1,7,null,{}]
02:26:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:26:55 INFO - 1501061215195 Marionette DEBUG Closed connection 364
02:26:55 INFO - 1501061215196 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49824
02:26:55 INFO - 1501061215197 Marionette DEBUG Closed connection 365
02:26:55 INFO - 1501061215198 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49825
02:26:55 INFO - 1501061215199 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:55 INFO - 1501061215200 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215202 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215211 Marionette TRACE 366 <- [1,1,null,{"sessionId":"78e1cb42-138a-42ea-82b7-1e98278d4cc9","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215217 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:26:55 INFO - 1501061215218 Marionette TRACE 366 <- [1,2,null,{}]
02:26:55 INFO - 1501061215219 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:55 INFO - 1501061215219 Marionette TRACE 366 <- [1,3,null,{}]
02:26:55 INFO - 1501061215220 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:55 INFO - 1501061215221 Marionette TRACE 366 <- [1,4,null,{}]
02:26:55 INFO - 1501061215223 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
02:26:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501061215232 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:26:55 INFO - 1501061215234 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:26:55 INFO - 1501061215235 Marionette TRACE 366 <- [1,6,null,{}]
02:26:55 INFO - 1501061215239 Marionette DEBUG Closed connection 366
02:26:55 INFO - 1501061215240 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49826
02:26:55 INFO - 1501061215241 Marionette DEBUG Closed connection 367
02:26:55 INFO - 1501061215241 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49827
02:26:55 INFO - 1501061215242 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:55 INFO - 1501061215243 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215245 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215248 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d5368b5b-cc95-4248-abd2-1aae221f97cd","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215251 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:26:55 INFO - 1501061215252 Marionette TRACE 368 <- [1,2,null,{}]
02:26:55 INFO - 1501061215254 Marionette DEBUG Closed connection 368
02:26:55 INFO - 1501061215255 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49828
02:26:55 INFO - 1501061215256 Marionette DEBUG Closed connection 369
02:26:55 INFO - 1501061215257 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49829
02:26:55 INFO - 1501061215258 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:55 INFO - 1501061215258 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215260 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215264 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5f3131c6-3b06-4ea1-8eef-66e734b54745","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215266 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:26:55 INFO - 1501061215268 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"
02:26:55 INFO - 1501061215271 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"
02:26:55 INFO - 1501061215272 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"
02:26:55 INFO - 1501061215291 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"
02:26:55 INFO - 1501061215295 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"
02:26:55 INFO - 1501061215304 Marionette TRACE 370 <- [1,2,null,{}]
02:26:55 INFO - 1501061215310 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:26:55 INFO - 1501061215313 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ccb4810e-7fd7-4ebc-9c35-1004e052a490","ELEMENT":"ccb4810e-7fd7-4ebc-9c35-1004e052a490"}}]
02:26:55 INFO - 1501061215314 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ccb4810e-7fd7-4ebc-9c35-1004e052a490"}]
02:26:55 INFO - 1501061215559 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:26:55 INFO - 1501061215560 Marionette TRACE 370 <- [1,4,null,{}]
02:26:55 INFO - 1501061215576 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ccb4810e-7fd7-4ebc-9c35-1004e052a490","name":"selected"}]
02:26:55 INFO - 1501061215587 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:26:55 INFO - 1501061215601 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
02:26:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501061215604 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:26:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 425ms
02:26:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:26:55 INFO - 1501061215617 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:26:55 INFO - 1501061215618 Marionette TRACE 370 <- [1,7,null,{}]
02:26:55 INFO - 1501061215621 Marionette DEBUG Closed connection 370
02:26:55 INFO - 1501061215622 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49830
02:26:55 INFO - 1501061215623 Marionette DEBUG Closed connection 371
02:26:55 INFO - 1501061215623 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49831
02:26:55 INFO - 1501061215624 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:55 INFO - 1501061215625 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215627 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215634 Marionette TRACE 372 <- [1,1,null,{"sessionId":"160b6d11-4370-4f48-805e-21f901f1a43a","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215636 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:26:55 INFO - 1501061215637 Marionette TRACE 372 <- [1,2,null,{}]
02:26:55 INFO - 1501061215638 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:55 INFO - 1501061215638 Marionette TRACE 372 <- [1,3,null,{}]
02:26:55 INFO - 1501061215639 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:55 INFO - 1501061215640 Marionette TRACE 372 <- [1,4,null,{}]
02:26:55 INFO - 1501061215642 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
02:26:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501061215644 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:26:55 INFO - 1501061215645 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:26:55 INFO - 1501061215647 Marionette TRACE 372 <- [1,6,null,{}]
02:26:55 INFO - 1501061215651 Marionette DEBUG Closed connection 372
02:26:55 INFO - 1501061215651 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49832
02:26:55 INFO - 1501061215653 Marionette DEBUG Closed connection 373
02:26:55 INFO - 1501061215654 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49833
02:26:55 INFO - 1501061215655 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:55 INFO - 1501061215656 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215658 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215661 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b254fe15-3764-42db-bf16-90f19ab30998","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215664 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:26:55 INFO - 1501061215665 Marionette TRACE 374 <- [1,2,null,{}]
02:26:55 INFO - 1501061215668 Marionette DEBUG Closed connection 374
02:26:55 INFO - 1501061215669 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49834
02:26:55 INFO - 1501061215669 Marionette DEBUG Closed connection 375
02:26:55 INFO - 1501061215670 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49835
02:26:55 INFO - 1501061215671 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:55 INFO - 1501061215672 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215674 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215677 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2ee74171-c27d-4f05-ab58-b03c084f98cd","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215679 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
02:26:55 INFO - 1501061215681 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"
02:26:55 INFO - 1501061215684 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"
02:26:55 INFO - 1501061215684 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"
02:26:55 INFO - 1501061215708 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"
02:26:55 INFO - 1501061215709 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"
02:26:55 INFO - 1501061215714 Marionette TRACE 376 <- [1,2,null,{}]
02:26:55 INFO - 1501061215723 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:26:55 INFO - 1501061215726 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c0f9c1f8-9899-4154-b7f8-f5031b470604","ELEMENT":"c0f9c1f8-9899-4154-b7f8-f5031b470604"}}]
02:26:55 INFO - 1501061215727 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c0f9c1f8-9899-4154-b7f8-f5031b470604"}]
02:26:55 INFO - 1501061215968 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:26:55 INFO - 1501061215969 Marionette TRACE 376 <- [1,4,null,{}]
02:26:55 INFO - 1501061215971 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
02:26:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501061215975 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:26:55 INFO - 1501061215976 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:26:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 359ms
02:26:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:26:55 INFO - 1501061215978 Marionette TRACE 376 <- [1,6,null,{}]
02:26:55 INFO - 1501061215980 Marionette DEBUG Closed connection 376
02:26:55 INFO - 1501061215981 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49836
02:26:55 INFO - 1501061215982 Marionette DEBUG Closed connection 377
02:26:55 INFO - 1501061215982 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49837
02:26:55 INFO - 1501061215983 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:55 INFO - 1501061215984 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:55 INFO - 1501061215986 Marionette DEBUG Register listener.js for window 12
02:26:55 INFO - 1501061215990 Marionette TRACE 378 <- [1,1,null,{"sessionId":"974f694d-7ff4-4bbb-9974-87c9fd0fd6db","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:55 INFO - 1501061215992 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:26:55 INFO - 1501061215992 Marionette TRACE 378 <- [1,2,null,{}]
02:26:55 INFO - 1501061215993 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:55 INFO - 1501061215994 Marionette TRACE 378 <- [1,3,null,{}]
02:26:55 INFO - 1501061215994 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:55 INFO - 1501061215995 Marionette TRACE 378 <- [1,4,null,{}]
02:26:55 INFO - 1501061215997 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
02:26:56 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501061215999 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216000 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:26:56 INFO - 1501061216002 Marionette TRACE 378 <- [1,6,null,{}]
02:26:56 INFO - 1501061216004 Marionette DEBUG Closed connection 378
02:26:56 INFO - 1501061216005 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49838
02:26:56 INFO - 1501061216006 Marionette DEBUG Closed connection 379
02:26:56 INFO - 1501061216006 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49839
02:26:56 INFO - 1501061216007 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216008 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216010 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216019 Marionette TRACE 380 <- [1,1,null,{"sessionId":"6b639dbb-b917-485b-a4ac-3d3683aca410","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216022 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:26:56 INFO - 1501061216023 Marionette TRACE 380 <- [1,2,null,{}]
02:26:56 INFO - 1501061216025 Marionette DEBUG Closed connection 380
02:26:56 INFO - 1501061216026 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49840
02:26:56 INFO - 1501061216027 Marionette DEBUG Closed connection 381
02:26:56 INFO - 1501061216028 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49841
02:26:56 INFO - 1501061216029 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:56 INFO - 1501061216029 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216031 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216035 Marionette TRACE 382 <- [1,1,null,{"sessionId":"a41774ce-2cac-470c-a804-7c00a8853add","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216037 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
02:26:56 INFO - 1501061216038 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"
02:26:56 INFO - 1501061216041 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"
02:26:56 INFO - 1501061216041 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"
02:26:56 INFO - 1501061216062 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"
02:26:56 INFO - 1501061216066 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"
02:26:56 INFO - 1501061216070 Marionette TRACE 382 <- [1,2,null,{}]
02:26:56 INFO - 1501061216072 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:26:56 INFO - 1501061216074 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c74c604-8a1e-427d-94a8-192f49852599","ELEMENT":"2c74c604-8a1e-427d-94a8-192f49852599"}}]
02:26:56 INFO - 1501061216075 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2c74c604-8a1e-427d-94a8-192f49852599"}]
02:26:56 INFO - 1501061216329 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:26:56 INFO - 1501061216330 Marionette TRACE 382 <- [1,4,null,{}]
02:26:56 INFO - 1501061216332 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
02:26:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501061216335 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216336 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:26:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 360ms
02:26:56 INFO - 1501061216338 Marionette TRACE 382 <- [1,6,null,{}]
02:26:56 INFO - TEST-START | test_click.py TestClick.test_input_file
02:26:56 INFO - 1501061216340 Marionette DEBUG Closed connection 382
02:26:56 INFO - 1501061216342 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49842
02:26:56 INFO - 1501061216342 Marionette DEBUG Closed connection 383
02:26:56 INFO - 1501061216343 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49843
02:26:56 INFO - 1501061216344 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216345 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216347 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216350 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ee7c0a3f-ccb1-4873-b402-531373075fde","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216352 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:26:56 INFO - 1501061216353 Marionette TRACE 384 <- [1,2,null,{}]
02:26:56 INFO - 1501061216354 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:56 INFO - 1501061216354 Marionette TRACE 384 <- [1,3,null,{}]
02:26:56 INFO - 1501061216355 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:56 INFO - 1501061216356 Marionette TRACE 384 <- [1,4,null,{}]
02:26:56 INFO - 1501061216357 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
02:26:56 INFO - TEST-START: test_click.py TestClick.test_input_file1501061216360 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216361 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:26:56 INFO - 1501061216363 Marionette TRACE 384 <- [1,6,null,{}]
02:26:56 INFO - 1501061216365 Marionette DEBUG Closed connection 384
02:26:56 INFO - 1501061216366 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49844
02:26:56 INFO - 1501061216366 Marionette DEBUG Closed connection 385
02:26:56 INFO - 1501061216367 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49845
02:26:56 INFO - 1501061216368 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216369 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216371 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216374 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a48c6ff6-10d6-4fd3-99c4-8525eecf1505","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216376 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:26:56 INFO - 1501061216378 Marionette TRACE 386 <- [1,2,null,{}]
02:26:56 INFO - 1501061216380 Marionette DEBUG Closed connection 386
02:26:56 INFO - 1501061216381 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49846
02:26:56 INFO - 1501061216382 Marionette DEBUG Closed connection 387
02:26:56 INFO - 1501061216382 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49847
02:26:56 INFO - 1501061216383 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:56 INFO - 1501061216384 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216386 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216389 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9f49d663-fd38-49bf-9474-c5e6ebbca397","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216391 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:26:56 INFO - 1501061216393 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"
02:26:56 INFO - 1501061216395 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"
02:26:56 INFO - 1501061216396 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"
02:26:56 INFO - 1501061216420 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:26:56 INFO - 1501061216423 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:26:56 INFO - 1501061216428 Marionette TRACE 388 <- [1,2,null,{}]
02:26:56 INFO - 1501061216438 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:26:56 INFO - 1501061216441 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6bcbe82a-1cdc-462c-8a74-e47f717ee789","ELEMENT":"6bcbe82a-1cdc-462c-8a74-e47f717ee789"}}]
02:26:56 INFO - 1501061216442 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6bcbe82a-1cdc-462c-8a74-e47f717ee789"}]
02:26:56 INFO - 1501061216472 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:26:56 INFO - 1501061216474 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
02:26:56 INFO - TEST-END: test_click.py TestClick.test_input_file1501061216478 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216479 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:26:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 142ms
02:26:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:26:56 INFO - 1501061216480 Marionette TRACE 388 <- [1,6,null,{}]
02:26:56 INFO - 1501061216483 Marionette DEBUG Closed connection 388
02:26:56 INFO - 1501061216484 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49848
02:26:56 INFO - 1501061216485 Marionette DEBUG Closed connection 389
02:26:56 INFO - 1501061216485 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49849
02:26:56 INFO - 1501061216487 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216487 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216489 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216493 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9118d0ff-9a8b-4374-b669-ee910869406f","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216495 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:26:56 INFO - 1501061216495 Marionette TRACE 390 <- [1,2,null,{}]
02:26:56 INFO - 1501061216496 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:56 INFO - 1501061216497 Marionette TRACE 390 <- [1,3,null,{}]
02:26:56 INFO - 1501061216498 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:56 INFO - 1501061216498 Marionette TRACE 390 <- [1,4,null,{}]
02:26:56 INFO - 1501061216500 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
02:26:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501061216503 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216504 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:26:56 INFO - 1501061216506 Marionette TRACE 390 <- [1,6,null,{}]
02:26:56 INFO - 1501061216508 Marionette DEBUG Closed connection 390
02:26:56 INFO - 1501061216509 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49850
02:26:56 INFO - 1501061216509 Marionette DEBUG Closed connection 391
02:26:56 INFO - 1501061216510 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49851
02:26:56 INFO - 1501061216511 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216512 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216514 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216517 Marionette TRACE 392 <- [1,1,null,{"sessionId":"367bbcb1-3566-465b-a47b-a5d0427e6d12","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216519 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:26:56 INFO - 1501061216521 Marionette TRACE 392 <- [1,2,null,{}]
02:26:56 INFO - 1501061216523 Marionette DEBUG Closed connection 392
02:26:56 INFO - 1501061216524 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49852
02:26:56 INFO - 1501061216524 Marionette DEBUG Closed connection 393
02:26:56 INFO - 1501061216525 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49853
02:26:56 INFO - 1501061216526 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:56 INFO - 1501061216527 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216534 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216538 Marionette TRACE 394 <- [1,1,null,{"sessionId":"541e2a27-85a7-4438-88b4-d913e2e3de3a","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216540 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
02:26:56 INFO - 1501061216542 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:26:56 INFO - 1501061216544 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:26:56 INFO - 1501061216544 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:26:56 INFO - 1501061216569 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"
02:26:56 INFO - 1501061216571 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"
02:26:56 INFO - 1501061216575 Marionette TRACE 394 <- [1,2,null,{}]
02:26:56 INFO - 1501061216576 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:26:56 INFO - 1501061216578 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8393c61a-b578-4aa0-8043-ce2f72fa5533","ELEMENT":"8393c61a-b578-4aa0-8043-ce2f72fa5533"}}]
02:26:56 INFO - 1501061216586 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:26:56 INFO - 1501061216589 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"483cbd05-8963-4863-b76f-6b1af52eae1c","ELEMENT":"483cbd05-8963-4863-b76f-6b1af52eae1c"}}]
02:26:56 INFO - 1501061216591 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8393c61a-b578-4aa0-8043-ce2f72fa5533"}]
02:26:56 INFO - 1501061216830 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:26:56 INFO - 1501061216831 Marionette TRACE 394 <- [1,5,null,{}]
02:26:56 INFO - 1501061216833 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"483cbd05-8963-4863-b76f-6b1af52eae1c"}]
02:26:56 INFO - 1501061216850 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
02:26:56 INFO - 1501061216852 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:26:56 INFO - 1501061216855 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:26:56 INFO - 1501061216856 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
02:26:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501061216859 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:26:56 INFO - 1501061216860 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:26:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 381ms
02:26:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:26:56 INFO - 1501061216862 Marionette TRACE 394 <- [1,9,null,{}]
02:26:56 INFO - 1501061216864 Marionette DEBUG Closed connection 394
02:26:56 INFO - 1501061216865 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49854
02:26:56 INFO - 1501061216866 Marionette DEBUG Closed connection 395
02:26:56 INFO - 1501061216867 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49855
02:26:56 INFO - 1501061216868 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216868 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216870 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216874 Marionette TRACE 396 <- [1,1,null,{"sessionId":"47525390-3cf3-45b9-94be-75311bc2e5e4","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216876 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:26:56 INFO - 1501061216876 Marionette TRACE 396 <- [1,2,null,{}]
02:26:56 INFO - 1501061216877 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:26:56 INFO - 1501061216878 Marionette TRACE 396 <- [1,3,null,{}]
02:26:56 INFO - 1501061216879 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:26:56 INFO - 1501061216879 Marionette TRACE 396 <- [1,4,null,{}]
02:26:56 INFO - 1501061216881 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
02:26:56 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501061216884 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:26:56 INFO - 1501061216885 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:26:56 INFO - 1501061216886 Marionette TRACE 396 <- [1,6,null,{}]
02:26:56 INFO - 1501061216889 Marionette DEBUG Closed connection 396
02:26:56 INFO - 1501061216889 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49856
02:26:56 INFO - 1501061216890 Marionette DEBUG Closed connection 397
02:26:56 INFO - 1501061216891 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49857
02:26:56 INFO - 1501061216892 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:26:56 INFO - 1501061216893 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216895 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216898 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f3d7ea86-8078-4b11-a50b-cba156879800","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216901 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:26:56 INFO - 1501061216902 Marionette TRACE 398 <- [1,2,null,{}]
02:26:56 INFO - 1501061216905 Marionette DEBUG Closed connection 398
02:26:56 INFO - 1501061216906 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49858
02:26:56 INFO - 1501061216906 Marionette DEBUG Closed connection 399
02:26:56 INFO - 1501061216907 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49859
02:26:56 INFO - 1501061216908 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:26:56 INFO - 1501061216909 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}
02:26:56 INFO - 1501061216911 Marionette DEBUG Register listener.js for window 12
02:26:56 INFO - 1501061216915 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8c8cf261-0cd9-48c5-9ba2-494a849440c4","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_6gmlo.mozrunner","moz:accessibilityChecks":false}}]
02:26:56 INFO - 1501061216917 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
02:26:56 INFO - 1501061216919 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"
02:26:56 INFO - 1501061216921 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"
02:26:56 INFO - 1501061216921 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"
02:26:56 INFO - 1501061216943 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"
02:26:56 INFO - 1501061216945 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"
02:26:56 INFO - 1501061216956 Marionette TRACE 400 <- [1,2,null,{}]
02:26:56 INFO - 1501061216957 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:26:56 INFO - 1501061216960 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf8efff8-0d5c-4d8e-a723-a31df5862c91","ELEMENT":"cf8efff8-0d5c-4d8e-a723-a31df5862c91"}}]
02:26:56 INFO - 1501061216961 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cf8efff8-0d5c-4d8e-a723-a31df5862c91"}]
02:26:56 INFO - 1501061216986 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:26:56 INFO - 1501061216987 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"cf8efff8-0d5c-4d8e-a723-a31df5862c91"}]
02:26:57 INFO - 1501061217009 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element