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]
21:40:37 INFO - 1500698437119 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}]
21:40:37 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 154ms
21:40:37 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:40:37 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500698437129 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:40:37 INFO - 1500698437130 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:40:37 INFO - 1500698437132 Marionette TRACE 352 <- [1,6,null,{}]
21:40:37 INFO - 1500698437133 Marionette DEBUG Closed connection 352
21:40:37 INFO - 1500698437134 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50322
21:40:37 INFO - 1500698437135 Marionette DEBUG Closed connection 353
21:40:37 INFO - 1500698437136 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50323
21:40:37 INFO - 1500698437137 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437137 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437140 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437143 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f96b802d-eee8-4446-b9b0-dba6402dcfa1","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437146 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:40:37 INFO - 1500698437146 Marionette TRACE 354 <- [1,2,null,{}]
21:40:37 INFO - 1500698437147 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:37 INFO - 1500698437148 Marionette TRACE 354 <- [1,3,null,{}]
21:40:37 INFO - 1500698437149 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:37 INFO - 1500698437149 Marionette TRACE 354 <- [1,4,null,{}]
21:40:37 INFO - 1500698437151 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}]
21:40:37 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500698437154 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:40:37 INFO - 1500698437155 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:40:37 INFO - 1500698437156 Marionette TRACE 354 <- [1,6,null,{}]
21:40:37 INFO - 1500698437157 Marionette DEBUG Closed connection 354
21:40:37 INFO - 1500698437158 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50324
21:40:37 INFO - 1500698437158 Marionette DEBUG Closed connection 355
21:40:37 INFO - 1500698437159 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50325
21:40:37 INFO - 1500698437160 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437162 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437164 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437167 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5fcaff49-b48b-41bc-b87f-0193672bbb74","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437169 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:40:37 INFO - 1500698437170 Marionette TRACE 356 <- [1,2,null,{}]
21:40:37 INFO - 1500698437171 Marionette DEBUG Closed connection 356
21:40:37 INFO - 1500698437172 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50326
21:40:37 INFO - 1500698437173 Marionette DEBUG Closed connection 357
21:40:37 INFO - 1500698437173 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50327
21:40:37 INFO - 1500698437174 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:37 INFO - 1500698437175 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437177 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437180 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3314640f-c687-46a1-9a73-b874b407beac","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437181 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49881/clicks.html"}]
21:40:37 INFO - 1500698437183 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49881/hidden.html"
21:40:37 INFO - 1500698437191 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49881/hidden.html"
21:40:37 INFO - 1500698437191 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49881/hidden.html"
21:40:37 INFO - 1500698437197 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49881/clicks.html"
21:40:37 INFO - 1500698437201 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49881/clicks.html"
21:40:37 INFO - 1500698437204 Marionette TRACE 358 <- [1,2,null,{}]
21:40:37 INFO - 1500698437213 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:40:37 INFO - 1500698437216 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da8a68f6-8c4b-42a1-a6a9-525b68fe9115","ELEMENT":"da8a68f6-8c4b-42a1-a6a9-525b68fe9115"}}]
21:40:37 INFO - 1500698437217 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"da8a68f6-8c4b-42a1-a6a9-525b68fe9115"}]
21:40:37 INFO - 1500698437256 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49881/clicks.html"
21:40:37 INFO - 1500698437263 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49881/clicks.html"
21:40:37 INFO - 1500698437264 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49881/clicks.html"
21:40:37 INFO - 1500698437270 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49881/test.html"
21:40:37 INFO - 1500698437273 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49881/test.html"
21:40:37 INFO - 1500698437280 Marionette TRACE 358 <- [1,4,null,{}]
21:40:37 INFO - 1500698437285 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:40:37 INFO - 1500698437288 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0f4fda99-0fab-4bd6-bf14-636b78278333","ELEMENT":"0f4fda99-0fab-4bd6-bf14-636b78278333"}}]
21:40:37 INFO - 1500698437289 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:40:37 INFO - 1500698437289 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:40:37 INFO - 1500698437294 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}]
21:40:37 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500698437297 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:40:37 INFO - 1500698437299 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:40:37 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
21:40:37 INFO - TEST-START | test_click.py TestClick.test_container_element
21:40:37 INFO - 1500698437300 Marionette TRACE 358 <- [1,8,null,{}]
21:40:37 INFO - 1500698437301 Marionette DEBUG Closed connection 358
21:40:37 INFO - 1500698437302 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50330
21:40:37 INFO - 1500698437303 Marionette DEBUG Closed connection 359
21:40:37 INFO - 1500698437304 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50331
21:40:37 INFO - 1500698437306 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437306 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437308 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437321 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5db4d996-ee0e-4b6a-bcb0-fd9d61d30145","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437323 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:40:37 INFO - 1500698437323 Marionette TRACE 360 <- [1,2,null,{}]
21:40:37 INFO - 1500698437324 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:37 INFO - 1500698437325 Marionette TRACE 360 <- [1,3,null,{}]
21:40:37 INFO - 1500698437325 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:37 INFO - 1500698437326 Marionette TRACE 360 <- [1,4,null,{}]
21:40:37 INFO - 1500698437328 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}]
21:40:37 INFO - TEST-START: test_click.py TestClick.test_container_element1500698437331 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:40:37 INFO - 1500698437332 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:40:37 INFO - 1500698437333 Marionette TRACE 360 <- [1,6,null,{}]
21:40:37 INFO - 1500698437336 Marionette DEBUG Closed connection 360
21:40:37 INFO - 1500698437336 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50332
21:40:37 INFO - 1500698437337 Marionette DEBUG Closed connection 361
21:40:37 INFO - 1500698437339 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50333
21:40:37 INFO - 1500698437340 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437340 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437343 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437346 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f765679f-d209-4098-904b-ae708f085c31","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437347 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:40:37 INFO - 1500698437348 Marionette TRACE 362 <- [1,2,null,{}]
21:40:37 INFO - 1500698437350 Marionette DEBUG Closed connection 362
21:40:37 INFO - 1500698437351 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50334
21:40:37 INFO - 1500698437351 Marionette DEBUG Closed connection 363
21:40:37 INFO - 1500698437352 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50335
21:40:37 INFO - 1500698437353 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:37 INFO - 1500698437354 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437356 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437359 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c7510fb5-f19a-49f2-8f55-9c3b6d5245b6","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437361 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"}]
21:40:37 INFO - 1500698437362 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49881/test.html"
21:40:37 INFO - 1500698437364 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49881/test.html"
21:40:37 INFO - 1500698437364 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49881/test.html"
21:40:37 INFO - 1500698437370 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"
21:40:37 INFO - 1500698437373 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"
21:40:37 INFO - 1500698437382 Marionette TRACE 364 <- [1,2,null,{}]
21:40:37 INFO - 1500698437386 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:40:37 INFO - 1500698437389 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6ceb2a41-47a8-4c8d-8c7a-731a8dd1d794","ELEMENT":"6ceb2a41-47a8-4c8d-8c7a-731a8dd1d794"}}]
21:40:37 INFO - 1500698437395 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6ceb2a41-47a8-4c8d-8c7a-731a8dd1d794"}]
21:40:37 INFO - 1500698437645 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:37 INFO - 1500698437646 Marionette TRACE 364 <- [1,4,null,{}]
21:40:37 INFO - 1500698437647 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6ceb2a41-47a8-4c8d-8c7a-731a8dd1d794","name":"selected"}]
21:40:37 INFO - 1500698437651 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:40:37 INFO - 1500698437653 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}]
21:40:37 INFO - TEST-END: test_click.py TestClick.test_container_element1500698437656 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:40:37 INFO - 1500698437656 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:40:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
21:40:37 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:40:37 INFO - 1500698437658 Marionette TRACE 364 <- [1,7,null,{}]
21:40:37 INFO - 1500698437659 Marionette DEBUG Closed connection 364
21:40:37 INFO - 1500698437660 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50336
21:40:37 INFO - 1500698437661 Marionette DEBUG Closed connection 365
21:40:37 INFO - 1500698437662 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50337
21:40:37 INFO - 1500698437663 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437663 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437666 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437669 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c229ba28-98cd-4b7c-9545-2c6581fdceb8","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437670 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:40:37 INFO - 1500698437670 Marionette TRACE 366 <- [1,2,null,{}]
21:40:37 INFO - 1500698437671 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:37 INFO - 1500698437671 Marionette TRACE 366 <- [1,3,null,{}]
21:40:37 INFO - 1500698437672 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:37 INFO - 1500698437673 Marionette TRACE 366 <- [1,4,null,{}]
21:40:37 INFO - 1500698437674 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}]
21:40:37 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500698437677 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:40:37 INFO - 1500698437678 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:40:37 INFO - 1500698437679 Marionette TRACE 366 <- [1,6,null,{}]
21:40:37 INFO - 1500698437680 Marionette DEBUG Closed connection 366
21:40:37 INFO - 1500698437682 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50338
21:40:37 INFO - 1500698437682 Marionette DEBUG Closed connection 367
21:40:37 INFO - 1500698437683 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50339
21:40:37 INFO - 1500698437684 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:37 INFO - 1500698437685 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437687 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437690 Marionette TRACE 368 <- [1,1,null,{"sessionId":"8c5b885c-2a3b-4fe9-aca1-87fcfa8075bb","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437692 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:40:37 INFO - 1500698437693 Marionette TRACE 368 <- [1,2,null,{}]
21:40:37 INFO - 1500698437695 Marionette DEBUG Closed connection 368
21:40:37 INFO - 1500698437695 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50340
21:40:37 INFO - 1500698437696 Marionette DEBUG Closed connection 369
21:40:37 INFO - 1500698437697 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50341
21:40:37 INFO - 1500698437698 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:37 INFO - 1500698437698 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:37 INFO - 1500698437700 Marionette DEBUG Register listener.js for window 6442450945
21:40:37 INFO - 1500698437703 Marionette TRACE 370 <- [1,1,null,{"sessionId":"17402d42-39e2-4cb4-a7f5-6b8e6b9faba1","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:37 INFO - 1500698437705 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"}]
21:40:37 INFO - 1500698437706 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"
21:40:37 INFO - 1500698437708 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"
21:40:37 INFO - 1500698437708 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"
21:40:37 INFO - 1500698437714 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"
21:40:37 INFO - 1500698437716 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"
21:40:37 INFO - 1500698437727 Marionette TRACE 370 <- [1,2,null,{}]
21:40:37 INFO - 1500698437740 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:40:37 INFO - 1500698437745 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eeaafdf6-9bf8-4502-b734-8fb0c1632f8a","ELEMENT":"eeaafdf6-9bf8-4502-b734-8fb0c1632f8a"}}]
21:40:37 INFO - 1500698437747 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"eeaafdf6-9bf8-4502-b734-8fb0c1632f8a"}]
21:40:37 INFO - 1500698437988 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:37 INFO - 1500698437989 Marionette TRACE 370 <- [1,4,null,{}]
21:40:37 INFO - 1500698437990 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"eeaafdf6-9bf8-4502-b734-8fb0c1632f8a","name":"selected"}]
21:40:38 INFO - 1500698437995 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:40:38 INFO - 1500698437996 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500698437999 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:40:38 INFO - 1500698438000 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:40:38 INFO - 1500698438001 Marionette TRACE 370 <- [1,7,null,{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 343ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:40:38 INFO - 1500698438003 Marionette DEBUG Closed connection 370
21:40:38 INFO - 1500698438004 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50342
21:40:38 INFO - 1500698438005 Marionette DEBUG Closed connection 371
21:40:38 INFO - 1500698438006 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50343
21:40:38 INFO - 1500698438007 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438007 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438009 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438012 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dc959129-c29b-4433-8def-7b243f718f48","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438014 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438014 Marionette TRACE 372 <- [1,2,null,{}]
21:40:38 INFO - 1500698438015 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438016 Marionette TRACE 372 <- [1,3,null,{}]
21:40:38 INFO - 1500698438017 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438017 Marionette TRACE 372 <- [1,4,null,{}]
21:40:38 INFO - 1500698438019 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500698438022 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438022 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438024 Marionette TRACE 372 <- [1,6,null,{}]
21:40:38 INFO - 1500698438025 Marionette DEBUG Closed connection 372
21:40:38 INFO - 1500698438026 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50344
21:40:38 INFO - 1500698438027 Marionette DEBUG Closed connection 373
21:40:38 INFO - 1500698438028 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50345
21:40:38 INFO - 1500698438029 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438029 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438032 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438035 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ec5f8ab5-a1ef-42b1-9ca8-124d09390dae","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438036 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438037 Marionette TRACE 374 <- [1,2,null,{}]
21:40:38 INFO - 1500698438039 Marionette DEBUG Closed connection 374
21:40:38 INFO - 1500698438039 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50346
21:40:38 INFO - 1500698438040 Marionette DEBUG Closed connection 375
21:40:38 INFO - 1500698438041 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50347
21:40:38 INFO - 1500698438042 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438042 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438045 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438048 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9f507de8-b5e2-477a-9f18-fada317d96c4","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438050 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"}]
21:40:38 INFO - 1500698438051 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"
21:40:38 INFO - 1500698438054 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"
21:40:38 INFO - 1500698438055 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"
21:40:38 INFO - 1500698438063 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"
21:40:38 INFO - 1500698438066 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"
21:40:38 INFO - 1500698438070 Marionette TRACE 376 <- [1,2,null,{}]
21:40:38 INFO - 1500698438079 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:40:38 INFO - 1500698438083 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f31be54b-fe1c-4dbd-a9c4-01e8ad2a6eb1","ELEMENT":"f31be54b-fe1c-4dbd-a9c4-01e8ad2a6eb1"}}]
21:40:38 INFO - 1500698438084 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f31be54b-fe1c-4dbd-a9c4-01e8ad2a6eb1"}]
21:40:38 INFO - 1500698438321 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:38 INFO - 1500698438322 Marionette TRACE 376 <- [1,4,null,{}]
21:40:38 INFO - 1500698438324 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500698438326 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438327 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:40:38 INFO - 1500698438329 Marionette TRACE 376 <- [1,6,null,{}]
21:40:38 INFO - 1500698438330 Marionette DEBUG Closed connection 376
21:40:38 INFO - 1500698438331 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50348
21:40:38 INFO - 1500698438332 Marionette DEBUG Closed connection 377
21:40:38 INFO - 1500698438333 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50349
21:40:38 INFO - 1500698438334 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438334 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438337 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438339 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e83199b6-bfaa-480f-910b-7d386b65d909","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438340 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438341 Marionette TRACE 378 <- [1,2,null,{}]
21:40:38 INFO - 1500698438342 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438342 Marionette TRACE 378 <- [1,3,null,{}]
21:40:38 INFO - 1500698438343 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438344 Marionette TRACE 378 <- [1,4,null,{}]
21:40:38 INFO - 1500698438345 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500698438348 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438349 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438351 Marionette TRACE 378 <- [1,6,null,{}]
21:40:38 INFO - 1500698438352 Marionette DEBUG Closed connection 378
21:40:38 INFO - 1500698438353 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50350
21:40:38 INFO - 1500698438353 Marionette DEBUG Closed connection 379
21:40:38 INFO - 1500698438354 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50351
21:40:38 INFO - 1500698438355 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438356 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438358 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438361 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a8655fe2-e999-4701-90aa-1fb556df8768","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438362 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438363 Marionette TRACE 380 <- [1,2,null,{}]
21:40:38 INFO - 1500698438365 Marionette DEBUG Closed connection 380
21:40:38 INFO - 1500698438365 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50352
21:40:38 INFO - 1500698438366 Marionette DEBUG Closed connection 381
21:40:38 INFO - 1500698438367 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50353
21:40:38 INFO - 1500698438368 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438369 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438371 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438374 Marionette TRACE 382 <- [1,1,null,{"sessionId":"28052ea5-7491-44b7-b9d1-76307b1bc03d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438375 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"}]
21:40:38 INFO - 1500698438377 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"
21:40:38 INFO - 1500698438379 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"
21:40:38 INFO - 1500698438379 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"
21:40:38 INFO - 1500698438384 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"
21:40:38 INFO - 1500698438387 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"
21:40:38 INFO - 1500698438390 Marionette TRACE 382 <- [1,2,null,{}]
21:40:38 INFO - 1500698438398 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:40:38 INFO - 1500698438411 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb924fe4-6053-4310-94f0-129f7a42a81f","ELEMENT":"cb924fe4-6053-4310-94f0-129f7a42a81f"}}]
21:40:38 INFO - 1500698438412 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"cb924fe4-6053-4310-94f0-129f7a42a81f"}]
21:40:38 INFO - 1500698438665 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:38 INFO - 1500698438666 Marionette TRACE 382 <- [1,4,null,{}]
21:40:38 INFO - 1500698438668 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500698438670 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438671 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_input_file
21:40:38 INFO - 1500698438673 Marionette TRACE 382 <- [1,6,null,{}]
21:40:38 INFO - 1500698438674 Marionette DEBUG Closed connection 382
21:40:38 INFO - 1500698438675 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50354
21:40:38 INFO - 1500698438676 Marionette DEBUG Closed connection 383
21:40:38 INFO - 1500698438677 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50355
21:40:38 INFO - 1500698438678 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438678 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438681 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438684 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a9a2e3d0-30f6-4652-89c4-640f9107972d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438685 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438686 Marionette TRACE 384 <- [1,2,null,{}]
21:40:38 INFO - 1500698438687 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438687 Marionette TRACE 384 <- [1,3,null,{}]
21:40:38 INFO - 1500698438688 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438688 Marionette TRACE 384 <- [1,4,null,{}]
21:40:38 INFO - 1500698438690 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_input_file1500698438693 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438694 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438695 Marionette TRACE 384 <- [1,6,null,{}]
21:40:38 INFO - 1500698438696 Marionette DEBUG Closed connection 384
21:40:38 INFO - 1500698438697 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50356
21:40:38 INFO - 1500698438698 Marionette DEBUG Closed connection 385
21:40:38 INFO - 1500698438699 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50357
21:40:38 INFO - 1500698438699 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438700 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438702 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438705 Marionette TRACE 386 <- [1,1,null,{"sessionId":"61e602f9-e3c0-4b07-bd97-3ab41760545b","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438706 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438707 Marionette TRACE 386 <- [1,2,null,{}]
21:40:38 INFO - 1500698438709 Marionette DEBUG Closed connection 386
21:40:38 INFO - 1500698438710 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50358
21:40:38 INFO - 1500698438710 Marionette DEBUG Closed connection 387
21:40:38 INFO - 1500698438711 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50359
21:40:38 INFO - 1500698438712 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438713 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438715 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438718 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9b400715-df85-4315-aff6-e535196d988d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438720 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:40:38 INFO - 1500698438722 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"
21:40:38 INFO - 1500698438724 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"
21:40:38 INFO - 1500698438725 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"
21:40:38 INFO - 1500698438731 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:38 INFO - 1500698438734 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:38 INFO - 1500698438736 Marionette TRACE 388 <- [1,2,null,{}]
21:40:38 INFO - 1500698438739 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:40:38 INFO - 1500698438746 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f84cda90-2e93-4425-a27e-e7c3fd1f12df","ELEMENT":"f84cda90-2e93-4425-a27e-e7c3fd1f12df"}}]
21:40:38 INFO - 1500698438747 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f84cda90-2e93-4425-a27e-e7c3fd1f12df"}]
21:40:38 INFO - 1500698438774 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]
21:40:38 INFO - 1500698438776 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_input_file1500698438779 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438780 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438781 Marionette TRACE 388 <- [1,6,null,{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:40:38 INFO - 1500698438782 Marionette DEBUG Closed connection 388
21:40:38 INFO - 1500698438785 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50360
21:40:38 INFO - 1500698438785 Marionette DEBUG Closed connection 389
21:40:38 INFO - 1500698438786 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50361
21:40:38 INFO - 1500698438787 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438788 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438790 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438793 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6bbce274-e85e-4a4f-b5bc-fe7a584780b9","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438794 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438795 Marionette TRACE 390 <- [1,2,null,{}]
21:40:38 INFO - 1500698438796 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438796 Marionette TRACE 390 <- [1,3,null,{}]
21:40:38 INFO - 1500698438797 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438798 Marionette TRACE 390 <- [1,4,null,{}]
21:40:38 INFO - 1500698438800 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500698438802 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438803 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438805 Marionette TRACE 390 <- [1,6,null,{}]
21:40:38 INFO - 1500698438806 Marionette DEBUG Closed connection 390
21:40:38 INFO - 1500698438807 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50362
21:40:38 INFO - 1500698438807 Marionette DEBUG Closed connection 391
21:40:38 INFO - 1500698438808 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50363
21:40:38 INFO - 1500698438809 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438810 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438812 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438815 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f6bc2a9c-41d3-45f0-b909-8d40f82a7249","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438817 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438818 Marionette TRACE 392 <- [1,2,null,{}]
21:40:38 INFO - 1500698438819 Marionette DEBUG Closed connection 392
21:40:38 INFO - 1500698438820 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50364
21:40:38 INFO - 1500698438821 Marionette DEBUG Closed connection 393
21:40:38 INFO - 1500698438821 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50365
21:40:38 INFO - 1500698438822 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438823 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438825 Marionette DEBUG Register listener.js for window 6442450945
21:40:38 INFO - 1500698438828 Marionette TRACE 394 <- [1,1,null,{"sessionId":"21632f64-7cea-44ff-bf6a-b5952cb2fa28","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438829 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"}]
21:40:38 INFO - 1500698438831 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:38 INFO - 1500698438833 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:38 INFO - 1500698438834 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:38 INFO - 1500698438840 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"
21:40:38 INFO - 1500698438842 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"
21:40:38 INFO - 1500698438849 Marionette TRACE 394 <- [1,2,null,{}]
21:40:38 INFO - 1500698438853 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:40:38 INFO - 1500698438856 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"df6cc67d-2a68-4c55-9567-d40684f6470a","ELEMENT":"df6cc67d-2a68-4c55-9567-d40684f6470a"}}]
21:40:38 INFO - 1500698438857 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:40:38 INFO - 1500698438862 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cc8f708e-1b7f-4793-b1d0-d41550ef1414","ELEMENT":"cc8f708e-1b7f-4793-b1d0-d41550ef1414"}}]
21:40:38 INFO - 1500698438863 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"df6cc67d-2a68-4c55-9567-d40684f6470a"}]
21:40:39 INFO - 1500698439098 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:39 INFO - 1500698439099 Marionette TRACE 394 <- [1,5,null,{}]
21:40:39 INFO - 1500698439100 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"cc8f708e-1b7f-4793-b1d0-d41550ef1414"}]
21:40:39 INFO - 1500698439114 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]
21:40:39 INFO - 1500698439116 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:40:39 INFO - 1500698439119 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:40:39 INFO - 1500698439121 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}]
21:40:39 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500698439123 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:40:39 INFO - 1500698439124 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:40:39 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 343ms
21:40:39 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:40:39 INFO - 1500698439126 Marionette TRACE 394 <- [1,9,null,{}]
21:40:39 INFO - 1500698439127 Marionette DEBUG Closed connection 394
21:40:39 INFO - 1500698439128 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50366
21:40:39 INFO - 1500698439129 Marionette DEBUG Closed connection 395
21:40:39 INFO - 1500698439130 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50367
21:40:39 INFO - 1500698439131 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439132 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439134 Marionette DEBUG Register listener.js for window 6442450945
21:40:39 INFO - 1500698439145 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9528e32c-742e-4313-a872-501500e07f4d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439146 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:40:39 INFO - 1500698439147 Marionette TRACE 396 <- [1,2,null,{}]
21:40:39 INFO - 1500698439148 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:39 INFO - 1500698439148 Marionette TRACE 396 <- [1,3,null,{}]
21:40:39 INFO - 1500698439149 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:39 INFO - 1500698439149 Marionette TRACE 396 <- [1,4,null,{}]
21:40:39 INFO - 1500698439151 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}]
21:40:39 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500698439154 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439155 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439157 Marionette TRACE 396 <- [1,6,null,{}]
21:40:39 INFO - 1500698439158 Marionette DEBUG Closed connection 396
21:40:39 INFO - 1500698439159 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50368
21:40:39 INFO - 1500698439159 Marionette DEBUG Closed connection 397
21:40:39 INFO - 1500698439160 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50369
21:40:39 INFO - 1500698439161 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439162 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439164 Marionette DEBUG Register listener.js for window 6442450945
21:40:39 INFO - 1500698439167 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6d087bd2-989a-4f25-a87c-4b538239bf6c","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439168 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:40:39 INFO - 1500698439169 Marionette TRACE 398 <- [1,2,null,{}]
21:40:39 INFO - 1500698439170 Marionette DEBUG Closed connection 398
21:40:39 INFO - 1500698439171 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50370
21:40:39 INFO - 1500698439172 Marionette DEBUG Closed connection 399
21:40:39 INFO - 1500698439172 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50371
21:40:39 INFO - 1500698439173 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:39 INFO - 1500698439174 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439176 Marionette DEBUG Register listener.js for window 6442450945
21:40:39 INFO - 1500698439179 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9a6b01d6-30f8-45a8-b6be-d46f89d8cb86","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvrkzdm.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439180 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"}]
21:40:39 INFO - 1500698439182 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"
21:40:39 INFO - 1500698439184 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"
21:40:39 INFO - 1500698439184 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"
21:40:39 INFO - 1500698439190 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"
21:40:39 INFO - 1500698439192 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"
21:40:39 INFO - 1500698439201 Marionette TRACE 400 <- [1,2,null,{}]
21:40:39 INFO - 1500698439206 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:40:39 INFO - 1500698439211 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81f429e1-e88f-4bc3-bd09-e48fca5f0cc0","ELEMENT":"81f429e1-e88f-4bc3-bd09-e48fca5f0cc0"}}]
21:40:39 INFO - 1500698439213 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"81f429e1-e88f-4bc3-bd09-e48fca5f0cc0"}]
21:40:39 INFO - 1500698439240 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:40:39 INFO - 1500698439242 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"81f429e1-e88f-4bc3-bd09-e48fca5f0cc0"}]
21:40:39 INFO - 1500698439256 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element