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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:20:08 INFO - 1499422808880 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:20:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499422808883 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:20:08 INFO - 1499422808885 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:20:08 INFO - 1499422808886 Marionette TRACE 352 <- [1,6,null,{}]
03:20:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 223ms
03:20:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:20:08 INFO - 1499422808890 Marionette DEBUG Closed connection 352
03:20:08 INFO - 1499422808891 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49775
03:20:08 INFO - 1499422808893 Marionette DEBUG Closed connection 353
03:20:08 INFO - 1499422808894 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49776
03:20:08 INFO - 1499422808895 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:08 INFO - 1499422808895 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:08 INFO - 1499422808899 Marionette DEBUG Register listener.js for window 12
03:20:08 INFO - 1499422808902 Marionette TRACE 354 <- [1,1,null,{"sessionId":"dc2f766c-8f38-429a-a1d0-13e57f9dd2a5","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:08 INFO - 1499422808904 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:20:08 INFO - 1499422808905 Marionette TRACE 354 <- [1,2,null,{}]
03:20:08 INFO - 1499422808906 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:08 INFO - 1499422808906 Marionette TRACE 354 <- [1,3,null,{}]
03:20:08 INFO - 1499422808908 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:08 INFO - 1499422808908 Marionette TRACE 354 <- [1,4,null,{}]
03:20:08 INFO - 1499422808910 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:20:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499422808913 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:20:08 INFO - 1499422808914 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:20:08 INFO - 1499422808915 Marionette TRACE 354 <- [1,6,null,{}]
03:20:08 INFO - 1499422808918 Marionette DEBUG Closed connection 354
03:20:08 INFO - 1499422808919 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49777
03:20:08 INFO - 1499422808919 Marionette DEBUG Closed connection 355
03:20:08 INFO - 1499422808920 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49778
03:20:08 INFO - 1499422808921 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:08 INFO - 1499422808922 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:08 INFO - 1499422808925 Marionette DEBUG Register listener.js for window 12
03:20:08 INFO - 1499422808928 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4073a980-8710-4701-929f-354b52626112","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:08 INFO - 1499422808931 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:20:08 INFO - 1499422808932 Marionette TRACE 356 <- [1,2,null,{}]
03:20:08 INFO - 1499422808934 Marionette DEBUG Closed connection 356
03:20:08 INFO - 1499422808935 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49779
03:20:08 INFO - 1499422808936 Marionette DEBUG Closed connection 357
03:20:08 INFO - 1499422808937 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49780
03:20:08 INFO - 1499422808938 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:08 INFO - 1499422808938 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:08 INFO - 1499422808941 Marionette DEBUG Register listener.js for window 12
03:20:08 INFO - 1499422808944 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c5a5b949-6edb-4d36-ab02-1421bed4328c","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:08 INFO - 1499422808948 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49342/clicks.html"}]
03:20:08 INFO - 1499422808950 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/hidden.html"
03:20:08 INFO - 1499422808956 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/hidden.html"
03:20:08 INFO - 1499422808956 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/hidden.html"
03:20:08 INFO - 1499422808984 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49342/clicks.html"
03:20:08 INFO - 1499422808986 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49342/clicks.html"
03:20:09 INFO - 1499422808990 Marionette TRACE 358 <- [1,2,null,{}]
03:20:09 INFO - 1499422809001 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:20:09 INFO - 1499422809004 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"30008b61-f7b8-4ac8-b744-199ea2ba011d","ELEMENT":"30008b61-f7b8-4ac8-b744-199ea2ba011d"}}]
03:20:09 INFO - 1499422809005 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"30008b61-f7b8-4ac8-b744-199ea2ba011d"}]
03:20:09 INFO - 1499422809051 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/clicks.html"
03:20:09 INFO - 1499422809064 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/clicks.html"
03:20:09 INFO - 1499422809064 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/clicks.html"
03:20:09 INFO - 1499422809101 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49342/test.html"
03:20:09 INFO - 1499422809106 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49342/test.html"
03:20:09 INFO - 1499422809110 Marionette TRACE 358 <- [1,4,null,{}]
03:20:09 INFO - 1499422809122 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:20:09 INFO - 1499422809125 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c448813a-17c0-4d49-9ada-6abc3b06fb93","ELEMENT":"c448813a-17c0-4d49-9ada-6abc3b06fb93"}}]
03:20:09 INFO - 1499422809127 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:20:09 INFO - 1499422809128 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:20:09 INFO - 1499422809130 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:20:09 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499422809133 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:20:09 INFO - 1499422809134 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:20:09 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 249ms
03:20:09 INFO - TEST-START | test_click.py TestClick.test_container_element
03:20:09 INFO - 1499422809136 Marionette TRACE 358 <- [1,8,null,{}]
03:20:09 INFO - 1499422809138 Marionette DEBUG Closed connection 358
03:20:09 INFO - 1499422809139 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49783
03:20:09 INFO - 1499422809140 Marionette DEBUG Closed connection 359
03:20:09 INFO - 1499422809140 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49784
03:20:09 INFO - 1499422809142 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809142 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809145 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809148 Marionette TRACE 360 <- [1,1,null,{"sessionId":"320ecfc6-868d-4ec7-a701-7705a2e86205","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809152 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:20:09 INFO - 1499422809153 Marionette TRACE 360 <- [1,2,null,{}]
03:20:09 INFO - 1499422809154 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:09 INFO - 1499422809154 Marionette TRACE 360 <- [1,3,null,{}]
03:20:09 INFO - 1499422809155 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:09 INFO - 1499422809156 Marionette TRACE 360 <- [1,4,null,{}]
03:20:09 INFO - 1499422809158 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:20:09 INFO - TEST-START: test_click.py TestClick.test_container_element1499422809161 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:20:09 INFO - 1499422809162 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:20:09 INFO - 1499422809163 Marionette TRACE 360 <- [1,6,null,{}]
03:20:09 INFO - 1499422809165 Marionette DEBUG Closed connection 360
03:20:09 INFO - 1499422809166 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49785
03:20:09 INFO - 1499422809167 Marionette DEBUG Closed connection 361
03:20:09 INFO - 1499422809168 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49786
03:20:09 INFO - 1499422809169 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809169 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809172 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809175 Marionette TRACE 362 <- [1,1,null,{"sessionId":"76a17ee1-f117-4ba3-9507-fa427031c90f","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809177 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:20:09 INFO - 1499422809179 Marionette TRACE 362 <- [1,2,null,{}]
03:20:09 INFO - 1499422809181 Marionette DEBUG Closed connection 362
03:20:09 INFO - 1499422809182 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49787
03:20:09 INFO - 1499422809183 Marionette DEBUG Closed connection 363
03:20:09 INFO - 1499422809183 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49788
03:20:09 INFO - 1499422809184 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:09 INFO - 1499422809185 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809188 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809191 Marionette TRACE 364 <- [1,1,null,{"sessionId":"fe741192-3ce5-425b-b923-bc5ef075c014","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809193 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:20:09 INFO - 1499422809195 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/test.html"
03:20:09 INFO - 1499422809198 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/test.html"
03:20:09 INFO - 1499422809198 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/test.html"
03:20:09 INFO - 1499422809229 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:20:09 INFO - 1499422809231 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:20:09 INFO - 1499422809236 Marionette TRACE 364 <- [1,2,null,{}]
03:20:09 INFO - 1499422809238 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:20:09 INFO - 1499422809247 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"896742a2-5d7c-486e-ae16-1cd1d79ba385","ELEMENT":"896742a2-5d7c-486e-ae16-1cd1d79ba385"}}]
03:20:09 INFO - 1499422809251 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"896742a2-5d7c-486e-ae16-1cd1d79ba385"}]
03:20:09 INFO - 1499422809496 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:20:09 INFO - 1499422809497 Marionette TRACE 364 <- [1,4,null,{}]
03:20:09 INFO - 1499422809498 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"896742a2-5d7c-486e-ae16-1cd1d79ba385","name":"selected"}]
03:20:09 INFO - 1499422809503 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:20:09 INFO - 1499422809505 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:20:09 INFO - TEST-END: test_click.py TestClick.test_container_element1499422809508 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:20:09 INFO - 1499422809509 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:20:09 INFO - 1499422809510 Marionette TRACE 364 <- [1,7,null,{}]
03:20:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 375ms
03:20:09 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:20:09 INFO - 1499422809513 Marionette DEBUG Closed connection 364
03:20:09 INFO - 1499422809514 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49789
03:20:09 INFO - 1499422809515 Marionette DEBUG Closed connection 365
03:20:09 INFO - 1499422809515 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49790
03:20:09 INFO - 1499422809516 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809517 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809528 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809533 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d156a91b-4c06-44d0-92f6-93f712dd2609","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809535 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:20:09 INFO - 1499422809536 Marionette TRACE 366 <- [1,2,null,{}]
03:20:09 INFO - 1499422809537 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:09 INFO - 1499422809538 Marionette TRACE 366 <- [1,3,null,{}]
03:20:09 INFO - 1499422809538 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:09 INFO - 1499422809539 Marionette TRACE 366 <- [1,4,null,{}]
03:20:09 INFO - 1499422809541 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:20:09 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499422809545 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:20:09 INFO - 1499422809546 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:20:09 INFO - 1499422809547 Marionette TRACE 366 <- [1,6,null,{}]
03:20:09 INFO - 1499422809549 Marionette DEBUG Closed connection 366
03:20:09 INFO - 1499422809550 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49791
03:20:09 INFO - 1499422809551 Marionette DEBUG Closed connection 367
03:20:09 INFO - 1499422809552 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49792
03:20:09 INFO - 1499422809553 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809553 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809556 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809559 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5a9d06ce-be6d-4431-997d-a365b6e52603","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809562 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:20:09 INFO - 1499422809563 Marionette TRACE 368 <- [1,2,null,{}]
03:20:09 INFO - 1499422809565 Marionette DEBUG Closed connection 368
03:20:09 INFO - 1499422809566 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49793
03:20:09 INFO - 1499422809567 Marionette DEBUG Closed connection 369
03:20:09 INFO - 1499422809568 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49794
03:20:09 INFO - 1499422809569 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:09 INFO - 1499422809569 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809572 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809575 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0e932a8b-7cc6-4a7c-87b1-9f5beb9dab66","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809578 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:20:09 INFO - 1499422809580 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:20:09 INFO - 1499422809585 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:20:09 INFO - 1499422809586 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:20:09 INFO - 1499422809606 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:20:09 INFO - 1499422809613 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:20:09 INFO - 1499422809617 Marionette TRACE 370 <- [1,2,null,{}]
03:20:09 INFO - 1499422809620 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:20:09 INFO - 1499422809629 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14b66e9b-6721-42d1-a2ed-4e0017056298","ELEMENT":"14b66e9b-6721-42d1-a2ed-4e0017056298"}}]
03:20:09 INFO - 1499422809631 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"14b66e9b-6721-42d1-a2ed-4e0017056298"}]
03:20:09 INFO - 1499422809878 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:20:09 INFO - 1499422809879 Marionette TRACE 370 <- [1,4,null,{}]
03:20:09 INFO - 1499422809880 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"14b66e9b-6721-42d1-a2ed-4e0017056298","name":"selected"}]
03:20:09 INFO - 1499422809885 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:20:09 INFO - 1499422809887 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:20:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499422809890 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:20:09 INFO - 1499422809891 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:20:09 INFO - 1499422809892 Marionette TRACE 370 <- [1,7,null,{}]
03:20:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 381ms
03:20:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:20:09 INFO - 1499422809894 Marionette DEBUG Closed connection 370
03:20:09 INFO - 1499422809895 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49795
03:20:09 INFO - 1499422809896 Marionette DEBUG Closed connection 371
03:20:09 INFO - 1499422809897 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49796
03:20:09 INFO - 1499422809898 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809899 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809902 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809905 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8fd4bf6c-e0b6-45f9-ae88-eec812b6a54f","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809907 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:20:09 INFO - 1499422809908 Marionette TRACE 372 <- [1,2,null,{}]
03:20:09 INFO - 1499422809909 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:09 INFO - 1499422809909 Marionette TRACE 372 <- [1,3,null,{}]
03:20:09 INFO - 1499422809910 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:09 INFO - 1499422809911 Marionette TRACE 372 <- [1,4,null,{}]
03:20:09 INFO - 1499422809913 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:20:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499422809916 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:20:09 INFO - 1499422809917 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:20:09 INFO - 1499422809918 Marionette TRACE 372 <- [1,6,null,{}]
03:20:09 INFO - 1499422809920 Marionette DEBUG Closed connection 372
03:20:09 INFO - 1499422809921 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49797
03:20:09 INFO - 1499422809922 Marionette DEBUG Closed connection 373
03:20:09 INFO - 1499422809923 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49798
03:20:09 INFO - 1499422809924 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:09 INFO - 1499422809925 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809927 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809931 Marionette TRACE 374 <- [1,1,null,{"sessionId":"832cd7cd-b20b-42b2-82fa-1ba758913a85","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809933 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:20:09 INFO - 1499422809934 Marionette TRACE 374 <- [1,2,null,{}]
03:20:09 INFO - 1499422809937 Marionette DEBUG Closed connection 374
03:20:09 INFO - 1499422809937 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49799
03:20:09 INFO - 1499422809938 Marionette DEBUG Closed connection 375
03:20:09 INFO - 1499422809939 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49800
03:20:09 INFO - 1499422809940 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:09 INFO - 1499422809941 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:09 INFO - 1499422809943 Marionette DEBUG Register listener.js for window 12
03:20:09 INFO - 1499422809947 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4acc3e68-619d-46dc-9bbb-a7da1c3f8140","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:09 INFO - 1499422809956 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:20:09 INFO - 1499422809958 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:20:09 INFO - 1499422809961 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:20:09 INFO - 1499422809961 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:20:09 INFO - 1499422809984 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:20:09 INFO - 1499422809987 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:20:09 INFO - 1499422809992 Marionette TRACE 376 <- [1,2,null,{}]
03:20:10 INFO - 1499422810006 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:20:10 INFO - 1499422810009 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"52927f2d-1364-474c-903c-13af67752fb3","ELEMENT":"52927f2d-1364-474c-903c-13af67752fb3"}}]
03:20:10 INFO - 1499422810011 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"52927f2d-1364-474c-903c-13af67752fb3"}]
03:20:10 INFO - 1499422810253 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:20:10 INFO - 1499422810254 Marionette TRACE 376 <- [1,4,null,{}]
03:20:10 INFO - 1499422810256 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:20:10 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499422810259 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810260 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:20:10 INFO - 1499422810261 Marionette TRACE 376 <- [1,6,null,{}]
03:20:10 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 369ms
03:20:10 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:20:10 INFO - 1499422810264 Marionette DEBUG Closed connection 376
03:20:10 INFO - 1499422810265 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49801
03:20:10 INFO - 1499422810266 Marionette DEBUG Closed connection 377
03:20:10 INFO - 1499422810266 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49802
03:20:10 INFO - 1499422810267 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810268 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810271 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810282 Marionette TRACE 378 <- [1,1,null,{"sessionId":"015ec93d-0d8a-4d57-a7e6-6dbb2f77c11e","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810285 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:20:10 INFO - 1499422810286 Marionette TRACE 378 <- [1,2,null,{}]
03:20:10 INFO - 1499422810287 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:10 INFO - 1499422810287 Marionette TRACE 378 <- [1,3,null,{}]
03:20:10 INFO - 1499422810288 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:10 INFO - 1499422810289 Marionette TRACE 378 <- [1,4,null,{}]
03:20:10 INFO - 1499422810291 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:20:10 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499422810294 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810295 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:20:10 INFO - 1499422810297 Marionette TRACE 378 <- [1,6,null,{}]
03:20:10 INFO - 1499422810299 Marionette DEBUG Closed connection 378
03:20:10 INFO - 1499422810300 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49803
03:20:10 INFO - 1499422810300 Marionette DEBUG Closed connection 379
03:20:10 INFO - 1499422810301 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49804
03:20:10 INFO - 1499422810302 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810303 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810306 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810316 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1172a41e-4ccc-4cb8-b0f2-0e9656456141","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810319 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:20:10 INFO - 1499422810320 Marionette TRACE 380 <- [1,2,null,{}]
03:20:10 INFO - 1499422810323 Marionette DEBUG Closed connection 380
03:20:10 INFO - 1499422810324 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49805
03:20:10 INFO - 1499422810325 Marionette DEBUG Closed connection 381
03:20:10 INFO - 1499422810326 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49806
03:20:10 INFO - 1499422810327 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:10 INFO - 1499422810328 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810330 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810333 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1960272f-5782-4a61-819c-8e7eeabb3854","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810336 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:20:10 INFO - 1499422810337 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:20:10 INFO - 1499422810340 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:20:10 INFO - 1499422810340 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:20:10 INFO - 1499422810363 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:20:10 INFO - 1499422810366 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:20:10 INFO - 1499422810369 Marionette TRACE 382 <- [1,2,null,{}]
03:20:10 INFO - 1499422810371 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:20:10 INFO - 1499422810382 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86663f6b-2a33-4392-bebe-a1d12811119e","ELEMENT":"86663f6b-2a33-4392-bebe-a1d12811119e"}}]
03:20:10 INFO - 1499422810384 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"86663f6b-2a33-4392-bebe-a1d12811119e"}]
03:20:10 INFO - 1499422810629 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:20:10 INFO - 1499422810630 Marionette TRACE 382 <- [1,4,null,{}]
03:20:10 INFO - 1499422810632 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:20:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499422810635 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810636 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:20:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 376ms
03:20:10 INFO - TEST-START | test_click.py TestClick.test_input_file
03:20:10 INFO - 1499422810638 Marionette TRACE 382 <- [1,6,null,{}]
03:20:10 INFO - 1499422810640 Marionette DEBUG Closed connection 382
03:20:10 INFO - 1499422810641 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49807
03:20:10 INFO - 1499422810642 Marionette DEBUG Closed connection 383
03:20:10 INFO - 1499422810642 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49808
03:20:10 INFO - 1499422810643 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810644 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810647 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810650 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4e716c43-aa27-4b22-a76f-7d80728cb842","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810652 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:20:10 INFO - 1499422810653 Marionette TRACE 384 <- [1,2,null,{}]
03:20:10 INFO - 1499422810654 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:10 INFO - 1499422810654 Marionette TRACE 384 <- [1,3,null,{}]
03:20:10 INFO - 1499422810655 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:10 INFO - 1499422810656 Marionette TRACE 384 <- [1,4,null,{}]
03:20:10 INFO - 1499422810658 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:20:10 INFO - TEST-START: test_click.py TestClick.test_input_file1499422810661 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810661 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:20:10 INFO - 1499422810663 Marionette TRACE 384 <- [1,6,null,{}]
03:20:10 INFO - 1499422810665 Marionette DEBUG Closed connection 384
03:20:10 INFO - 1499422810666 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49809
03:20:10 INFO - 1499422810667 Marionette DEBUG Closed connection 385
03:20:10 INFO - 1499422810668 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49810
03:20:10 INFO - 1499422810669 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810669 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810672 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810675 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a8ce4344-75a1-4d7e-9911-8b4bf7d90ec6","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810678 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:20:10 INFO - 1499422810679 Marionette TRACE 386 <- [1,2,null,{}]
03:20:10 INFO - 1499422810682 Marionette DEBUG Closed connection 386
03:20:10 INFO - 1499422810683 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49811
03:20:10 INFO - 1499422810683 Marionette DEBUG Closed connection 387
03:20:10 INFO - 1499422810684 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49812
03:20:10 INFO - 1499422810685 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:10 INFO - 1499422810686 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810689 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810692 Marionette TRACE 388 <- [1,1,null,{"sessionId":"bf8a05d4-00f1-4cc7-a72c-e97e0081a407","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810694 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:20:10 INFO - 1499422810696 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:20:10 INFO - 1499422810699 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:20:10 INFO - 1499422810699 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:20:10 INFO - 1499422810719 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:20:10 INFO - 1499422810724 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:20:10 INFO - 1499422810735 Marionette TRACE 388 <- [1,2,null,{}]
03:20:10 INFO - 1499422810736 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:20:10 INFO - 1499422810739 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90a65126-7817-4144-a871-cbc8d391e9c2","ELEMENT":"90a65126-7817-4144-a871-cbc8d391e9c2"}}]
03:20:10 INFO - 1499422810741 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"90a65126-7817-4144-a871-cbc8d391e9c2"}]
03:20:10 INFO - 1499422810769 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:20:10 INFO - 1499422810771 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:20:10 INFO - TEST-END: test_click.py TestClick.test_input_file1499422810774 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810775 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:20:10 INFO - 1499422810777 Marionette TRACE 388 <- [1,6,null,{}]
03:20:10 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
03:20:10 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:20:10 INFO - 1499422810779 Marionette DEBUG Closed connection 388
03:20:10 INFO - 1499422810780 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49813
03:20:10 INFO - 1499422810781 Marionette DEBUG Closed connection 389
03:20:10 INFO - 1499422810781 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49814
03:20:10 INFO - 1499422810782 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810783 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810786 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810789 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0be27a88-0172-463d-a829-fe8af69220fc","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810792 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:20:10 INFO - 1499422810792 Marionette TRACE 390 <- [1,2,null,{}]
03:20:10 INFO - 1499422810793 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:10 INFO - 1499422810794 Marionette TRACE 390 <- [1,3,null,{}]
03:20:10 INFO - 1499422810795 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:10 INFO - 1499422810795 Marionette TRACE 390 <- [1,4,null,{}]
03:20:10 INFO - 1499422810797 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:20:10 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499422810800 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:20:10 INFO - 1499422810801 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:20:10 INFO - 1499422810802 Marionette TRACE 390 <- [1,6,null,{}]
03:20:10 INFO - 1499422810805 Marionette DEBUG Closed connection 390
03:20:10 INFO - 1499422810805 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49815
03:20:10 INFO - 1499422810806 Marionette DEBUG Closed connection 391
03:20:10 INFO - 1499422810807 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49816
03:20:10 INFO - 1499422810808 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:10 INFO - 1499422810809 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810812 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810823 Marionette TRACE 392 <- [1,1,null,{"sessionId":"15533171-4260-4ce1-b1e3-a933b5ba8a02","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810826 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:20:10 INFO - 1499422810827 Marionette TRACE 392 <- [1,2,null,{}]
03:20:10 INFO - 1499422810829 Marionette DEBUG Closed connection 392
03:20:10 INFO - 1499422810830 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49817
03:20:10 INFO - 1499422810831 Marionette DEBUG Closed connection 393
03:20:10 INFO - 1499422810832 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49818
03:20:10 INFO - 1499422810833 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:10 INFO - 1499422810834 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:10 INFO - 1499422810837 Marionette DEBUG Register listener.js for window 12
03:20:10 INFO - 1499422810840 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1c73ca8a-ccd5-45e6-81d0-a3e0f0429348","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:10 INFO - 1499422810842 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:20:10 INFO - 1499422810844 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:20:10 INFO - 1499422810846 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:20:10 INFO - 1499422810847 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:20:10 INFO - 1499422810870 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:20:10 INFO - 1499422810872 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:20:10 INFO - 1499422810882 Marionette TRACE 394 <- [1,2,null,{}]
03:20:10 INFO - 1499422810884 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:20:10 INFO - 1499422810887 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"69c7dd5e-3ea5-4ee8-be66-8ddd1ef5e951","ELEMENT":"69c7dd5e-3ea5-4ee8-be66-8ddd1ef5e951"}}]
03:20:10 INFO - 1499422810888 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:20:10 INFO - 1499422810890 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d353f36-130c-4968-bef5-1249cb357c1b","ELEMENT":"9d353f36-130c-4968-bef5-1249cb357c1b"}}]
03:20:10 INFO - 1499422810893 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"69c7dd5e-3ea5-4ee8-be66-8ddd1ef5e951"}]
03:20:11 INFO - 1499422811134 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:20:11 INFO - 1499422811135 Marionette TRACE 394 <- [1,5,null,{}]
03:20:11 INFO - 1499422811136 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9d353f36-130c-4968-bef5-1249cb357c1b"}]
03:20:11 INFO - 1499422811154 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
03:20:11 INFO - 1499422811156 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:20:11 INFO - 1499422811159 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:20:11 INFO - 1499422811160 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:20:11 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499422811163 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:20:11 INFO - 1499422811164 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:20:11 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 389ms
03:20:11 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:20:11 INFO - 1499422811166 Marionette TRACE 394 <- [1,9,null,{}]
03:20:11 INFO - 1499422811168 Marionette DEBUG Closed connection 394
03:20:11 INFO - 1499422811169 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49819
03:20:11 INFO - 1499422811170 Marionette DEBUG Closed connection 395
03:20:11 INFO - 1499422811171 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49820
03:20:11 INFO - 1499422811172 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:11 INFO - 1499422811173 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:11 INFO - 1499422811175 Marionette DEBUG Register listener.js for window 12
03:20:11 INFO - 1499422811179 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bbbb517f-1cfd-415c-be9e-a7f7695022b2","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:11 INFO - 1499422811182 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:20:11 INFO - 1499422811182 Marionette TRACE 396 <- [1,2,null,{}]
03:20:11 INFO - 1499422811183 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:20:11 INFO - 1499422811184 Marionette TRACE 396 <- [1,3,null,{}]
03:20:11 INFO - 1499422811185 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:20:11 INFO - 1499422811185 Marionette TRACE 396 <- [1,4,null,{}]
03:20:11 INFO - 1499422811187 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:20:11 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499422811190 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:20:11 INFO - 1499422811191 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:20:11 INFO - 1499422811192 Marionette TRACE 396 <- [1,6,null,{}]
03:20:11 INFO - 1499422811194 Marionette DEBUG Closed connection 396
03:20:11 INFO - 1499422811195 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49821
03:20:11 INFO - 1499422811196 Marionette DEBUG Closed connection 397
03:20:11 INFO - 1499422811197 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49822
03:20:11 INFO - 1499422811198 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:20:11 INFO - 1499422811198 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:11 INFO - 1499422811201 Marionette DEBUG Register listener.js for window 12
03:20:11 INFO - 1499422811204 Marionette TRACE 398 <- [1,1,null,{"sessionId":"0f15f7a4-9574-43fc-89a0-c1399c19aabd","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:11 INFO - 1499422811206 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:20:11 INFO - 1499422811208 Marionette TRACE 398 <- [1,2,null,{}]
03:20:11 INFO - 1499422811210 Marionette DEBUG Closed connection 398
03:20:11 INFO - 1499422811211 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49823
03:20:11 INFO - 1499422811212 Marionette DEBUG Closed connection 399
03:20:11 INFO - 1499422811212 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49824
03:20:11 INFO - 1499422811213 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:20:11 INFO - 1499422811214 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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}
03:20:11 INFO - 1499422811217 Marionette DEBUG Register listener.js for window 12
03:20:11 INFO - 1499422811221 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7423dd7f-bcf5-4729-b878-2675d903996d","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":2872,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyuovrq.mozrunner","moz:accessibilityChecks":false}}]
03:20:11 INFO - 1499422811224 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:20:11 INFO - 1499422811226 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:20:11 INFO - 1499422811228 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:20:11 INFO - 1499422811229 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:20:11 INFO - 1499422811251 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:20:11 INFO - 1499422811253 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:20:11 INFO - 1499422811257 Marionette TRACE 400 <- [1,2,null,{}]
03:20:11 INFO - 1499422811265 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:20:11 INFO - 1499422811268 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c72f24a5-9dc6-44ba-83ce-488b5f155d58","ELEMENT":"c72f24a5-9dc6-44ba-83ce-488b5f155d58"}}]
03:20:11 INFO - 1499422811269 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c72f24a5-9dc6-44ba-83ce-488b5f155d58"}]
03:20:11 INFO - 1499422811293 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:20:11 INFO - 1499422811295 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c72f24a5-9dc6-44ba-83ce-488b5f155d58"}]
03:20:11 INFO - 1499422811317 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element