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]
09:34:02 INFO - 1500741242398 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:34:02 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500741242401 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:34:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 113ms
09:34:02 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:34:02 INFO - 1500741242402 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:34:02 INFO - 1500741242403 Marionette TRACE 352 <- [1,6,null,{}]
09:34:02 INFO - 1500741242405 Marionette DEBUG Closed connection 352
09:34:02 INFO - 1500741242406 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50345
09:34:02 INFO - 1500741242407 Marionette DEBUG Closed connection 353
09:34:02 INFO - 1500741242408 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50346
09:34:02 INFO - 1500741242409 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:02 INFO - 1500741242411 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242413 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242417 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1a139cfd-c78d-4742-bfa5-a237af511fac","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242419 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:34:02 INFO - 1500741242419 Marionette TRACE 354 <- [1,2,null,{}]
09:34:02 INFO - 1500741242420 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:02 INFO - 1500741242421 Marionette TRACE 354 <- [1,3,null,{}]
09:34:02 INFO - 1500741242422 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:02 INFO - 1500741242422 Marionette TRACE 354 <- [1,4,null,{}]
09:34:02 INFO - 1500741242424 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:34:02 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500741242427 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:34:02 INFO - 1500741242427 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:34:02 INFO - 1500741242429 Marionette TRACE 354 <- [1,6,null,{}]
09:34:02 INFO - 1500741242430 Marionette DEBUG Closed connection 354
09:34:02 INFO - 1500741242430 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50347
09:34:02 INFO - 1500741242431 Marionette DEBUG Closed connection 355
09:34:02 INFO - 1500741242432 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50348
09:34:02 INFO - 1500741242433 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:02 INFO - 1500741242434 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242436 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242439 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a58a58f3-e52d-4725-9fbc-ad1b5bd00bc4","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242440 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:34:02 INFO - 1500741242441 Marionette TRACE 356 <- [1,2,null,{}]
09:34:02 INFO - 1500741242443 Marionette DEBUG Closed connection 356
09:34:02 INFO - 1500741242444 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50349
09:34:02 INFO - 1500741242444 Marionette DEBUG Closed connection 357
09:34:02 INFO - 1500741242445 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50350
09:34:02 INFO - 1500741242446 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:02 INFO - 1500741242447 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242455 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242468 Marionette TRACE 358 <- [1,1,null,{"sessionId":"cc4cdd07-6674-40c3-9e30-26cf41476e85","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242469 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49901/clicks.html"}]
09:34:02 INFO - 1500741242471 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49901/hidden.html"
09:34:02 INFO - 1500741242479 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49901/hidden.html"
09:34:02 INFO - 1500741242479 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49901/hidden.html"
09:34:02 INFO - 1500741242489 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49901/clicks.html"
09:34:02 INFO - 1500741242490 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49901/clicks.html"
09:34:02 INFO - 1500741242494 Marionette TRACE 358 <- [1,2,null,{}]
09:34:02 INFO - 1500741242499 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:34:02 INFO - 1500741242505 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db6458f1-823a-4eca-b3d7-d7289d2757bd","ELEMENT":"db6458f1-823a-4eca-b3d7-d7289d2757bd"}}]
09:34:02 INFO - 1500741242507 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"db6458f1-823a-4eca-b3d7-d7289d2757bd"}]
09:34:02 INFO - 1500741242543 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49901/clicks.html"
09:34:02 INFO - 1500741242551 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49901/clicks.html"
09:34:02 INFO - 1500741242551 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49901/clicks.html"
09:34:02 INFO - 1500741242558 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49901/test.html"
09:34:02 INFO - 1500741242561 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49901/test.html"
09:34:02 INFO - 1500741242565 Marionette TRACE 358 <- [1,4,null,{}]
09:34:02 INFO - 1500741242573 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:34:02 INFO - 1500741242578 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b8358477-938a-4f3a-9d50-f16ca0797c6a","ELEMENT":"b8358477-938a-4f3a-9d50-f16ca0797c6a"}}]
09:34:02 INFO - 1500741242586 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:34:02 INFO - 1500741242586 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:34:02 INFO - 1500741242588 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:34:02 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500741242591 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:34:02 INFO - 1500741242592 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:34:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 189ms
09:34:02 INFO - TEST-START | test_click.py TestClick.test_container_element
09:34:02 INFO - 1500741242594 Marionette TRACE 358 <- [1,8,null,{}]
09:34:02 INFO - 1500741242595 Marionette DEBUG Closed connection 358
09:34:02 INFO - 1500741242596 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50353
09:34:02 INFO - 1500741242597 Marionette DEBUG Closed connection 359
09:34:02 INFO - 1500741242598 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50354
09:34:02 INFO - 1500741242599 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:02 INFO - 1500741242599 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242602 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242605 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d97b2181-a8fe-4a71-8d85-591bd3e312d7","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242606 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:34:02 INFO - 1500741242607 Marionette TRACE 360 <- [1,2,null,{}]
09:34:02 INFO - 1500741242608 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:02 INFO - 1500741242608 Marionette TRACE 360 <- [1,3,null,{}]
09:34:02 INFO - 1500741242609 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:02 INFO - 1500741242611 Marionette TRACE 360 <- [1,4,null,{}]
09:34:02 INFO - 1500741242612 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:34:02 INFO - TEST-START: test_click.py TestClick.test_container_element1500741242616 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:34:02 INFO - 1500741242617 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:34:02 INFO - 1500741242618 Marionette TRACE 360 <- [1,6,null,{}]
09:34:02 INFO - 1500741242620 Marionette DEBUG Closed connection 360
09:34:02 INFO - 1500741242620 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50355
09:34:02 INFO - 1500741242621 Marionette DEBUG Closed connection 361
09:34:02 INFO - 1500741242622 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50356
09:34:02 INFO - 1500741242623 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:02 INFO - 1500741242623 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242625 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242628 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f98d11ce-020a-4f6a-9d0e-aa46ebf9025e","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242629 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:34:02 INFO - 1500741242630 Marionette TRACE 362 <- [1,2,null,{}]
09:34:02 INFO - 1500741242632 Marionette DEBUG Closed connection 362
09:34:02 INFO - 1500741242632 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50357
09:34:02 INFO - 1500741242633 Marionette DEBUG Closed connection 363
09:34:02 INFO - 1500741242634 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50358
09:34:02 INFO - 1500741242635 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:02 INFO - 1500741242635 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242637 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242640 Marionette TRACE 364 <- [1,1,null,{"sessionId":"de6523ea-be14-4d15-b2af-8c09789dbd20","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242641 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:34:02 INFO - 1500741242643 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49901/test.html"
09:34:02 INFO - 1500741242645 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49901/test.html"
09:34:02 INFO - 1500741242645 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49901/test.html"
09:34:02 INFO - 1500741242651 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:34:02 INFO - 1500741242653 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:34:02 INFO - 1500741242663 Marionette TRACE 364 <- [1,2,null,{}]
09:34:02 INFO - 1500741242667 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:34:02 INFO - 1500741242673 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c20ea5e-e7ca-4ce2-b450-f85b71b1bd0a","ELEMENT":"9c20ea5e-e7ca-4ce2-b450-f85b71b1bd0a"}}]
09:34:02 INFO - 1500741242674 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"9c20ea5e-e7ca-4ce2-b450-f85b71b1bd0a"}]
09:34:02 INFO - 1500741242912 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:34:02 INFO - 1500741242913 Marionette TRACE 364 <- [1,4,null,{}]
09:34:02 INFO - 1500741242914 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"9c20ea5e-e7ca-4ce2-b450-f85b71b1bd0a","name":"selected"}]
09:34:02 INFO - 1500741242918 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:34:02 INFO - 1500741242920 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:34:02 INFO - TEST-END: test_click.py TestClick.test_container_element1500741242923 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:34:02 INFO - 1500741242923 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:34:02 INFO - 1500741242925 Marionette TRACE 364 <- [1,7,null,{}]
09:34:02 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
09:34:02 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:34:02 INFO - 1500741242926 Marionette DEBUG Closed connection 364
09:34:02 INFO - 1500741242928 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50359
09:34:02 INFO - 1500741242928 Marionette DEBUG Closed connection 365
09:34:02 INFO - 1500741242929 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50360
09:34:02 INFO - 1500741242930 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:02 INFO - 1500741242931 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:02 INFO - 1500741242933 Marionette DEBUG Register listener.js for window 6442450945
09:34:02 INFO - 1500741242936 Marionette TRACE 366 <- [1,1,null,{"sessionId":"810e1ca7-c04b-4d35-b2a3-a5841fa530d8","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:02 INFO - 1500741242937 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:34:02 INFO - 1500741242937 Marionette TRACE 366 <- [1,2,null,{}]
09:34:02 INFO - 1500741242938 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:02 INFO - 1500741242938 Marionette TRACE 366 <- [1,3,null,{}]
09:34:02 INFO - 1500741242939 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:02 INFO - 1500741242940 Marionette TRACE 366 <- [1,4,null,{}]
09:34:02 INFO - 1500741242941 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:34:02 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500741242944 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:34:02 INFO - 1500741242945 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:34:02 INFO - 1500741242946 Marionette TRACE 366 <- [1,6,null,{}]
09:34:02 INFO - 1500741242947 Marionette DEBUG Closed connection 366
09:34:02 INFO - 1500741242948 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50361
09:34:02 INFO - 1500741242948 Marionette DEBUG Closed connection 367
09:34:03 INFO - 1500741242949 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50362
09:34:03 INFO - 1500741242950 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741242951 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741242952 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741242955 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e0da0dbb-8c2d-49a8-9030-dd31b3bf108c","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741242956 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:34:03 INFO - 1500741242957 Marionette TRACE 368 <- [1,2,null,{}]
09:34:03 INFO - 1500741242959 Marionette DEBUG Closed connection 368
09:34:03 INFO - 1500741242959 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50363
09:34:03 INFO - 1500741242960 Marionette DEBUG Closed connection 369
09:34:03 INFO - 1500741242961 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50364
09:34:03 INFO - 1500741242962 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:03 INFO - 1500741242962 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741242964 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741242967 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3bf6ea45-f1db-42fc-b299-6a4eb74c63fd","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741242968 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:34:03 INFO - 1500741242970 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:34:03 INFO - 1500741242971 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:34:03 INFO - 1500741242972 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:34:03 INFO - 1500741242977 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:34:03 INFO - 1500741242980 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:34:03 INFO - 1500741242991 Marionette TRACE 370 <- [1,2,null,{}]
09:34:03 INFO - 1500741243003 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:34:03 INFO - 1500741243007 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed2683d3-7b7e-4fb6-b537-93765a94ca22","ELEMENT":"ed2683d3-7b7e-4fb6-b537-93765a94ca22"}}]
09:34:03 INFO - 1500741243008 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ed2683d3-7b7e-4fb6-b537-93765a94ca22"}]
09:34:03 INFO - 1500741243245 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:34:03 INFO - 1500741243247 Marionette TRACE 370 <- [1,4,null,{}]
09:34:03 INFO - 1500741243248 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ed2683d3-7b7e-4fb6-b537-93765a94ca22","name":"selected"}]
09:34:03 INFO - 1500741243253 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:34:03 INFO - 1500741243254 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:34:03 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500741243257 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:34:03 INFO - 1500741243258 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:34:03 INFO - 1500741243259 Marionette TRACE 370 <- [1,7,null,{}]
09:34:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
09:34:03 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:34:03 INFO - 1500741243260 Marionette DEBUG Closed connection 370
09:34:03 INFO - 1500741243261 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50365
09:34:03 INFO - 1500741243262 Marionette DEBUG Closed connection 371
09:34:03 INFO - 1500741243263 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50366
09:34:03 INFO - 1500741243264 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243264 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243267 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243269 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dc76675d-c276-40e5-afa0-8119a15b9cc2","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243271 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:34:03 INFO - 1500741243271 Marionette TRACE 372 <- [1,2,null,{}]
09:34:03 INFO - 1500741243272 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:03 INFO - 1500741243272 Marionette TRACE 372 <- [1,3,null,{}]
09:34:03 INFO - 1500741243273 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:03 INFO - 1500741243274 Marionette TRACE 372 <- [1,4,null,{}]
09:34:03 INFO - 1500741243275 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:34:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500741243278 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:34:03 INFO - 1500741243279 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:34:03 INFO - 1500741243280 Marionette TRACE 372 <- [1,6,null,{}]
09:34:03 INFO - 1500741243281 Marionette DEBUG Closed connection 372
09:34:03 INFO - 1500741243282 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50367
09:34:03 INFO - 1500741243282 Marionette DEBUG Closed connection 373
09:34:03 INFO - 1500741243283 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50368
09:34:03 INFO - 1500741243284 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243284 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243286 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243289 Marionette TRACE 374 <- [1,1,null,{"sessionId":"1af81fc8-d396-4db6-b616-01fde7d3bab2","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243290 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:34:03 INFO - 1500741243292 Marionette TRACE 374 <- [1,2,null,{}]
09:34:03 INFO - 1500741243293 Marionette DEBUG Closed connection 374
09:34:03 INFO - 1500741243294 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50369
09:34:03 INFO - 1500741243294 Marionette DEBUG Closed connection 375
09:34:03 INFO - 1500741243295 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50370
09:34:03 INFO - 1500741243296 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:03 INFO - 1500741243297 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243299 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243301 Marionette TRACE 376 <- [1,1,null,{"sessionId":"07443b2c-fc18-416e-955b-475f997e8994","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243303 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:34:03 INFO - 1500741243304 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:34:03 INFO - 1500741243306 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:34:03 INFO - 1500741243306 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:34:03 INFO - 1500741243313 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:34:03 INFO - 1500741243315 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:34:03 INFO - 1500741243318 Marionette TRACE 376 <- [1,2,null,{}]
09:34:03 INFO - 1500741243328 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:34:03 INFO - 1500741243331 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8e5eb6c-7bdf-45cf-86ff-b6015cafa544","ELEMENT":"e8e5eb6c-7bdf-45cf-86ff-b6015cafa544"}}]
09:34:03 INFO - 1500741243332 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e8e5eb6c-7bdf-45cf-86ff-b6015cafa544"}]
09:34:03 INFO - 1500741243573 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:34:03 INFO - 1500741243574 Marionette TRACE 376 <- [1,4,null,{}]
09:34:03 INFO - 1500741243576 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:34:03 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500741243579 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:34:03 INFO - 1500741243580 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:34:03 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
09:34:03 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:34:03 INFO - 1500741243581 Marionette TRACE 376 <- [1,6,null,{}]
09:34:03 INFO - 1500741243583 Marionette DEBUG Closed connection 376
09:34:03 INFO - 1500741243584 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50371
09:34:03 INFO - 1500741243584 Marionette DEBUG Closed connection 377
09:34:03 INFO - 1500741243585 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50372
09:34:03 INFO - 1500741243586 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243587 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243589 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243592 Marionette TRACE 378 <- [1,1,null,{"sessionId":"933577d0-e50b-4a68-9ba0-424519368680","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243593 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:34:03 INFO - 1500741243593 Marionette TRACE 378 <- [1,2,null,{}]
09:34:03 INFO - 1500741243594 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:03 INFO - 1500741243595 Marionette TRACE 378 <- [1,3,null,{}]
09:34:03 INFO - 1500741243595 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:03 INFO - 1500741243596 Marionette TRACE 378 <- [1,4,null,{}]
09:34:03 INFO - 1500741243597 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:34:03 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500741243600 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:34:03 INFO - 1500741243601 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:34:03 INFO - 1500741243602 Marionette TRACE 378 <- [1,6,null,{}]
09:34:03 INFO - 1500741243603 Marionette DEBUG Closed connection 378
09:34:03 INFO - 1500741243604 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50373
09:34:03 INFO - 1500741243604 Marionette DEBUG Closed connection 379
09:34:03 INFO - 1500741243605 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50374
09:34:03 INFO - 1500741243606 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243607 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243609 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243611 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d811007a-5fbf-4e02-93c0-c031b05ea54b","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243612 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:34:03 INFO - 1500741243613 Marionette TRACE 380 <- [1,2,null,{}]
09:34:03 INFO - 1500741243615 Marionette DEBUG Closed connection 380
09:34:03 INFO - 1500741243615 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50375
09:34:03 INFO - 1500741243616 Marionette DEBUG Closed connection 381
09:34:03 INFO - 1500741243617 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50376
09:34:03 INFO - 1500741243618 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:03 INFO - 1500741243618 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243620 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243623 Marionette TRACE 382 <- [1,1,null,{"sessionId":"29089f20-604b-42df-b589-5a8770c230b8","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243624 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:34:03 INFO - 1500741243626 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:34:03 INFO - 1500741243628 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:34:03 INFO - 1500741243628 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:34:03 INFO - 1500741243634 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:34:03 INFO - 1500741243636 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:34:03 INFO - 1500741243643 Marionette TRACE 382 <- [1,2,null,{}]
09:34:03 INFO - 1500741243647 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:34:03 INFO - 1500741243650 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"28cdab75-164f-462d-94fb-885fcdbde33d","ELEMENT":"28cdab75-164f-462d-94fb-885fcdbde33d"}}]
09:34:03 INFO - 1500741243651 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"28cdab75-164f-462d-94fb-885fcdbde33d"}]
09:34:03 INFO - 1500741243891 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:34:03 INFO - 1500741243892 Marionette TRACE 382 <- [1,4,null,{}]
09:34:03 INFO - 1500741243894 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:34:03 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500741243897 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:34:03 INFO - 1500741243898 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:34:03 INFO - 1500741243899 Marionette TRACE 382 <- [1,6,null,{}]
09:34:03 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
09:34:03 INFO - TEST-START | test_click.py TestClick.test_input_file
09:34:03 INFO - 1500741243900 Marionette DEBUG Closed connection 382
09:34:03 INFO - 1500741243901 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50377
09:34:03 INFO - 1500741243902 Marionette DEBUG Closed connection 383
09:34:03 INFO - 1500741243903 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50378
09:34:03 INFO - 1500741243904 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243904 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243907 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243910 Marionette TRACE 384 <- [1,1,null,{"sessionId":"65ccb104-efdb-4249-8ca3-3127e6da49e5","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243911 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:34:03 INFO - 1500741243911 Marionette TRACE 384 <- [1,2,null,{}]
09:34:03 INFO - 1500741243912 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:03 INFO - 1500741243912 Marionette TRACE 384 <- [1,3,null,{}]
09:34:03 INFO - 1500741243913 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:03 INFO - 1500741243914 Marionette TRACE 384 <- [1,4,null,{}]
09:34:03 INFO - 1500741243915 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:34:03 INFO - TEST-START: test_click.py TestClick.test_input_file1500741243918 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:34:03 INFO - 1500741243918 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:34:03 INFO - 1500741243920 Marionette TRACE 384 <- [1,6,null,{}]
09:34:03 INFO - 1500741243921 Marionette DEBUG Closed connection 384
09:34:03 INFO - 1500741243922 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50379
09:34:03 INFO - 1500741243922 Marionette DEBUG Closed connection 385
09:34:03 INFO - 1500741243923 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50380
09:34:03 INFO - 1500741243924 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:03 INFO - 1500741243925 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243926 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243929 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cf2e0795-244d-4b8c-a9a8-aec61a3806d5","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243930 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:34:03 INFO - 1500741243931 Marionette TRACE 386 <- [1,2,null,{}]
09:34:03 INFO - 1500741243933 Marionette DEBUG Closed connection 386
09:34:03 INFO - 1500741243933 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50381
09:34:03 INFO - 1500741243934 Marionette DEBUG Closed connection 387
09:34:03 INFO - 1500741243935 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50382
09:34:03 INFO - 1500741243936 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:03 INFO - 1500741243937 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:03 INFO - 1500741243939 Marionette DEBUG Register listener.js for window 6442450945
09:34:03 INFO - 1500741243942 Marionette TRACE 388 <- [1,1,null,{"sessionId":"58df43b8-29e3-470e-8071-f76bcd0c88eb","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:03 INFO - 1500741243943 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:34:03 INFO - 1500741243944 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:34:03 INFO - 1500741243947 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:34:03 INFO - 1500741243948 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:34:03 INFO - 1500741243957 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:34:03 INFO - 1500741243963 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:34:03 INFO - 1500741243964 Marionette TRACE 388 <- [1,2,null,{}]
09:34:03 INFO - 1500741243967 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:34:03 INFO - 1500741243973 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4c01cc39-13c7-4741-91cb-24dcec5734ec","ELEMENT":"4c01cc39-13c7-4741-91cb-24dcec5734ec"}}]
09:34:03 INFO - 1500741243974 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4c01cc39-13c7-4741-91cb-24dcec5734ec"}]
09:34:04 INFO - 1500741243999 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]
09:34:04 INFO - 1500741244001 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:34:04 INFO - TEST-END: test_click.py TestClick.test_input_file1500741244004 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:34:04 INFO - 1500741244005 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:34:04 INFO - 1500741244006 Marionette TRACE 388 <- [1,6,null,{}]
09:34:04 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
09:34:04 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:34:04 INFO - 1500741244008 Marionette DEBUG Closed connection 388
09:34:04 INFO - 1500741244009 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50383
09:34:04 INFO - 1500741244009 Marionette DEBUG Closed connection 389
09:34:04 INFO - 1500741244010 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50384
09:34:04 INFO - 1500741244011 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:04 INFO - 1500741244012 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244014 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244016 Marionette TRACE 390 <- [1,1,null,{"sessionId":"75c069d6-73d3-41d0-a3f7-cd67e9d2c01f","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244017 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:34:04 INFO - 1500741244018 Marionette TRACE 390 <- [1,2,null,{}]
09:34:04 INFO - 1500741244019 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:04 INFO - 1500741244019 Marionette TRACE 390 <- [1,3,null,{}]
09:34:04 INFO - 1500741244020 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:04 INFO - 1500741244020 Marionette TRACE 390 <- [1,4,null,{}]
09:34:04 INFO - 1500741244022 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:34:04 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500741244024 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:34:04 INFO - 1500741244025 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:34:04 INFO - 1500741244026 Marionette TRACE 390 <- [1,6,null,{}]
09:34:04 INFO - 1500741244028 Marionette DEBUG Closed connection 390
09:34:04 INFO - 1500741244028 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50385
09:34:04 INFO - 1500741244029 Marionette DEBUG Closed connection 391
09:34:04 INFO - 1500741244030 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50386
09:34:04 INFO - 1500741244030 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:04 INFO - 1500741244031 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244033 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244036 Marionette TRACE 392 <- [1,1,null,{"sessionId":"979c21e6-0fd8-48ae-b277-fad86e0b8aef","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244037 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:34:04 INFO - 1500741244038 Marionette TRACE 392 <- [1,2,null,{}]
09:34:04 INFO - 1500741244039 Marionette DEBUG Closed connection 392
09:34:04 INFO - 1500741244040 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50387
09:34:04 INFO - 1500741244041 Marionette DEBUG Closed connection 393
09:34:04 INFO - 1500741244041 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50388
09:34:04 INFO - 1500741244042 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:04 INFO - 1500741244043 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244045 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244048 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a51e6b2f-ea05-4ed7-9a97-855334165fff","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244049 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:34:04 INFO - 1500741244050 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:34:04 INFO - 1500741244052 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:34:04 INFO - 1500741244052 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:34:04 INFO - 1500741244058 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:34:04 INFO - 1500741244060 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:34:04 INFO - 1500741244063 Marionette TRACE 394 <- [1,2,null,{}]
09:34:04 INFO - 1500741244065 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:34:04 INFO - 1500741244067 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2af0495c-032e-4a66-a907-df08fc8b3c1b","ELEMENT":"2af0495c-032e-4a66-a907-df08fc8b3c1b"}}]
09:34:04 INFO - 1500741244068 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:34:04 INFO - 1500741244075 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70764b27-7b50-49e5-a267-0bc2c1846921","ELEMENT":"70764b27-7b50-49e5-a267-0bc2c1846921"}}]
09:34:04 INFO - 1500741244076 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2af0495c-032e-4a66-a907-df08fc8b3c1b"}]
09:34:04 INFO - 1500741244317 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:34:04 INFO - 1500741244318 Marionette TRACE 394 <- [1,5,null,{}]
09:34:04 INFO - 1500741244319 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"70764b27-7b50-49e5-a267-0bc2c1846921"}]
09:34:04 INFO - 1500741244333 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]
09:34:04 INFO - 1500741244335 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:34:04 INFO - 1500741244338 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:34:04 INFO - 1500741244340 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:34:04 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500741244342 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:34:04 INFO - 1500741244343 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:34:04 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 338ms
09:34:04 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:34:04 INFO - 1500741244344 Marionette TRACE 394 <- [1,9,null,{}]
09:34:04 INFO - 1500741244346 Marionette DEBUG Closed connection 394
09:34:04 INFO - 1500741244347 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50389
09:34:04 INFO - 1500741244347 Marionette DEBUG Closed connection 395
09:34:04 INFO - 1500741244348 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50390
09:34:04 INFO - 1500741244349 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:04 INFO - 1500741244357 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244360 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244362 Marionette TRACE 396 <- [1,1,null,{"sessionId":"71e2d900-a4c7-4ce1-a318-1e986e74e086","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244364 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:34:04 INFO - 1500741244364 Marionette TRACE 396 <- [1,2,null,{}]
09:34:04 INFO - 1500741244365 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:34:04 INFO - 1500741244366 Marionette TRACE 396 <- [1,3,null,{}]
09:34:04 INFO - 1500741244366 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:34:04 INFO - 1500741244367 Marionette TRACE 396 <- [1,4,null,{}]
09:34:04 INFO - 1500741244368 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:34:04 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500741244371 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:34:04 INFO - 1500741244372 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:34:04 INFO - 1500741244373 Marionette TRACE 396 <- [1,6,null,{}]
09:34:04 INFO - 1500741244375 Marionette DEBUG Closed connection 396
09:34:04 INFO - 1500741244375 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50391
09:34:04 INFO - 1500741244376 Marionette DEBUG Closed connection 397
09:34:04 INFO - 1500741244377 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50392
09:34:04 INFO - 1500741244378 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:34:04 INFO - 1500741244378 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244380 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244383 Marionette TRACE 398 <- [1,1,null,{"sessionId":"461ba5b9-008f-443e-8b95-4fbb6f7977f5","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244384 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:34:04 INFO - 1500741244385 Marionette TRACE 398 <- [1,2,null,{}]
09:34:04 INFO - 1500741244387 Marionette DEBUG Closed connection 398
09:34:04 INFO - 1500741244387 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50393
09:34:04 INFO - 1500741244388 Marionette DEBUG Closed connection 399
09:34:04 INFO - 1500741244389 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50394
09:34:04 INFO - 1500741244390 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:34:04 INFO - 1500741244390 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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}
09:34:04 INFO - 1500741244392 Marionette DEBUG Register listener.js for window 6442450945
09:34:04 INFO - 1500741244395 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1a076858-c066-48ae-8534-e09f7cf9ccb9","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":2476,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqg56i.mozrunner","moz:accessibilityChecks":false}}]
09:34:04 INFO - 1500741244396 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:34:04 INFO - 1500741244398 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:34:04 INFO - 1500741244400 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:34:04 INFO - 1500741244400 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:34:04 INFO - 1500741244407 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:34:04 INFO - 1500741244409 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:34:04 INFO - 1500741244414 Marionette TRACE 400 <- [1,2,null,{}]
09:34:04 INFO - 1500741244417 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:34:04 INFO - 1500741244424 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d823307-82a6-4d88-bbcf-8d2c49497530","ELEMENT":"6d823307-82a6-4d88-bbcf-8d2c49497530"}}]
09:34:04 INFO - 1500741244425 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6d823307-82a6-4d88-bbcf-8d2c49497530"}]
09:34:04 INFO - 1500741244447 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:34:04 INFO - 1500741244448 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6d823307-82a6-4d88-bbcf-8d2c49497530"}]
09:34:04 INFO - 1500741244463 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element