could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:05:08 INFO - 1499961908174 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}]
09:05:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499961908178 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:05:08 INFO - 1499961908179 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:05:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 206ms
09:05:08 INFO - 1499961908181 Marionette TRACE 352 <- [1,6,null,{}]
09:05:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:05:08 INFO - 1499961908184 Marionette DEBUG Closed connection 352
09:05:08 INFO - 1499961908185 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49786
09:05:08 INFO - 1499961908186 Marionette DEBUG Closed connection 353
09:05:08 INFO - 1499961908186 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49787
09:05:08 INFO - 1499961908187 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908188 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908199 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908213 Marionette TRACE 354 <- [1,1,null,{"sessionId":"58f00d37-2854-4ea3-b57f-1c7f42560fbe","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908216 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:05:08 INFO - 1499961908216 Marionette TRACE 354 <- [1,2,null,{}]
09:05:08 INFO - 1499961908217 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:08 INFO - 1499961908218 Marionette TRACE 354 <- [1,3,null,{}]
09:05:08 INFO - 1499961908219 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:08 INFO - 1499961908219 Marionette TRACE 354 <- [1,4,null,{}]
09:05:08 INFO - 1499961908221 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}]
09:05:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499961908225 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:05:08 INFO - 1499961908226 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:05:08 INFO - 1499961908228 Marionette TRACE 354 <- [1,6,null,{}]
09:05:08 INFO - 1499961908230 Marionette DEBUG Closed connection 354
09:05:08 INFO - 1499961908231 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49788
09:05:08 INFO - 1499961908232 Marionette DEBUG Closed connection 355
09:05:08 INFO - 1499961908232 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49789
09:05:08 INFO - 1499961908233 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908234 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908237 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908240 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bb0ffb7e-4f91-4e75-9ea5-833f226b3178","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908242 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:05:08 INFO - 1499961908244 Marionette TRACE 356 <- [1,2,null,{}]
09:05:08 INFO - 1499961908246 Marionette DEBUG Closed connection 356
09:05:08 INFO - 1499961908247 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49790
09:05:08 INFO - 1499961908248 Marionette DEBUG Closed connection 357
09:05:08 INFO - 1499961908249 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49791
09:05:08 INFO - 1499961908250 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:08 INFO - 1499961908251 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908253 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908257 Marionette TRACE 358 <- [1,1,null,{"sessionId":"9d16aa09-a098-4322-9e25-58683eac99f1","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908259 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49347/clicks.html"}]
09:05:08 INFO - 1499961908261 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/hidden.html"
09:05:08 INFO - 1499961908267 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/hidden.html"
09:05:08 INFO - 1499961908268 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/hidden.html"
09:05:08 INFO - 1499961908311 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/clicks.html"
09:05:08 INFO - 1499961908313 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/clicks.html"
09:05:08 INFO - 1499961908317 Marionette TRACE 358 <- [1,2,null,{}]
09:05:08 INFO - 1499961908326 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:05:08 INFO - 1499961908329 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4382cb4-0fc2-4335-a1ee-cc44829e80f2","ELEMENT":"b4382cb4-0fc2-4335-a1ee-cc44829e80f2"}}]
09:05:08 INFO - 1499961908348 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b4382cb4-0fc2-4335-a1ee-cc44829e80f2"}]
09:05:08 INFO - 1499961908394 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/clicks.html"
09:05:08 INFO - 1499961908403 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/clicks.html"
09:05:08 INFO - 1499961908404 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/clicks.html"
09:05:08 INFO - 1499961908464 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/test.html"
09:05:08 INFO - 1499961908469 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/test.html"
09:05:08 INFO - 1499961908483 Marionette TRACE 358 <- [1,4,null,{}]
09:05:08 INFO - 1499961908484 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:05:08 INFO - 1499961908487 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a85e177a-babd-4923-a462-8910fe245f18","ELEMENT":"a85e177a-babd-4923-a462-8910fe245f18"}}]
09:05:08 INFO - 1499961908501 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:05:08 INFO - 1499961908502 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:05:08 INFO - 1499961908516 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}]
09:05:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499961908519 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:05:08 INFO - 1499961908520 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:05:08 INFO - 1499961908522 Marionette TRACE 358 <- [1,8,null,{}]
09:05:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 341ms
09:05:08 INFO - TEST-START | test_click.py TestClick.test_container_element
09:05:08 INFO - 1499961908525 Marionette DEBUG Closed connection 358
09:05:08 INFO - 1499961908526 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49794
09:05:08 INFO - 1499961908528 Marionette DEBUG Closed connection 359
09:05:08 INFO - 1499961908529 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49795
09:05:08 INFO - 1499961908531 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908531 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908534 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908542 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2028d118-6364-44c1-92cf-dc619a0c391f","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908544 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:05:08 INFO - 1499961908545 Marionette TRACE 360 <- [1,2,null,{}]
09:05:08 INFO - 1499961908546 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:08 INFO - 1499961908547 Marionette TRACE 360 <- [1,3,null,{}]
09:05:08 INFO - 1499961908548 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:08 INFO - 1499961908548 Marionette TRACE 360 <- [1,4,null,{}]
09:05:08 INFO - 1499961908550 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}]
09:05:08 INFO - TEST-START: test_click.py TestClick.test_container_element1499961908556 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:05:08 INFO - 1499961908557 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:05:08 INFO - 1499961908559 Marionette TRACE 360 <- [1,6,null,{}]
09:05:08 INFO - 1499961908561 Marionette DEBUG Closed connection 360
09:05:08 INFO - 1499961908562 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49796
09:05:08 INFO - 1499961908563 Marionette DEBUG Closed connection 361
09:05:08 INFO - 1499961908564 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49797
09:05:08 INFO - 1499961908565 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908566 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908569 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908573 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f7b4f738-48de-4095-91db-28019c03775a","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908576 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:05:08 INFO - 1499961908577 Marionette TRACE 362 <- [1,2,null,{}]
09:05:08 INFO - 1499961908580 Marionette DEBUG Closed connection 362
09:05:08 INFO - 1499961908580 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49798
09:05:08 INFO - 1499961908581 Marionette DEBUG Closed connection 363
09:05:08 INFO - 1499961908582 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49799
09:05:08 INFO - 1499961908583 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:08 INFO - 1499961908584 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908587 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908591 Marionette TRACE 364 <- [1,1,null,{"sessionId":"bfb3ae20-c8d1-4baa-bf0c-ef82bce69879","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908593 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"}]
09:05:08 INFO - 1499961908595 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/test.html"
09:05:08 INFO - 1499961908597 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/test.html"
09:05:08 INFO - 1499961908598 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/test.html"
09:05:08 INFO - 1499961908624 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"
09:05:08 INFO - 1499961908630 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"
09:05:08 INFO - 1499961908634 Marionette TRACE 364 <- [1,2,null,{}]
09:05:08 INFO - 1499961908644 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:05:08 INFO - 1499961908647 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7430a53-3b80-44b4-8f92-aeb09a146a5a","ELEMENT":"a7430a53-3b80-44b4-8f92-aeb09a146a5a"}}]
09:05:08 INFO - 1499961908648 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"a7430a53-3b80-44b4-8f92-aeb09a146a5a"}]
09:05:08 INFO - 1499961908895 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:05:08 INFO - 1499961908896 Marionette TRACE 364 <- [1,4,null,{}]
09:05:08 INFO - 1499961908897 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"a7430a53-3b80-44b4-8f92-aeb09a146a5a","name":"selected"}]
09:05:08 INFO - 1499961908902 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:05:08 INFO - 1499961908904 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}]
09:05:08 INFO - TEST-END: test_click.py TestClick.test_container_element1499961908907 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:05:08 INFO - 1499961908908 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:05:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 387ms
09:05:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:05:08 INFO - 1499961908910 Marionette TRACE 364 <- [1,7,null,{}]
09:05:08 INFO - 1499961908912 Marionette DEBUG Closed connection 364
09:05:08 INFO - 1499961908913 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49800
09:05:08 INFO - 1499961908914 Marionette DEBUG Closed connection 365
09:05:08 INFO - 1499961908914 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49801
09:05:08 INFO - 1499961908915 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908916 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908919 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908930 Marionette TRACE 366 <- [1,1,null,{"sessionId":"bf638127-f117-4aee-bf51-3a4465e3823e","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908933 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:05:08 INFO - 1499961908933 Marionette TRACE 366 <- [1,2,null,{}]
09:05:08 INFO - 1499961908934 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:08 INFO - 1499961908935 Marionette TRACE 366 <- [1,3,null,{}]
09:05:08 INFO - 1499961908936 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:08 INFO - 1499961908936 Marionette TRACE 366 <- [1,4,null,{}]
09:05:08 INFO - 1499961908938 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}]
09:05:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499961908942 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:05:08 INFO - 1499961908943 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:05:08 INFO - 1499961908944 Marionette TRACE 366 <- [1,6,null,{}]
09:05:08 INFO - 1499961908947 Marionette DEBUG Closed connection 366
09:05:08 INFO - 1499961908948 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49802
09:05:08 INFO - 1499961908948 Marionette DEBUG Closed connection 367
09:05:08 INFO - 1499961908949 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49803
09:05:08 INFO - 1499961908950 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:08 INFO - 1499961908951 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908954 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908957 Marionette TRACE 368 <- [1,1,null,{"sessionId":"09e42065-1daa-4ecb-b0db-6a10f0bae1c8","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908959 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:05:08 INFO - 1499961908961 Marionette TRACE 368 <- [1,2,null,{}]
09:05:08 INFO - 1499961908963 Marionette DEBUG Closed connection 368
09:05:08 INFO - 1499961908964 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49804
09:05:08 INFO - 1499961908965 Marionette DEBUG Closed connection 369
09:05:08 INFO - 1499961908966 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49805
09:05:08 INFO - 1499961908967 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:08 INFO - 1499961908968 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:08 INFO - 1499961908971 Marionette DEBUG Register listener.js for window 15
09:05:08 INFO - 1499961908974 Marionette TRACE 370 <- [1,1,null,{"sessionId":"49c755e8-4c94-47c8-9ef4-d18aee0f31d1","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:08 INFO - 1499961908976 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"}]
09:05:08 INFO - 1499961908979 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"
09:05:08 INFO - 1499961908982 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"
09:05:08 INFO - 1499961908983 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"
09:05:09 INFO - 1499961909006 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"
09:05:09 INFO - 1499961909009 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"
09:05:09 INFO - 1499961909013 Marionette TRACE 370 <- [1,2,null,{}]
09:05:09 INFO - 1499961909014 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:05:09 INFO - 1499961909017 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dcac17ad-a10a-4364-abac-bacaa8f80962","ELEMENT":"dcac17ad-a10a-4364-abac-bacaa8f80962"}}]
09:05:09 INFO - 1499961909025 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"dcac17ad-a10a-4364-abac-bacaa8f80962"}]
09:05:09 INFO - 1499961909273 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:05:09 INFO - 1499961909274 Marionette TRACE 370 <- [1,4,null,{}]
09:05:09 INFO - 1499961909275 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"dcac17ad-a10a-4364-abac-bacaa8f80962","name":"selected"}]
09:05:09 INFO - 1499961909280 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:05:09 INFO - 1499961909282 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}]
09:05:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499961909285 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:05:09 INFO - 1499961909286 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:05:09 INFO - 1499961909287 Marionette TRACE 370 <- [1,7,null,{}]
09:05:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 378ms
09:05:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:05:09 INFO - 1499961909290 Marionette DEBUG Closed connection 370
09:05:09 INFO - 1499961909291 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49806
09:05:09 INFO - 1499961909291 Marionette DEBUG Closed connection 371
09:05:09 INFO - 1499961909292 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49807
09:05:09 INFO - 1499961909293 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:09 INFO - 1499961909294 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909297 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909301 Marionette TRACE 372 <- [1,1,null,{"sessionId":"cdef0a0e-340f-429d-88bb-606faa5b9279","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909303 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:05:09 INFO - 1499961909303 Marionette TRACE 372 <- [1,2,null,{}]
09:05:09 INFO - 1499961909304 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:09 INFO - 1499961909305 Marionette TRACE 372 <- [1,3,null,{}]
09:05:09 INFO - 1499961909306 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:09 INFO - 1499961909306 Marionette TRACE 372 <- [1,4,null,{}]
09:05:09 INFO - 1499961909308 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}]
09:05:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499961909311 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:05:09 INFO - 1499961909312 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:05:09 INFO - 1499961909314 Marionette TRACE 372 <- [1,6,null,{}]
09:05:09 INFO - 1499961909316 Marionette DEBUG Closed connection 372
09:05:09 INFO - 1499961909317 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49808
09:05:09 INFO - 1499961909318 Marionette DEBUG Closed connection 373
09:05:09 INFO - 1499961909318 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49809
09:05:09 INFO - 1499961909319 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:09 INFO - 1499961909320 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909323 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909327 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a367a3c2-ca46-411c-ad07-2ac0f93885f8","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909329 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:05:09 INFO - 1499961909330 Marionette TRACE 374 <- [1,2,null,{}]
09:05:09 INFO - 1499961909333 Marionette DEBUG Closed connection 374
09:05:09 INFO - 1499961909333 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49810
09:05:09 INFO - 1499961909334 Marionette DEBUG Closed connection 375
09:05:09 INFO - 1499961909335 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49811
09:05:09 INFO - 1499961909336 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:09 INFO - 1499961909337 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909340 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909343 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6c1eeff7-40f6-47f4-9dd6-f91cf3aba55a","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909345 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"}]
09:05:09 INFO - 1499961909347 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"
09:05:09 INFO - 1499961909350 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"
09:05:09 INFO - 1499961909350 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"
09:05:09 INFO - 1499961909374 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"
09:05:09 INFO - 1499961909377 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"
09:05:09 INFO - 1499961909382 Marionette TRACE 376 <- [1,2,null,{}]
09:05:09 INFO - 1499961909383 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:05:09 INFO - 1499961909394 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b419ebde-53e9-461e-9cdb-b46e70a57df3","ELEMENT":"b419ebde-53e9-461e-9cdb-b46e70a57df3"}}]
09:05:09 INFO - 1499961909397 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b419ebde-53e9-461e-9cdb-b46e70a57df3"}]
09:05:09 INFO - 1499961909641 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:05:09 INFO - 1499961909642 Marionette TRACE 376 <- [1,4,null,{}]
09:05:09 INFO - 1499961909644 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}]
09:05:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499961909647 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:05:09 INFO - 1499961909648 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:05:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 362ms
09:05:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:05:09 INFO - 1499961909650 Marionette TRACE 376 <- [1,6,null,{}]
09:05:09 INFO - 1499961909653 Marionette DEBUG Closed connection 376
09:05:09 INFO - 1499961909654 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49812
09:05:09 INFO - 1499961909655 Marionette DEBUG Closed connection 377
09:05:09 INFO - 1499961909655 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49813
09:05:09 INFO - 1499961909656 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:09 INFO - 1499961909657 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909660 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909664 Marionette TRACE 378 <- [1,1,null,{"sessionId":"54b5c6b3-70aa-412a-b772-9c8fba8843b8","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909674 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:05:09 INFO - 1499961909674 Marionette TRACE 378 <- [1,2,null,{}]
09:05:09 INFO - 1499961909675 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:09 INFO - 1499961909676 Marionette TRACE 378 <- [1,3,null,{}]
09:05:09 INFO - 1499961909677 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:09 INFO - 1499961909677 Marionette TRACE 378 <- [1,4,null,{}]
09:05:09 INFO - 1499961909679 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}]
09:05:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499961909682 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:05:09 INFO - 1499961909683 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:05:09 INFO - 1499961909685 Marionette TRACE 378 <- [1,6,null,{}]
09:05:09 INFO - 1499961909687 Marionette DEBUG Closed connection 378
09:05:09 INFO - 1499961909688 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49814
09:05:09 INFO - 1499961909689 Marionette DEBUG Closed connection 379
09:05:09 INFO - 1499961909690 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49815
09:05:09 INFO - 1499961909691 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:09 INFO - 1499961909692 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909695 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909698 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4363cbb8-8990-4b6c-856f-fbd267808359","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909700 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:05:09 INFO - 1499961909702 Marionette TRACE 380 <- [1,2,null,{}]
09:05:09 INFO - 1499961909704 Marionette DEBUG Closed connection 380
09:05:09 INFO - 1499961909705 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49816
09:05:09 INFO - 1499961909706 Marionette DEBUG Closed connection 381
09:05:09 INFO - 1499961909707 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49817
09:05:09 INFO - 1499961909708 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:09 INFO - 1499961909709 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:09 INFO - 1499961909711 Marionette DEBUG Register listener.js for window 15
09:05:09 INFO - 1499961909715 Marionette TRACE 382 <- [1,1,null,{"sessionId":"046d171f-aea0-4217-a288-67109741df2b","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:09 INFO - 1499961909717 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"}]
09:05:09 INFO - 1499961909719 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"
09:05:09 INFO - 1499961909722 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"
09:05:09 INFO - 1499961909722 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"
09:05:09 INFO - 1499961909735 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"
09:05:09 INFO - 1499961909745 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"
09:05:09 INFO - 1499961909750 Marionette TRACE 382 <- [1,2,null,{}]
09:05:09 INFO - 1499961909759 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:05:09 INFO - 1499961909762 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21e3c038-e551-411f-a694-0c8f80b45025","ELEMENT":"21e3c038-e551-411f-a694-0c8f80b45025"}}]
09:05:09 INFO - 1499961909763 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"21e3c038-e551-411f-a694-0c8f80b45025"}]
09:05:10 INFO - 1499961910009 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:05:10 INFO - 1499961910010 Marionette TRACE 382 <- [1,4,null,{}]
09:05:10 INFO - 1499961910012 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}]
09:05:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499961910015 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:05:10 INFO - 1499961910016 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:05:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 368ms
09:05:10 INFO - 1499961910018 Marionette TRACE 382 <- [1,6,null,{}]
09:05:10 INFO - TEST-START | test_click.py TestClick.test_input_file
09:05:10 INFO - 1499961910020 Marionette DEBUG Closed connection 382
09:05:10 INFO - 1499961910021 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49818
09:05:10 INFO - 1499961910022 Marionette DEBUG Closed connection 383
09:05:10 INFO - 1499961910023 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49819
09:05:10 INFO - 1499961910024 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910025 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910028 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910031 Marionette TRACE 384 <- [1,1,null,{"sessionId":"16d4737f-b131-4d1e-bfe4-0973bc38a111","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910034 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:05:10 INFO - 1499961910034 Marionette TRACE 384 <- [1,2,null,{}]
09:05:10 INFO - 1499961910035 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:10 INFO - 1499961910036 Marionette TRACE 384 <- [1,3,null,{}]
09:05:10 INFO - 1499961910037 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:10 INFO - 1499961910037 Marionette TRACE 384 <- [1,4,null,{}]
09:05:10 INFO - 1499961910039 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}]
09:05:10 INFO - TEST-START: test_click.py TestClick.test_input_file1499961910042 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:05:10 INFO - 1499961910043 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:05:10 INFO - 1499961910045 Marionette TRACE 384 <- [1,6,null,{}]
09:05:10 INFO - 1499961910047 Marionette DEBUG Closed connection 384
09:05:10 INFO - 1499961910048 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49820
09:05:10 INFO - 1499961910049 Marionette DEBUG Closed connection 385
09:05:10 INFO - 1499961910049 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49821
09:05:10 INFO - 1499961910051 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910051 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910054 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910058 Marionette TRACE 386 <- [1,1,null,{"sessionId":"8087fab5-c4de-411b-ad49-af564171f7b2","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910060 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:05:10 INFO - 1499961910062 Marionette TRACE 386 <- [1,2,null,{}]
09:05:10 INFO - 1499961910064 Marionette DEBUG Closed connection 386
09:05:10 INFO - 1499961910065 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49822
09:05:10 INFO - 1499961910066 Marionette DEBUG Closed connection 387
09:05:10 INFO - 1499961910066 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49823
09:05:10 INFO - 1499961910068 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:10 INFO - 1499961910068 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910071 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910075 Marionette TRACE 388 <- [1,1,null,{"sessionId":"99398f22-da68-4303-a58a-37168b53ce10","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910077 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:05:10 INFO - 1499961910079 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"
09:05:10 INFO - 1499961910081 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"
09:05:10 INFO - 1499961910082 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"
09:05:10 INFO - 1499961910108 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:05:10 INFO - 1499961910110 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:05:10 INFO - 1499961910114 Marionette TRACE 388 <- [1,2,null,{}]
09:05:10 INFO - 1499961910115 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:05:10 INFO - 1499961910126 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63ce2dce-76fc-420a-b1fd-057f894ee88d","ELEMENT":"63ce2dce-76fc-420a-b1fd-057f894ee88d"}}]
09:05:10 INFO - 1499961910128 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"63ce2dce-76fc-420a-b1fd-057f894ee88d"}]
09:05:10 INFO - 1499961910158 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:05:10 INFO - 1499961910160 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}]
09:05:10 INFO - TEST-END: test_click.py TestClick.test_input_file1499961910163 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:05:10 INFO - 1499961910164 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:05:10 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 147ms
09:05:10 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:05:10 INFO - 1499961910166 Marionette TRACE 388 <- [1,6,null,{}]
09:05:10 INFO - 1499961910168 Marionette DEBUG Closed connection 388
09:05:10 INFO - 1499961910169 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49824
09:05:10 INFO - 1499961910170 Marionette DEBUG Closed connection 389
09:05:10 INFO - 1499961910171 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49825
09:05:10 INFO - 1499961910172 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910173 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910175 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910179 Marionette TRACE 390 <- [1,1,null,{"sessionId":"3f7d8d70-97ef-4301-be97-2b4da5f3a700","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910181 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:05:10 INFO - 1499961910182 Marionette TRACE 390 <- [1,2,null,{}]
09:05:10 INFO - 1499961910183 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:10 INFO - 1499961910183 Marionette TRACE 390 <- [1,3,null,{}]
09:05:10 INFO - 1499961910184 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:10 INFO - 1499961910185 Marionette TRACE 390 <- [1,4,null,{}]
09:05:10 INFO - 1499961910187 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}]
09:05:10 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499961910190 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:05:10 INFO - 1499961910191 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:05:10 INFO - 1499961910193 Marionette TRACE 390 <- [1,6,null,{}]
09:05:10 INFO - 1499961910195 Marionette DEBUG Closed connection 390
09:05:10 INFO - 1499961910196 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49826
09:05:10 INFO - 1499961910197 Marionette DEBUG Closed connection 391
09:05:10 INFO - 1499961910198 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49827
09:05:10 INFO - 1499961910199 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910199 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910202 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910214 Marionette TRACE 392 <- [1,1,null,{"sessionId":"04976ae5-6622-4549-aece-9aea3d6c238a","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910217 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:05:10 INFO - 1499961910219 Marionette TRACE 392 <- [1,2,null,{}]
09:05:10 INFO - 1499961910221 Marionette DEBUG Closed connection 392
09:05:10 INFO - 1499961910222 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49828
09:05:10 INFO - 1499961910223 Marionette DEBUG Closed connection 393
09:05:10 INFO - 1499961910224 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49829
09:05:10 INFO - 1499961910225 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:10 INFO - 1499961910225 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910228 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910232 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8ccfc9f7-8bb4-4ffc-a979-9f4e0de84e80","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910234 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"}]
09:05:10 INFO - 1499961910236 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:05:10 INFO - 1499961910238 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:05:10 INFO - 1499961910239 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:05:10 INFO - 1499961910262 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"
09:05:10 INFO - 1499961910265 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"
09:05:10 INFO - 1499961910269 Marionette TRACE 394 <- [1,2,null,{}]
09:05:10 INFO - 1499961910278 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:05:10 INFO - 1499961910281 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"564c347b-db6b-44cf-921f-4faff1a28b0c","ELEMENT":"564c347b-db6b-44cf-921f-4faff1a28b0c"}}]
09:05:10 INFO - 1499961910282 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:05:10 INFO - 1499961910285 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7929da3a-e4b8-49f4-8095-f410ed0b984c","ELEMENT":"7929da3a-e4b8-49f4-8095-f410ed0b984c"}}]
09:05:10 INFO - 1499961910286 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"564c347b-db6b-44cf-921f-4faff1a28b0c"}]
09:05:10 INFO - 1499961910529 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:05:10 INFO - 1499961910530 Marionette TRACE 394 <- [1,5,null,{}]
09:05:10 INFO - 1499961910531 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7929da3a-e4b8-49f4-8095-f410ed0b984c"}]
09:05:10 INFO - 1499961910549 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:05:10 INFO - 1499961910551 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:05:10 INFO - 1499961910555 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:05:10 INFO - 1499961910556 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}]
09:05:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499961910559 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:05:10 INFO - 1499961910560 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:05:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 396ms
09:05:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:05:10 INFO - 1499961910562 Marionette TRACE 394 <- [1,9,null,{}]
09:05:10 INFO - 1499961910564 Marionette DEBUG Closed connection 394
09:05:10 INFO - 1499961910565 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49832
09:05:10 INFO - 1499961910566 Marionette DEBUG Closed connection 395
09:05:10 INFO - 1499961910567 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49833
09:05:10 INFO - 1499961910568 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910568 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910571 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910575 Marionette TRACE 396 <- [1,1,null,{"sessionId":"0a7dbbf0-4f5a-438c-b8f5-c511ce6d91d7","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910578 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:05:10 INFO - 1499961910578 Marionette TRACE 396 <- [1,2,null,{}]
09:05:10 INFO - 1499961910579 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:05:10 INFO - 1499961910580 Marionette TRACE 396 <- [1,3,null,{}]
09:05:10 INFO - 1499961910580 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:05:10 INFO - 1499961910581 Marionette TRACE 396 <- [1,4,null,{}]
09:05:10 INFO - 1499961910583 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}]
09:05:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499961910586 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:05:10 INFO - 1499961910587 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:05:10 INFO - 1499961910588 Marionette TRACE 396 <- [1,6,null,{}]
09:05:10 INFO - 1499961910591 Marionette DEBUG Closed connection 396
09:05:10 INFO - 1499961910592 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49834
09:05:10 INFO - 1499961910592 Marionette DEBUG Closed connection 397
09:05:10 INFO - 1499961910593 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49835
09:05:10 INFO - 1499961910594 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:05:10 INFO - 1499961910595 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910598 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910601 Marionette TRACE 398 <- [1,1,null,{"sessionId":"db838bdb-abbd-4dd2-87f6-ea2ab50342d2","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910603 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:05:10 INFO - 1499961910605 Marionette TRACE 398 <- [1,2,null,{}]
09:05:10 INFO - 1499961910607 Marionette DEBUG Closed connection 398
09:05:10 INFO - 1499961910608 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49836
09:05:10 INFO - 1499961910609 Marionette DEBUG Closed connection 399
09:05:10 INFO - 1499961910610 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49837
09:05:10 INFO - 1499961910611 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:05:10 INFO - 1499961910612 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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}
09:05:10 INFO - 1499961910614 Marionette DEBUG Register listener.js for window 15
09:05:10 INFO - 1499961910619 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1b18cc17-15a2-4ad7-88aa-2c3f3f6d6f34","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":3848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpem9hob.mozrunner","moz:accessibilityChecks":false}}]
09:05:10 INFO - 1499961910622 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"}]
09:05:10 INFO - 1499961910624 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"
09:05:10 INFO - 1499961910627 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"
09:05:10 INFO - 1499961910627 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"
09:05:10 INFO - 1499961910649 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"
09:05:10 INFO - 1499961910653 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"
09:05:10 INFO - 1499961910664 Marionette TRACE 400 <- [1,2,null,{}]
09:05:10 INFO - 1499961910666 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:05:10 INFO - 1499961910669 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"476fdfc8-f47b-49bb-ac99-bfe127cbbe5d","ELEMENT":"476fdfc8-f47b-49bb-ac99-bfe127cbbe5d"}}]
09:05:10 INFO - 1499961910671 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"476fdfc8-f47b-49bb-ac99-bfe127cbbe5d"}]
09:05:10 INFO - 1499961910696 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:05:10 INFO - 1499961910699 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"476fdfc8-f47b-49bb-ac99-bfe127cbbe5d"}]
09:05:10 INFO - 1499961910717 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element