could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:56:37 INFO - 1501044997409 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:56:37 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501044997412 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:56:37 INFO - 1501044997413 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:56:37 INFO - 1501044997414 Marionette TRACE 353 <- [1,6,null,{}]
21:56:37 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 116ms
21:56:37 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:56:37 INFO - 1501044997416 Marionette DEBUG Closed connection 353
21:56:37 INFO - 1501044997417 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50039
21:56:37 INFO - 1501044997417 Marionette DEBUG Closed connection 354
21:56:37 INFO - 1501044997418 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50040
21:56:37 INFO - 1501044997419 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997420 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997421 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997425 Marionette TRACE 355 <- [1,1,null,{"sessionId":"da4361e6-e99a-4047-96a2-1af2ef751f6a","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997426 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:56:37 INFO - 1501044997426 Marionette TRACE 355 <- [1,2,null,{}]
21:56:37 INFO - 1501044997427 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:37 INFO - 1501044997427 Marionette TRACE 355 <- [1,3,null,{}]
21:56:37 INFO - 1501044997428 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:37 INFO - 1501044997429 Marionette TRACE 355 <- [1,4,null,{}]
21:56:37 INFO - 1501044997430 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:56:37 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501044997433 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:56:37 INFO - 1501044997434 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:56:37 INFO - 1501044997435 Marionette TRACE 355 <- [1,6,null,{}]
21:56:37 INFO - 1501044997436 Marionette DEBUG Closed connection 355
21:56:37 INFO - 1501044997437 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50041
21:56:37 INFO - 1501044997437 Marionette DEBUG Closed connection 356
21:56:37 INFO - 1501044997438 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50042
21:56:37 INFO - 1501044997439 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997440 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997442 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997445 Marionette TRACE 357 <- [1,1,null,{"sessionId":"2c01f1ac-e974-404a-b887-0047ce068580","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997445 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:56:37 INFO - 1501044997447 Marionette TRACE 357 <- [1,2,null,{}]
21:56:37 INFO - 1501044997448 Marionette DEBUG Closed connection 357
21:56:37 INFO - 1501044997448 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50043
21:56:37 INFO - 1501044997449 Marionette DEBUG Closed connection 358
21:56:37 INFO - 1501044997450 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50044
21:56:37 INFO - 1501044997451 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:37 INFO - 1501044997452 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997453 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997464 Marionette TRACE 359 <- [1,1,null,{"sessionId":"a7432b72-2bab-4c6f-85ad-dc3aa2d479f9","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997466 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49602/clicks.html"}]
21:56:37 INFO - 1501044997468 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/hidden.html"
21:56:37 INFO - 1501044997478 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/hidden.html"
21:56:37 INFO - 1501044997479 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/hidden.html"
21:56:37 INFO - 1501044997491 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49602/clicks.html"
21:56:37 INFO - 1501044997495 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49602/clicks.html"
21:56:37 INFO - 1501044997498 Marionette TRACE 359 <- [1,2,null,{}]
21:56:37 INFO - 1501044997503 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:56:37 INFO - 1501044997508 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2a9044a4-d176-4b14-83f9-8445ab8f15a8","ELEMENT":"2a9044a4-d176-4b14-83f9-8445ab8f15a8"}}]
21:56:37 INFO - 1501044997510 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"2a9044a4-d176-4b14-83f9-8445ab8f15a8"}]
21:56:37 INFO - 1501044997546 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/clicks.html"
21:56:37 INFO - 1501044997554 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/clicks.html"
21:56:37 INFO - 1501044997554 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/clicks.html"
21:56:37 INFO - 1501044997560 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49602/test.html"
21:56:37 INFO - 1501044997564 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49602/test.html"
21:56:37 INFO - 1501044997568 Marionette TRACE 359 <- [1,4,null,{}]
21:56:37 INFO - 1501044997577 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:56:37 INFO - 1501044997581 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af3a61c9-004f-4a07-b8df-4a38636347c1","ELEMENT":"af3a61c9-004f-4a07-b8df-4a38636347c1"}}]
21:56:37 INFO - 1501044997582 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:56:37 INFO - 1501044997582 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:56:37 INFO - 1501044997584 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:56:37 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501044997586 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:56:37 INFO - 1501044997587 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:56:37 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 174ms
21:56:37 INFO - TEST-START | test_click.py TestClick.test_container_element
21:56:37 INFO - 1501044997589 Marionette TRACE 359 <- [1,8,null,{}]
21:56:37 INFO - 1501044997590 Marionette DEBUG Closed connection 359
21:56:37 INFO - 1501044997591 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50047
21:56:37 INFO - 1501044997592 Marionette DEBUG Closed connection 360
21:56:37 INFO - 1501044997593 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50048
21:56:37 INFO - 1501044997594 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997596 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997598 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997601 Marionette TRACE 361 <- [1,1,null,{"sessionId":"7297147b-7347-4c44-bd69-fe4aa8ac7d30","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997602 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:56:37 INFO - 1501044997602 Marionette TRACE 361 <- [1,2,null,{}]
21:56:37 INFO - 1501044997603 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:37 INFO - 1501044997604 Marionette TRACE 361 <- [1,3,null,{}]
21:56:37 INFO - 1501044997605 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:37 INFO - 1501044997605 Marionette TRACE 361 <- [1,4,null,{}]
21:56:37 INFO - 1501044997607 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:56:37 INFO - TEST-START: test_click.py TestClick.test_container_element1501044997610 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:56:37 INFO - 1501044997611 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:56:37 INFO - 1501044997612 Marionette TRACE 361 <- [1,6,null,{}]
21:56:37 INFO - 1501044997613 Marionette DEBUG Closed connection 361
21:56:37 INFO - 1501044997614 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50049
21:56:37 INFO - 1501044997615 Marionette DEBUG Closed connection 362
21:56:37 INFO - 1501044997616 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50050
21:56:37 INFO - 1501044997617 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997617 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997619 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997622 Marionette TRACE 363 <- [1,1,null,{"sessionId":"208f351a-20d4-41b3-bc57-92cd54429238","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997623 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:56:37 INFO - 1501044997624 Marionette TRACE 363 <- [1,2,null,{}]
21:56:37 INFO - 1501044997625 Marionette DEBUG Closed connection 363
21:56:37 INFO - 1501044997626 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50051
21:56:37 INFO - 1501044997626 Marionette DEBUG Closed connection 364
21:56:37 INFO - 1501044997627 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50052
21:56:37 INFO - 1501044997628 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:37 INFO - 1501044997629 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997631 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997633 Marionette TRACE 365 <- [1,1,null,{"sessionId":"31c3e23e-98ec-48de-a17b-0d91ddb417ba","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997634 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:56:37 INFO - 1501044997636 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/test.html"
21:56:37 INFO - 1501044997638 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/test.html"
21:56:37 INFO - 1501044997638 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/test.html"
21:56:37 INFO - 1501044997643 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997646 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997650 Marionette TRACE 365 <- [1,2,null,{}]
21:56:37 INFO - 1501044997653 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:56:37 INFO - 1501044997661 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"343f301c-fea3-4739-ba1e-a81d7a0c24bc","ELEMENT":"343f301c-fea3-4739-ba1e-a81d7a0c24bc"}}]
21:56:37 INFO - 1501044997662 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"343f301c-fea3-4739-ba1e-a81d7a0c24bc"}]
21:56:37 INFO - 1501044997901 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:56:37 INFO - 1501044997902 Marionette TRACE 365 <- [1,4,null,{}]
21:56:37 INFO - 1501044997903 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"343f301c-fea3-4739-ba1e-a81d7a0c24bc","name":"selected"}]
21:56:37 INFO - 1501044997907 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:56:37 INFO - 1501044997909 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:56:37 INFO - TEST-END: test_click.py TestClick.test_container_element1501044997911 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:56:37 INFO - 1501044997912 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:56:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 324ms
21:56:37 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:56:37 INFO - 1501044997914 Marionette TRACE 365 <- [1,7,null,{}]
21:56:37 INFO - 1501044997915 Marionette DEBUG Closed connection 365
21:56:37 INFO - 1501044997916 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50053
21:56:37 INFO - 1501044997917 Marionette DEBUG Closed connection 366
21:56:37 INFO - 1501044997918 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50054
21:56:37 INFO - 1501044997920 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997920 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997922 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997925 Marionette TRACE 367 <- [1,1,null,{"sessionId":"a168a465-af21-4693-9c14-8f91b017f3c7","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997926 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:56:37 INFO - 1501044997927 Marionette TRACE 367 <- [1,2,null,{}]
21:56:37 INFO - 1501044997928 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:37 INFO - 1501044997928 Marionette TRACE 367 <- [1,3,null,{}]
21:56:37 INFO - 1501044997929 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:37 INFO - 1501044997929 Marionette TRACE 367 <- [1,4,null,{}]
21:56:37 INFO - 1501044997931 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:56:37 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501044997933 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:56:37 INFO - 1501044997934 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:56:37 INFO - 1501044997935 Marionette TRACE 367 <- [1,6,null,{}]
21:56:37 INFO - 1501044997937 Marionette DEBUG Closed connection 367
21:56:37 INFO - 1501044997937 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50055
21:56:37 INFO - 1501044997938 Marionette DEBUG Closed connection 368
21:56:37 INFO - 1501044997939 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50056
21:56:37 INFO - 1501044997940 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:37 INFO - 1501044997940 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997942 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997945 Marionette TRACE 369 <- [1,1,null,{"sessionId":"c53d5204-ee8a-4118-85d0-cab53548a035","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997946 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:56:37 INFO - 1501044997947 Marionette TRACE 369 <- [1,2,null,{}]
21:56:37 INFO - 1501044997948 Marionette DEBUG Closed connection 369
21:56:37 INFO - 1501044997949 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50057
21:56:37 INFO - 1501044997949 Marionette DEBUG Closed connection 370
21:56:37 INFO - 1501044997950 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50058
21:56:37 INFO - 1501044997951 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:37 INFO - 1501044997952 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:37 INFO - 1501044997953 Marionette DEBUG Register listener.js for window 4294967297
21:56:37 INFO - 1501044997956 Marionette TRACE 371 <- [1,1,null,{"sessionId":"9087bf5d-d2d4-468d-b60f-cd51d2da2638","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:37 INFO - 1501044997957 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:56:37 INFO - 1501044997959 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997961 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997961 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997966 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997969 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:37 INFO - 1501044997972 Marionette TRACE 371 <- [1,2,null,{}]
21:56:37 INFO - 1501044997980 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:56:37 INFO - 1501044997983 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"939817fe-96a8-4b7e-a146-1abb8966d1da","ELEMENT":"939817fe-96a8-4b7e-a146-1abb8966d1da"}}]
21:56:37 INFO - 1501044997984 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"939817fe-96a8-4b7e-a146-1abb8966d1da"}]
21:56:38 INFO - 1501044998222 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:56:38 INFO - 1501044998222 Marionette TRACE 371 <- [1,4,null,{}]
21:56:38 INFO - 1501044998224 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"939817fe-96a8-4b7e-a146-1abb8966d1da","name":"selected"}]
21:56:38 INFO - 1501044998227 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:56:38 INFO - 1501044998229 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:56:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501044998232 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:56:38 INFO - 1501044998232 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:56:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 319ms
21:56:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:56:38 INFO - 1501044998234 Marionette TRACE 371 <- [1,7,null,{}]
21:56:38 INFO - 1501044998235 Marionette DEBUG Closed connection 371
21:56:38 INFO - 1501044998236 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50059
21:56:38 INFO - 1501044998237 Marionette DEBUG Closed connection 372
21:56:38 INFO - 1501044998237 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50060
21:56:38 INFO - 1501044998238 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998239 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998241 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998243 Marionette TRACE 373 <- [1,1,null,{"sessionId":"145e1a15-9330-4cc4-b8ca-97798ce80e03","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998244 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:56:38 INFO - 1501044998245 Marionette TRACE 373 <- [1,2,null,{}]
21:56:38 INFO - 1501044998246 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:38 INFO - 1501044998246 Marionette TRACE 373 <- [1,3,null,{}]
21:56:38 INFO - 1501044998247 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:38 INFO - 1501044998247 Marionette TRACE 373 <- [1,4,null,{}]
21:56:38 INFO - 1501044998249 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:56:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501044998251 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998252 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:56:38 INFO - 1501044998253 Marionette TRACE 373 <- [1,6,null,{}]
21:56:38 INFO - 1501044998255 Marionette DEBUG Closed connection 373
21:56:38 INFO - 1501044998255 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50061
21:56:38 INFO - 1501044998257 Marionette DEBUG Closed connection 374
21:56:38 INFO - 1501044998257 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50062
21:56:38 INFO - 1501044998258 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998258 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998260 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998263 Marionette TRACE 375 <- [1,1,null,{"sessionId":"19f5995c-5c39-4ce4-92d4-bc60ddfee37d","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998264 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:56:38 INFO - 1501044998265 Marionette TRACE 375 <- [1,2,null,{}]
21:56:38 INFO - 1501044998266 Marionette DEBUG Closed connection 375
21:56:38 INFO - 1501044998267 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50063
21:56:38 INFO - 1501044998268 Marionette DEBUG Closed connection 376
21:56:38 INFO - 1501044998268 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50064
21:56:38 INFO - 1501044998269 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:38 INFO - 1501044998270 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998272 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998274 Marionette TRACE 377 <- [1,1,null,{"sessionId":"81856676-dc5e-449a-98e8-1ac077cea11c","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998275 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:56:38 INFO - 1501044998277 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998279 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998279 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998285 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:56:38 INFO - 1501044998287 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:56:38 INFO - 1501044998298 Marionette TRACE 377 <- [1,2,null,{}]
21:56:38 INFO - 1501044998315 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:56:38 INFO - 1501044998317 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d658a8a-8b95-45a2-a27d-d49db71d910f","ELEMENT":"8d658a8a-8b95-45a2-a27d-d49db71d910f"}}]
21:56:38 INFO - 1501044998318 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"8d658a8a-8b95-45a2-a27d-d49db71d910f"}]
21:56:38 INFO - 1501044998559 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:56:38 INFO - 1501044998559 Marionette TRACE 377 <- [1,4,null,{}]
21:56:38 INFO - 1501044998561 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:56:38 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501044998564 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998565 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:56:38 INFO - 1501044998566 Marionette TRACE 377 <- [1,6,null,{}]
21:56:38 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 332ms
21:56:38 INFO - 1501044998567 Marionette DEBUG Closed connection 377
21:56:38 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:56:38 INFO - 1501044998569 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50065
21:56:38 INFO - 1501044998570 Marionette DEBUG Closed connection 378
21:56:38 INFO - 1501044998571 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50066
21:56:38 INFO - 1501044998572 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998572 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998574 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998577 Marionette TRACE 379 <- [1,1,null,{"sessionId":"b2ccc17a-bc91-4e6b-a088-9693bfaba125","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998578 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:56:38 INFO - 1501044998578 Marionette TRACE 379 <- [1,2,null,{}]
21:56:38 INFO - 1501044998579 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:38 INFO - 1501044998580 Marionette TRACE 379 <- [1,3,null,{}]
21:56:38 INFO - 1501044998580 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:38 INFO - 1501044998581 Marionette TRACE 379 <- [1,4,null,{}]
21:56:38 INFO - 1501044998583 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:56:38 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501044998585 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998586 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:56:38 INFO - 1501044998587 Marionette TRACE 379 <- [1,6,null,{}]
21:56:38 INFO - 1501044998588 Marionette DEBUG Closed connection 379
21:56:38 INFO - 1501044998589 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50067
21:56:38 INFO - 1501044998590 Marionette DEBUG Closed connection 380
21:56:38 INFO - 1501044998591 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50068
21:56:38 INFO - 1501044998591 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998592 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998594 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998596 Marionette TRACE 381 <- [1,1,null,{"sessionId":"8358f80f-5d7d-44b4-8226-e7dacd102432","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998597 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:56:38 INFO - 1501044998598 Marionette TRACE 381 <- [1,2,null,{}]
21:56:38 INFO - 1501044998600 Marionette DEBUG Closed connection 381
21:56:38 INFO - 1501044998600 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50069
21:56:38 INFO - 1501044998601 Marionette DEBUG Closed connection 382
21:56:38 INFO - 1501044998602 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50070
21:56:38 INFO - 1501044998603 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:38 INFO - 1501044998603 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998605 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998608 Marionette TRACE 383 <- [1,1,null,{"sessionId":"b7891199-2c53-4d4c-bf3c-5dc28ef0dd96","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998609 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:56:38 INFO - 1501044998610 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:56:38 INFO - 1501044998612 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:56:38 INFO - 1501044998612 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:56:38 INFO - 1501044998618 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998620 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998623 Marionette TRACE 383 <- [1,2,null,{}]
21:56:38 INFO - 1501044998631 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:56:38 INFO - 1501044998635 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9986ce6-8f6c-4e1f-9a3e-c0239def8a71","ELEMENT":"d9986ce6-8f6c-4e1f-9a3e-c0239def8a71"}}]
21:56:38 INFO - 1501044998636 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"d9986ce6-8f6c-4e1f-9a3e-c0239def8a71"}]
21:56:38 INFO - 1501044998876 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:56:38 INFO - 1501044998877 Marionette TRACE 383 <- [1,4,null,{}]
21:56:38 INFO - 1501044998878 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:56:38 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501044998881 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998882 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:56:38 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 317ms
21:56:38 INFO - TEST-START | test_click.py TestClick.test_input_file
21:56:38 INFO - 1501044998883 Marionette TRACE 383 <- [1,6,null,{}]
21:56:38 INFO - 1501044998885 Marionette DEBUG Closed connection 383
21:56:38 INFO - 1501044998886 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50071
21:56:38 INFO - 1501044998886 Marionette DEBUG Closed connection 384
21:56:38 INFO - 1501044998887 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50072
21:56:38 INFO - 1501044998888 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998889 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998891 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998894 Marionette TRACE 385 <- [1,1,null,{"sessionId":"08743be0-af78-45b8-8ff9-ef7973791295","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998895 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:56:38 INFO - 1501044998895 Marionette TRACE 385 <- [1,2,null,{}]
21:56:38 INFO - 1501044998896 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:38 INFO - 1501044998896 Marionette TRACE 385 <- [1,3,null,{}]
21:56:38 INFO - 1501044998897 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:38 INFO - 1501044998898 Marionette TRACE 385 <- [1,4,null,{}]
21:56:38 INFO - 1501044998899 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:56:38 INFO - TEST-START: test_click.py TestClick.test_input_file1501044998902 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998903 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:56:38 INFO - 1501044998904 Marionette TRACE 385 <- [1,6,null,{}]
21:56:38 INFO - 1501044998905 Marionette DEBUG Closed connection 385
21:56:38 INFO - 1501044998906 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50073
21:56:38 INFO - 1501044998906 Marionette DEBUG Closed connection 386
21:56:38 INFO - 1501044998907 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50074
21:56:38 INFO - 1501044998908 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998909 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998910 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998913 Marionette TRACE 387 <- [1,1,null,{"sessionId":"f3beaebc-29ac-4983-a56f-5cc3939d4d12","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998914 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:56:38 INFO - 1501044998915 Marionette TRACE 387 <- [1,2,null,{}]
21:56:38 INFO - 1501044998917 Marionette DEBUG Closed connection 387
21:56:38 INFO - 1501044998917 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50075
21:56:38 INFO - 1501044998918 Marionette DEBUG Closed connection 388
21:56:38 INFO - 1501044998919 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50076
21:56:38 INFO - 1501044998920 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:38 INFO - 1501044998920 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998922 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998924 Marionette TRACE 389 <- [1,1,null,{"sessionId":"907d3e59-7731-4851-a802-5318c96a0558","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998925 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:56:38 INFO - 1501044998927 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998930 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998930 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:56:38 INFO - 1501044998937 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:56:38 INFO - 1501044998943 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:56:38 INFO - 1501044998946 Marionette TRACE 389 <- [1,2,null,{}]
21:56:38 INFO - 1501044998949 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:56:38 INFO - 1501044998952 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"71f7294f-242e-41a7-8f40-6acb7cccac7b","ELEMENT":"71f7294f-242e-41a7-8f40-6acb7cccac7b"}}]
21:56:38 INFO - 1501044998952 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"71f7294f-242e-41a7-8f40-6acb7cccac7b"}]
21:56:38 INFO - 1501044998976 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:56:38 INFO - 1501044998977 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:56:38 INFO - TEST-END: test_click.py TestClick.test_input_file1501044998981 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:56:38 INFO - 1501044998982 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:56:38 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 100ms
21:56:38 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:56:38 INFO - 1501044998984 Marionette TRACE 389 <- [1,6,null,{}]
21:56:38 INFO - 1501044998985 Marionette DEBUG Closed connection 389
21:56:38 INFO - 1501044998986 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50077
21:56:38 INFO - 1501044998987 Marionette DEBUG Closed connection 390
21:56:38 INFO - 1501044998988 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50078
21:56:38 INFO - 1501044998989 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:38 INFO - 1501044998989 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:38 INFO - 1501044998991 Marionette DEBUG Register listener.js for window 4294967297
21:56:38 INFO - 1501044998994 Marionette TRACE 391 <- [1,1,null,{"sessionId":"b6f5c1dd-1d6f-4625-9241-a012c4f26078","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:38 INFO - 1501044998995 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:56:38 INFO - 1501044998995 Marionette TRACE 391 <- [1,2,null,{}]
21:56:38 INFO - 1501044998996 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:39 INFO - 1501044998997 Marionette TRACE 391 <- [1,3,null,{}]
21:56:39 INFO - 1501044998997 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:39 INFO - 1501044998998 Marionette TRACE 391 <- [1,4,null,{}]
21:56:39 INFO - 1501044999000 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:56:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501044999002 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:56:39 INFO - 1501044999003 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:56:39 INFO - 1501044999004 Marionette TRACE 391 <- [1,6,null,{}]
21:56:39 INFO - 1501044999005 Marionette DEBUG Closed connection 391
21:56:39 INFO - 1501044999006 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50079
21:56:39 INFO - 1501044999007 Marionette DEBUG Closed connection 392
21:56:39 INFO - 1501044999008 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50080
21:56:39 INFO - 1501044999008 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:39 INFO - 1501044999009 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:39 INFO - 1501044999011 Marionette DEBUG Register listener.js for window 4294967297
21:56:39 INFO - 1501044999013 Marionette TRACE 393 <- [1,1,null,{"sessionId":"de0a0352-7545-403e-8bc4-4892a700b772","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:39 INFO - 1501044999014 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:56:39 INFO - 1501044999015 Marionette TRACE 393 <- [1,2,null,{}]
21:56:39 INFO - 1501044999017 Marionette DEBUG Closed connection 393
21:56:39 INFO - 1501044999018 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50081
21:56:39 INFO - 1501044999018 Marionette DEBUG Closed connection 394
21:56:39 INFO - 1501044999019 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50082
21:56:39 INFO - 1501044999020 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:39 INFO - 1501044999020 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:39 INFO - 1501044999022 Marionette DEBUG Register listener.js for window 4294967297
21:56:39 INFO - 1501044999025 Marionette TRACE 395 <- [1,1,null,{"sessionId":"1fd18259-3fd0-4642-b5ab-2cd404847ae3","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:39 INFO - 1501044999026 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:56:39 INFO - 1501044999027 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:56:39 INFO - 1501044999029 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:56:39 INFO - 1501044999029 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:56:39 INFO - 1501044999035 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:56:39 INFO - 1501044999037 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:56:39 INFO - 1501044999045 Marionette TRACE 395 <- [1,2,null,{}]
21:56:39 INFO - 1501044999048 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:56:39 INFO - 1501044999051 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3d564cdb-d1e7-4021-ac27-81a86bd7d39c","ELEMENT":"3d564cdb-d1e7-4021-ac27-81a86bd7d39c"}}]
21:56:39 INFO - 1501044999052 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:56:39 INFO - 1501044999054 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c46e4085-c2d4-4032-94f0-aae74e0c0b13","ELEMENT":"c46e4085-c2d4-4032-94f0-aae74e0c0b13"}}]
21:56:39 INFO - 1501044999055 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"3d564cdb-d1e7-4021-ac27-81a86bd7d39c"}]
21:56:39 INFO - 1501044999295 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:56:39 INFO - 1501044999295 Marionette TRACE 395 <- [1,5,null,{}]
21:56:39 INFO - 1501044999296 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"c46e4085-c2d4-4032-94f0-aae74e0c0b13"}]
21:56:39 INFO - 1501044999310 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:56:39 INFO - 1501044999312 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:56:39 INFO - 1501044999314 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:56:39 INFO - 1501044999316 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:56:39 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501044999318 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:56:39 INFO - 1501044999319 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:56:39 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 336ms
21:56:39 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:56:39 INFO - 1501044999321 Marionette TRACE 395 <- [1,9,null,{}]
21:56:39 INFO - 1501044999322 Marionette DEBUG Closed connection 395
21:56:39 INFO - 1501044999323 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50083
21:56:39 INFO - 1501044999324 Marionette DEBUG Closed connection 396
21:56:39 INFO - 1501044999324 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50084
21:56:39 INFO - 1501044999325 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:39 INFO - 1501044999334 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:39 INFO - 1501044999336 Marionette DEBUG Register listener.js for window 4294967297
21:56:39 INFO - 1501044999339 Marionette TRACE 397 <- [1,1,null,{"sessionId":"2bcea11c-4c8b-44c4-8304-379e0d59c9c6","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:39 INFO - 1501044999340 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:56:39 INFO - 1501044999341 Marionette TRACE 397 <- [1,2,null,{}]
21:56:39 INFO - 1501044999341 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:56:39 INFO - 1501044999342 Marionette TRACE 397 <- [1,3,null,{}]
21:56:39 INFO - 1501044999343 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:56:39 INFO - 1501044999343 Marionette TRACE 397 <- [1,4,null,{}]
21:56:39 INFO - 1501044999345 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:56:39 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501044999347 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:56:39 INFO - 1501044999348 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:56:39 INFO - 1501044999349 Marionette TRACE 397 <- [1,6,null,{}]
21:56:39 INFO - 1501044999350 Marionette DEBUG Closed connection 397
21:56:39 INFO - 1501044999351 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50085
21:56:39 INFO - 1501044999352 Marionette DEBUG Closed connection 398
21:56:39 INFO - 1501044999353 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50086
21:56:39 INFO - 1501044999353 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:56:39 INFO - 1501044999354 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:39 INFO - 1501044999355 Marionette DEBUG Register listener.js for window 4294967297
21:56:39 INFO - 1501044999358 Marionette TRACE 399 <- [1,1,null,{"sessionId":"fa005142-2eec-4f92-b60e-8e47aa2c653c","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:39 INFO - 1501044999359 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:56:39 INFO - 1501044999360 Marionette TRACE 399 <- [1,2,null,{}]
21:56:39 INFO - 1501044999362 Marionette DEBUG Closed connection 399
21:56:39 INFO - 1501044999362 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50087
21:56:39 INFO - 1501044999363 Marionette DEBUG Closed connection 400
21:56:39 INFO - 1501044999364 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50088
21:56:39 INFO - 1501044999365 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:56:39 INFO - 1501044999365 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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}
21:56:39 INFO - 1501044999367 Marionette DEBUG Register listener.js for window 4294967297
21:56:39 INFO - 1501044999369 Marionette TRACE 401 <- [1,1,null,{"sessionId":"44285e62-2d77-4112-bdf0-76b0a97fe96a","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":5552,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbicjk.mozrunner","moz:accessibilityChecks":false}}]
21:56:39 INFO - 1501044999371 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:56:39 INFO - 1501044999372 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:56:39 INFO - 1501044999374 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:56:39 INFO - 1501044999375 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:56:39 INFO - 1501044999381 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:56:39 INFO - 1501044999382 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:56:39 INFO - 1501044999386 Marionette TRACE 401 <- [1,2,null,{}]
21:56:39 INFO - 1501044999395 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:56:39 INFO - 1501044999398 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6be58eb9-0a4e-4c8b-98ab-12231b0bdac2","ELEMENT":"6be58eb9-0a4e-4c8b-98ab-12231b0bdac2"}}]
21:56:39 INFO - 1501044999399 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6be58eb9-0a4e-4c8b-98ab-12231b0bdac2"}]
21:56:39 INFO - 1501044999419 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:56:39 INFO - 1501044999420 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"6be58eb9-0a4e-4c8b-98ab-12231b0bdac2"}]
21:56:39 INFO - 1501044999435 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element