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]
03:07:03 INFO - 1500977223702 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}]
03:07:03 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500977223705 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:07:03 INFO - 1500977223706 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:07:03 INFO - 1500977223708 Marionette TRACE 352 <- [1,6,null,{}]
03:07:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 210ms
03:07:03 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:07:03 INFO - 1500977223711 Marionette DEBUG Closed connection 352
03:07:03 INFO - 1500977223712 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49814
03:07:03 INFO - 1500977223713 Marionette DEBUG Closed connection 353
03:07:03 INFO - 1500977223713 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49815
03:07:03 INFO - 1500977223715 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:03 INFO - 1500977223715 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:03 INFO - 1500977223718 Marionette DEBUG Register listener.js for window 12
03:07:03 INFO - 1500977223722 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7882d3a5-2895-42eb-9b0f-1c6e87b478a3","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:03 INFO - 1500977223724 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:07:03 INFO - 1500977223725 Marionette TRACE 354 <- [1,2,null,{}]
03:07:03 INFO - 1500977223726 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:03 INFO - 1500977223727 Marionette TRACE 354 <- [1,3,null,{}]
03:07:03 INFO - 1500977223727 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:03 INFO - 1500977223728 Marionette TRACE 354 <- [1,4,null,{}]
03:07:03 INFO - 1500977223730 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}]
03:07:03 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500977223733 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:07:03 INFO - 1500977223734 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:07:03 INFO - 1500977223735 Marionette TRACE 354 <- [1,6,null,{}]
03:07:03 INFO - 1500977223738 Marionette DEBUG Closed connection 354
03:07:03 INFO - 1500977223738 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49816
03:07:03 INFO - 1500977223739 Marionette DEBUG Closed connection 355
03:07:03 INFO - 1500977223740 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49817
03:07:03 INFO - 1500977223741 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:03 INFO - 1500977223741 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:03 INFO - 1500977223744 Marionette DEBUG Register listener.js for window 12
03:07:03 INFO - 1500977223747 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4b80fbdd-c4a8-425e-ad21-60126ecc2b51","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:03 INFO - 1500977223750 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:07:03 INFO - 1500977223751 Marionette TRACE 356 <- [1,2,null,{}]
03:07:03 INFO - 1500977223753 Marionette DEBUG Closed connection 356
03:07:03 INFO - 1500977223754 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49818
03:07:03 INFO - 1500977223755 Marionette DEBUG Closed connection 357
03:07:03 INFO - 1500977223756 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49819
03:07:03 INFO - 1500977223757 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:03 INFO - 1500977223758 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:03 INFO - 1500977223760 Marionette DEBUG Register listener.js for window 12
03:07:03 INFO - 1500977223763 Marionette TRACE 358 <- [1,1,null,{"sessionId":"f20861c3-5324-4344-a5fb-1ccebd1ea400","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:03 INFO - 1500977223766 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49379/clicks.html"}]
03:07:03 INFO - 1500977223783 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/hidden.html"
03:07:03 INFO - 1500977223790 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/hidden.html"
03:07:03 INFO - 1500977223790 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/hidden.html"
03:07:03 INFO - 1500977223819 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49379/clicks.html"
03:07:03 INFO - 1500977223821 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49379/clicks.html"
03:07:03 INFO - 1500977223826 Marionette TRACE 358 <- [1,2,null,{}]
03:07:03 INFO - 1500977223827 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:07:03 INFO - 1500977223838 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5db898f6-477c-4b62-847e-c0effdde6816","ELEMENT":"5db898f6-477c-4b62-847e-c0effdde6816"}}]
03:07:03 INFO - 1500977223856 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5db898f6-477c-4b62-847e-c0effdde6816"}]
03:07:03 INFO - 1500977223902 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/clicks.html"
03:07:03 INFO - 1500977223911 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/clicks.html"
03:07:03 INFO - 1500977223912 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/clicks.html"
03:07:03 INFO - 1500977223971 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49379/test.html"
03:07:03 INFO - 1500977223975 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49379/test.html"
03:07:03 INFO - 1500977223980 Marionette TRACE 358 <- [1,4,null,{}]
03:07:04 INFO - 1500977223990 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:07:04 INFO - 1500977223993 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"67708c65-cb9f-4ba2-82f4-79bfa47c6f24","ELEMENT":"67708c65-cb9f-4ba2-82f4-79bfa47c6f24"}}]
03:07:04 INFO - 1500977223994 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:07:04 INFO - 1500977223995 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:07:04 INFO - 1500977223997 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}]
03:07:04 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500977224001 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:07:04 INFO - 1500977224023 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:07:04 INFO - 1500977224025 Marionette TRACE 358 <- [1,8,null,{}]
03:07:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 316ms
03:07:04 INFO - TEST-START | test_click.py TestClick.test_container_element
03:07:04 INFO - 1500977224027 Marionette DEBUG Closed connection 358
03:07:04 INFO - 1500977224032 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49822
03:07:04 INFO - 1500977224033 Marionette DEBUG Closed connection 359
03:07:04 INFO - 1500977224034 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49823
03:07:04 INFO - 1500977224048 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224049 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224052 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224056 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ab90e076-ab40-420c-ba2e-3f9d21d818b3","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224058 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:07:04 INFO - 1500977224059 Marionette TRACE 360 <- [1,2,null,{}]
03:07:04 INFO - 1500977224060 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:04 INFO - 1500977224061 Marionette TRACE 360 <- [1,3,null,{}]
03:07:04 INFO - 1500977224064 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:04 INFO - 1500977224065 Marionette TRACE 360 <- [1,4,null,{}]
03:07:04 INFO - 1500977224067 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}]
03:07:04 INFO - TEST-START: test_click.py TestClick.test_container_element1500977224071 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:07:04 INFO - 1500977224073 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:07:04 INFO - 1500977224075 Marionette TRACE 360 <- [1,6,null,{}]
03:07:04 INFO - 1500977224077 Marionette DEBUG Closed connection 360
03:07:04 INFO - 1500977224078 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49824
03:07:04 INFO - 1500977224079 Marionette DEBUG Closed connection 361
03:07:04 INFO - 1500977224080 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49825
03:07:04 INFO - 1500977224081 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224081 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224084 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224088 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e5e4ade2-7610-4d5d-9dd3-e357c4777505","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224090 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:07:04 INFO - 1500977224092 Marionette TRACE 362 <- [1,2,null,{}]
03:07:04 INFO - 1500977224094 Marionette DEBUG Closed connection 362
03:07:04 INFO - 1500977224095 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49826
03:07:04 INFO - 1500977224096 Marionette DEBUG Closed connection 363
03:07:04 INFO - 1500977224097 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49827
03:07:04 INFO - 1500977224098 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:04 INFO - 1500977224098 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224101 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224105 Marionette TRACE 364 <- [1,1,null,{"sessionId":"bc2bd085-b2b9-465e-9446-8ef62b9d662e","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224107 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"}]
03:07:04 INFO - 1500977224108 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/test.html"
03:07:04 INFO - 1500977224111 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/test.html"
03:07:04 INFO - 1500977224112 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/test.html"
03:07:04 INFO - 1500977224140 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:07:04 INFO - 1500977224144 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:07:04 INFO - 1500977224156 Marionette TRACE 364 <- [1,2,null,{}]
03:07:04 INFO - 1500977224158 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:07:04 INFO - 1500977224161 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ed6ea61-7ab3-4c9f-9486-30eab38420e1","ELEMENT":"0ed6ea61-7ab3-4c9f-9486-30eab38420e1"}}]
03:07:04 INFO - 1500977224162 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0ed6ea61-7ab3-4c9f-9486-30eab38420e1"}]
03:07:04 INFO - 1500977224419 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:07:04 INFO - 1500977224420 Marionette TRACE 364 <- [1,4,null,{}]
03:07:04 INFO - 1500977224421 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0ed6ea61-7ab3-4c9f-9486-30eab38420e1","name":"selected"}]
03:07:04 INFO - 1500977224426 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:07:04 INFO - 1500977224428 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}]
03:07:04 INFO - TEST-END: test_click.py TestClick.test_container_element1500977224431 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:07:04 INFO - 1500977224432 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:07:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 408ms
03:07:04 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:07:04 INFO - 1500977224434 Marionette TRACE 364 <- [1,7,null,{}]
03:07:04 INFO - 1500977224436 Marionette DEBUG Closed connection 364
03:07:04 INFO - 1500977224437 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49828
03:07:04 INFO - 1500977224438 Marionette DEBUG Closed connection 365
03:07:04 INFO - 1500977224439 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49829
03:07:04 INFO - 1500977224440 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224441 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224444 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224447 Marionette TRACE 366 <- [1,1,null,{"sessionId":"85412ebb-35e8-4d12-8d86-6e30304fc067","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224450 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:07:04 INFO - 1500977224450 Marionette TRACE 366 <- [1,2,null,{}]
03:07:04 INFO - 1500977224451 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:04 INFO - 1500977224452 Marionette TRACE 366 <- [1,3,null,{}]
03:07:04 INFO - 1500977224453 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:04 INFO - 1500977224454 Marionette TRACE 366 <- [1,4,null,{}]
03:07:04 INFO - 1500977224455 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}]
03:07:04 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500977224458 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:07:04 INFO - 1500977224459 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:07:04 INFO - 1500977224461 Marionette TRACE 366 <- [1,6,null,{}]
03:07:04 INFO - 1500977224463 Marionette DEBUG Closed connection 366
03:07:04 INFO - 1500977224464 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49830
03:07:04 INFO - 1500977224465 Marionette DEBUG Closed connection 367
03:07:04 INFO - 1500977224466 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49831
03:07:04 INFO - 1500977224467 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224467 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224470 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224473 Marionette TRACE 368 <- [1,1,null,{"sessionId":"32d7614b-f453-4b13-9799-bd56e18904c0","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224475 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:07:04 INFO - 1500977224477 Marionette TRACE 368 <- [1,2,null,{}]
03:07:04 INFO - 1500977224479 Marionette DEBUG Closed connection 368
03:07:04 INFO - 1500977224480 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49832
03:07:04 INFO - 1500977224481 Marionette DEBUG Closed connection 369
03:07:04 INFO - 1500977224481 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49833
03:07:04 INFO - 1500977224483 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:04 INFO - 1500977224483 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224486 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224489 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a5492caa-f93b-4874-bc17-d5d5ff599b6c","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224491 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"}]
03:07:04 INFO - 1500977224494 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:07:04 INFO - 1500977224497 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:07:04 INFO - 1500977224498 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:07:04 INFO - 1500977224528 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:07:04 INFO - 1500977224529 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:07:04 INFO - 1500977224541 Marionette TRACE 370 <- [1,2,null,{}]
03:07:04 INFO - 1500977224542 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:07:04 INFO - 1500977224545 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c65a0de3-f091-40f6-942b-72ce5862d63c","ELEMENT":"c65a0de3-f091-40f6-942b-72ce5862d63c"}}]
03:07:04 INFO - 1500977224547 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c65a0de3-f091-40f6-942b-72ce5862d63c"}]
03:07:04 INFO - 1500977224794 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:07:04 INFO - 1500977224795 Marionette TRACE 370 <- [1,4,null,{}]
03:07:04 INFO - 1500977224796 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c65a0de3-f091-40f6-942b-72ce5862d63c","name":"selected"}]
03:07:04 INFO - 1500977224802 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:07:04 INFO - 1500977224804 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}]
03:07:04 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500977224807 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:07:04 INFO - 1500977224808 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:07:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 376ms
03:07:04 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:07:04 INFO - 1500977224810 Marionette TRACE 370 <- [1,7,null,{}]
03:07:04 INFO - 1500977224812 Marionette DEBUG Closed connection 370
03:07:04 INFO - 1500977224813 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49834
03:07:04 INFO - 1500977224814 Marionette DEBUG Closed connection 371
03:07:04 INFO - 1500977224815 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49835
03:07:04 INFO - 1500977224816 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224817 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224820 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224823 Marionette TRACE 372 <- [1,1,null,{"sessionId":"6a4a5474-5a3c-4e03-b5e5-62cc299bbeba","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224825 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:07:04 INFO - 1500977224826 Marionette TRACE 372 <- [1,2,null,{}]
03:07:04 INFO - 1500977224827 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:04 INFO - 1500977224828 Marionette TRACE 372 <- [1,3,null,{}]
03:07:04 INFO - 1500977224829 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:04 INFO - 1500977224829 Marionette TRACE 372 <- [1,4,null,{}]
03:07:04 INFO - 1500977224831 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}]
03:07:04 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500977224834 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:07:04 INFO - 1500977224835 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:07:04 INFO - 1500977224836 Marionette TRACE 372 <- [1,6,null,{}]
03:07:04 INFO - 1500977224838 Marionette DEBUG Closed connection 372
03:07:04 INFO - 1500977224839 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49836
03:07:04 INFO - 1500977224840 Marionette DEBUG Closed connection 373
03:07:04 INFO - 1500977224841 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49837
03:07:04 INFO - 1500977224842 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:04 INFO - 1500977224843 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224845 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224850 Marionette TRACE 374 <- [1,1,null,{"sessionId":"253904f9-1ce9-463b-a16d-53d6758daa1a","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224852 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:07:04 INFO - 1500977224854 Marionette TRACE 374 <- [1,2,null,{}]
03:07:04 INFO - 1500977224856 Marionette DEBUG Closed connection 374
03:07:04 INFO - 1500977224857 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49838
03:07:04 INFO - 1500977224858 Marionette DEBUG Closed connection 375
03:07:04 INFO - 1500977224858 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49839
03:07:04 INFO - 1500977224860 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:04 INFO - 1500977224860 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:04 INFO - 1500977224863 Marionette DEBUG Register listener.js for window 12
03:07:04 INFO - 1500977224866 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a32785fd-1a41-42f7-ace7-80aa825007f4","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:04 INFO - 1500977224869 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"}]
03:07:04 INFO - 1500977224870 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:07:04 INFO - 1500977224873 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:07:04 INFO - 1500977224873 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:07:04 INFO - 1500977224898 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:07:04 INFO - 1500977224900 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:07:04 INFO - 1500977224904 Marionette TRACE 376 <- [1,2,null,{}]
03:07:04 INFO - 1500977224906 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:07:04 INFO - 1500977224909 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6321ebf6-b967-4415-a5f8-faaf3f93565b","ELEMENT":"6321ebf6-b967-4415-a5f8-faaf3f93565b"}}]
03:07:04 INFO - 1500977224910 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6321ebf6-b967-4415-a5f8-faaf3f93565b"}]
03:07:05 INFO - 1500977225162 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:07:05 INFO - 1500977225163 Marionette TRACE 376 <- [1,4,null,{}]
03:07:05 INFO - 1500977225165 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}]
03:07:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500977225168 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225169 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:07:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 361ms
03:07:05 INFO - 1500977225171 Marionette TRACE 376 <- [1,6,null,{}]
03:07:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:07:05 INFO - 1500977225173 Marionette DEBUG Closed connection 376
03:07:05 INFO - 1500977225175 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49840
03:07:05 INFO - 1500977225176 Marionette DEBUG Closed connection 377
03:07:05 INFO - 1500977225176 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49841
03:07:05 INFO - 1500977225178 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225178 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225182 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225194 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4ba3c955-521c-47a3-97ca-2b465699b2e8","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225197 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:07:05 INFO - 1500977225197 Marionette TRACE 378 <- [1,2,null,{}]
03:07:05 INFO - 1500977225198 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:05 INFO - 1500977225199 Marionette TRACE 378 <- [1,3,null,{}]
03:07:05 INFO - 1500977225200 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:05 INFO - 1500977225200 Marionette TRACE 378 <- [1,4,null,{}]
03:07:05 INFO - 1500977225202 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}]
03:07:05 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500977225206 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225207 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:07:05 INFO - 1500977225208 Marionette TRACE 378 <- [1,6,null,{}]
03:07:05 INFO - 1500977225211 Marionette DEBUG Closed connection 378
03:07:05 INFO - 1500977225212 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49842
03:07:05 INFO - 1500977225212 Marionette DEBUG Closed connection 379
03:07:05 INFO - 1500977225213 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49843
03:07:05 INFO - 1500977225214 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225215 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225218 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225221 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ac09aecb-e6c7-4c1a-97e1-46787606f4ca","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225223 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:07:05 INFO - 1500977225225 Marionette TRACE 380 <- [1,2,null,{}]
03:07:05 INFO - 1500977225227 Marionette DEBUG Closed connection 380
03:07:05 INFO - 1500977225228 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49844
03:07:05 INFO - 1500977225229 Marionette DEBUG Closed connection 381
03:07:05 INFO - 1500977225229 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49845
03:07:05 INFO - 1500977225230 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:05 INFO - 1500977225231 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225234 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225240 Marionette TRACE 382 <- [1,1,null,{"sessionId":"cb8f371c-4f72-4f50-95a0-7404abf0cfcc","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225291 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"}]
03:07:05 INFO - 1500977225294 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:07:05 INFO - 1500977225297 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:07:05 INFO - 1500977225297 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:07:05 INFO - 1500977225312 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:07:05 INFO - 1500977225323 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:07:05 INFO - 1500977225328 Marionette TRACE 382 <- [1,2,null,{}]
03:07:05 INFO - 1500977225344 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:07:05 INFO - 1500977225348 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14f29847-0182-4c57-acd0-8e354aa25693","ELEMENT":"14f29847-0182-4c57-acd0-8e354aa25693"}}]
03:07:05 INFO - 1500977225349 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"14f29847-0182-4c57-acd0-8e354aa25693"}]
03:07:05 INFO - 1500977225595 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:07:05 INFO - 1500977225596 Marionette TRACE 382 <- [1,4,null,{}]
03:07:05 INFO - 1500977225598 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}]
03:07:05 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500977225601 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225602 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:07:05 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 432ms
03:07:05 INFO - 1500977225604 Marionette TRACE 382 <- [1,6,null,{}]
03:07:05 INFO - TEST-START | test_click.py TestClick.test_input_file
03:07:05 INFO - 1500977225606 Marionette DEBUG Closed connection 382
03:07:05 INFO - 1500977225608 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49846
03:07:05 INFO - 1500977225609 Marionette DEBUG Closed connection 383
03:07:05 INFO - 1500977225609 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49847
03:07:05 INFO - 1500977225610 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225611 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225614 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225618 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c8afe18d-8da3-42b7-a61d-fc0c7d9ce23e","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225620 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:07:05 INFO - 1500977225621 Marionette TRACE 384 <- [1,2,null,{}]
03:07:05 INFO - 1500977225622 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:05 INFO - 1500977225622 Marionette TRACE 384 <- [1,3,null,{}]
03:07:05 INFO - 1500977225623 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:05 INFO - 1500977225624 Marionette TRACE 384 <- [1,4,null,{}]
03:07:05 INFO - 1500977225626 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}]
03:07:05 INFO - TEST-START: test_click.py TestClick.test_input_file1500977225629 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225630 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:07:05 INFO - 1500977225631 Marionette TRACE 384 <- [1,6,null,{}]
03:07:05 INFO - 1500977225633 Marionette DEBUG Closed connection 384
03:07:05 INFO - 1500977225634 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49848
03:07:05 INFO - 1500977225635 Marionette DEBUG Closed connection 385
03:07:05 INFO - 1500977225636 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49849
03:07:05 INFO - 1500977225637 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225638 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225641 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225644 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b4799f5c-0151-4d6e-a21d-3a64cc032f62","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225647 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:07:05 INFO - 1500977225648 Marionette TRACE 386 <- [1,2,null,{}]
03:07:05 INFO - 1500977225651 Marionette DEBUG Closed connection 386
03:07:05 INFO - 1500977225651 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49850
03:07:05 INFO - 1500977225652 Marionette DEBUG Closed connection 387
03:07:05 INFO - 1500977225653 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49851
03:07:05 INFO - 1500977225654 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:05 INFO - 1500977225655 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225658 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225661 Marionette TRACE 388 <- [1,1,null,{"sessionId":"cd61390e-7ff4-4308-8417-9674e3f6907e","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225663 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:07:05 INFO - 1500977225665 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:07:05 INFO - 1500977225668 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:07:05 INFO - 1500977225669 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:07:05 INFO - 1500977225691 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:07:05 INFO - 1500977225694 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:07:05 INFO - 1500977225705 Marionette TRACE 388 <- [1,2,null,{}]
03:07:05 INFO - 1500977225706 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:07:05 INFO - 1500977225709 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81d7dfab-7724-43bb-9133-663899ecb2b2","ELEMENT":"81d7dfab-7724-43bb-9133-663899ecb2b2"}}]
03:07:05 INFO - 1500977225710 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"81d7dfab-7724-43bb-9133-663899ecb2b2"}]
03:07:05 INFO - 1500977225740 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]
03:07:05 INFO - 1500977225742 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}]
03:07:05 INFO - TEST-END: test_click.py TestClick.test_input_file1500977225745 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225746 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:07:05 INFO - 1500977225748 Marionette TRACE 388 <- [1,6,null,{}]
03:07:05 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
03:07:05 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:07:05 INFO - 1500977225751 Marionette DEBUG Closed connection 388
03:07:05 INFO - 1500977225752 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49852
03:07:05 INFO - 1500977225753 Marionette DEBUG Closed connection 389
03:07:05 INFO - 1500977225753 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49853
03:07:05 INFO - 1500977225755 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225755 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225758 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225762 Marionette TRACE 390 <- [1,1,null,{"sessionId":"abfacb72-fb03-4c55-ac56-f00e9978369d","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225764 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:07:05 INFO - 1500977225765 Marionette TRACE 390 <- [1,2,null,{}]
03:07:05 INFO - 1500977225766 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:05 INFO - 1500977225766 Marionette TRACE 390 <- [1,3,null,{}]
03:07:05 INFO - 1500977225767 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:05 INFO - 1500977225768 Marionette TRACE 390 <- [1,4,null,{}]
03:07:05 INFO - 1500977225770 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}]
03:07:05 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500977225773 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:07:05 INFO - 1500977225774 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:07:05 INFO - 1500977225775 Marionette TRACE 390 <- [1,6,null,{}]
03:07:05 INFO - 1500977225777 Marionette DEBUG Closed connection 390
03:07:05 INFO - 1500977225778 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49854
03:07:05 INFO - 1500977225780 Marionette DEBUG Closed connection 391
03:07:05 INFO - 1500977225781 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49855
03:07:05 INFO - 1500977225782 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:05 INFO - 1500977225790 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225793 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225797 Marionette TRACE 392 <- [1,1,null,{"sessionId":"70b1f501-1999-4a6f-9ee7-9fa7373aaabb","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225799 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:07:05 INFO - 1500977225801 Marionette TRACE 392 <- [1,2,null,{}]
03:07:05 INFO - 1500977225803 Marionette DEBUG Closed connection 392
03:07:05 INFO - 1500977225804 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49858
03:07:05 INFO - 1500977225805 Marionette DEBUG Closed connection 393
03:07:05 INFO - 1500977225806 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49859
03:07:05 INFO - 1500977225807 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:05 INFO - 1500977225808 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:05 INFO - 1500977225811 Marionette DEBUG Register listener.js for window 12
03:07:05 INFO - 1500977225814 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3c512652-757b-48a1-ab8c-744565e6b634","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:05 INFO - 1500977225816 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"}]
03:07:05 INFO - 1500977225818 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:07:05 INFO - 1500977225821 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:07:05 INFO - 1500977225821 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:07:05 INFO - 1500977225849 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:07:05 INFO - 1500977225851 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:07:05 INFO - 1500977225855 Marionette TRACE 394 <- [1,2,null,{}]
03:07:05 INFO - 1500977225857 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:07:05 INFO - 1500977225860 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c757ab0f-21b7-4cc8-803d-6d8b53f4603c","ELEMENT":"c757ab0f-21b7-4cc8-803d-6d8b53f4603c"}}]
03:07:05 INFO - 1500977225861 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:07:05 INFO - 1500977225872 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"520122d7-20f5-4631-a94a-4517157df5bf","ELEMENT":"520122d7-20f5-4631-a94a-4517157df5bf"}}]
03:07:05 INFO - 1500977225874 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c757ab0f-21b7-4cc8-803d-6d8b53f4603c"}]
03:07:06 INFO - 1500977226120 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:07:06 INFO - 1500977226121 Marionette TRACE 394 <- [1,5,null,{}]
03:07:06 INFO - 1500977226122 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"520122d7-20f5-4631-a94a-4517157df5bf"}]
03:07:06 INFO - 1500977226141 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]
03:07:06 INFO - 1500977226143 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:07:06 INFO - 1500977226146 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:07:06 INFO - 1500977226148 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}]
03:07:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500977226151 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:07:06 INFO - 1500977226152 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:07:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 405ms
03:07:06 INFO - 1500977226154 Marionette TRACE 394 <- [1,9,null,{}]
03:07:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:07:06 INFO - 1500977226156 Marionette DEBUG Closed connection 394
03:07:06 INFO - 1500977226158 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49860
03:07:06 INFO - 1500977226158 Marionette DEBUG Closed connection 395
03:07:06 INFO - 1500977226159 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49861
03:07:06 INFO - 1500977226160 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:06 INFO - 1500977226161 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:06 INFO - 1500977226164 Marionette DEBUG Register listener.js for window 12
03:07:06 INFO - 1500977226168 Marionette TRACE 396 <- [1,1,null,{"sessionId":"3ab4f5b4-3864-47af-912d-f783d7a7b515","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:06 INFO - 1500977226170 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:07:06 INFO - 1500977226171 Marionette TRACE 396 <- [1,2,null,{}]
03:07:06 INFO - 1500977226172 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:07:06 INFO - 1500977226172 Marionette TRACE 396 <- [1,3,null,{}]
03:07:06 INFO - 1500977226173 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:07:06 INFO - 1500977226174 Marionette TRACE 396 <- [1,4,null,{}]
03:07:06 INFO - 1500977226176 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}]
03:07:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500977226179 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:07:06 INFO - 1500977226180 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:07:06 INFO - 1500977226181 Marionette TRACE 396 <- [1,6,null,{}]
03:07:06 INFO - 1500977226184 Marionette DEBUG Closed connection 396
03:07:06 INFO - 1500977226184 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49862
03:07:06 INFO - 1500977226185 Marionette DEBUG Closed connection 397
03:07:06 INFO - 1500977226186 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49863
03:07:06 INFO - 1500977226187 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:07:06 INFO - 1500977226188 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:06 INFO - 1500977226190 Marionette DEBUG Register listener.js for window 12
03:07:06 INFO - 1500977226194 Marionette TRACE 398 <- [1,1,null,{"sessionId":"53b23f2c-bf2b-4950-b9e7-fdea1262e3e1","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:06 INFO - 1500977226196 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:07:06 INFO - 1500977226197 Marionette TRACE 398 <- [1,2,null,{}]
03:07:06 INFO - 1500977226200 Marionette DEBUG Closed connection 398
03:07:06 INFO - 1500977226200 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49864
03:07:06 INFO - 1500977226201 Marionette DEBUG Closed connection 399
03:07:06 INFO - 1500977226202 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49865
03:07:06 INFO - 1500977226203 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:07:06 INFO - 1500977226204 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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}
03:07:06 INFO - 1500977226206 Marionette DEBUG Register listener.js for window 12
03:07:06 INFO - 1500977226211 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6cbca7e2-a71d-4158-829d-12b499344a90","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":1416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2slvuv.mozrunner","moz:accessibilityChecks":false}}]
03:07:06 INFO - 1500977226214 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"}]
03:07:06 INFO - 1500977226215 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:07:06 INFO - 1500977226218 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:07:06 INFO - 1500977226218 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:07:06 INFO - 1500977226244 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:07:06 INFO - 1500977226245 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:07:06 INFO - 1500977226256 Marionette TRACE 400 <- [1,2,null,{}]
03:07:06 INFO - 1500977226257 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:07:06 INFO - 1500977226260 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"95624de8-0c27-4344-be07-589419220dcf","ELEMENT":"95624de8-0c27-4344-be07-589419220dcf"}}]
03:07:06 INFO - 1500977226262 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"95624de8-0c27-4344-be07-589419220dcf"}]
03:07:06 INFO - 1500977226288 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:07:06 INFO - 1500977226289 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"95624de8-0c27-4344-be07-589419220dcf"}]
03:07:06 INFO - 1500977226308 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element