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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:07:56 INFO - 1499278076411 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}]
11:07:56 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499278076415 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:07:56 INFO - 1499278076416 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:07:56 INFO - 1499278076418 Marionette TRACE 352 <- [1,6,null,{}]
11:07:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 185ms
11:07:56 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:07:56 INFO - 1499278076421 Marionette DEBUG Closed connection 352
11:07:56 INFO - 1499278076422 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49866
11:07:56 INFO - 1499278076423 Marionette DEBUG Closed connection 353
11:07:56 INFO - 1499278076424 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49867
11:07:56 INFO - 1499278076425 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:56 INFO - 1499278076425 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076428 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076432 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1e395218-1046-4a75-a8ab-ccf1bc6447b5","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076433 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:07:56 INFO - 1499278076434 Marionette TRACE 354 <- [1,2,null,{}]
11:07:56 INFO - 1499278076435 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:56 INFO - 1499278076436 Marionette TRACE 354 <- [1,3,null,{}]
11:07:56 INFO - 1499278076437 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:56 INFO - 1499278076437 Marionette TRACE 354 <- [1,4,null,{}]
11:07:56 INFO - 1499278076440 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}]
11:07:56 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499278076443 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:07:56 INFO - 1499278076444 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:07:56 INFO - 1499278076445 Marionette TRACE 354 <- [1,6,null,{}]
11:07:56 INFO - 1499278076448 Marionette DEBUG Closed connection 354
11:07:56 INFO - 1499278076449 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49868
11:07:56 INFO - 1499278076449 Marionette DEBUG Closed connection 355
11:07:56 INFO - 1499278076450 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49869
11:07:56 INFO - 1499278076451 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:56 INFO - 1499278076452 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076455 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076458 Marionette TRACE 356 <- [1,1,null,{"sessionId":"2757ddac-039c-4d85-9346-badef2dbe4bb","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076459 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:07:56 INFO - 1499278076461 Marionette TRACE 356 <- [1,2,null,{}]
11:07:56 INFO - 1499278076463 Marionette DEBUG Closed connection 356
11:07:56 INFO - 1499278076464 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49870
11:07:56 INFO - 1499278076465 Marionette DEBUG Closed connection 357
11:07:56 INFO - 1499278076466 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49871
11:07:56 INFO - 1499278076467 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:56 INFO - 1499278076467 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076470 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076474 Marionette TRACE 358 <- [1,1,null,{"sessionId":"014bab76-72ea-4a13-834c-22d417833ac0","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076475 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49432/clicks.html"}]
11:07:56 INFO - 1499278076476 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/hidden.html"
11:07:56 INFO - 1499278076482 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/hidden.html"
11:07:56 INFO - 1499278076483 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/hidden.html"
11:07:56 INFO - 1499278076508 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49432/clicks.html"
11:07:56 INFO - 1499278076510 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49432/clicks.html"
11:07:56 INFO - 1499278076522 Marionette TRACE 358 <- [1,2,null,{}]
11:07:56 INFO - 1499278076523 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:07:56 INFO - 1499278076526 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0fc102b7-302a-4749-9aac-73bf6d6bb7b6","ELEMENT":"0fc102b7-302a-4749-9aac-73bf6d6bb7b6"}}]
11:07:56 INFO - 1499278076528 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0fc102b7-302a-4749-9aac-73bf6d6bb7b6"}]
11:07:56 INFO - 1499278076577 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/clicks.html"
11:07:56 INFO - 1499278076591 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/clicks.html"
11:07:56 INFO - 1499278076592 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/clicks.html"
11:07:56 INFO - 1499278076625 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49432/test.html"
11:07:56 INFO - 1499278076630 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49432/test.html"
11:07:56 INFO - 1499278076642 Marionette TRACE 358 <- [1,4,null,{}]
11:07:56 INFO - 1499278076647 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:07:56 INFO - 1499278076650 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"076d803c-3210-4175-9c6f-8430562ddd2f","ELEMENT":"076d803c-3210-4175-9c6f-8430562ddd2f"}}]
11:07:56 INFO - 1499278076651 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:07:56 INFO - 1499278076652 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:07:56 INFO - 1499278076659 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}]
11:07:56 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499278076662 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:07:56 INFO - 1499278076663 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:07:56 INFO - 1499278076664 Marionette TRACE 358 <- [1,8,null,{}]
11:07:56 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 246ms
11:07:56 INFO - TEST-START | test_click.py TestClick.test_container_element
11:07:56 INFO - 1499278076667 Marionette DEBUG Closed connection 358
11:07:56 INFO - 1499278076668 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49874
11:07:56 INFO - 1499278076669 Marionette DEBUG Closed connection 359
11:07:56 INFO - 1499278076670 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49875
11:07:56 INFO - 1499278076671 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:56 INFO - 1499278076671 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076674 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076678 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b6947c23-7eec-492b-b23e-ed5a0484911c","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076679 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:07:56 INFO - 1499278076680 Marionette TRACE 360 <- [1,2,null,{}]
11:07:56 INFO - 1499278076681 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:56 INFO - 1499278076681 Marionette TRACE 360 <- [1,3,null,{}]
11:07:56 INFO - 1499278076682 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:56 INFO - 1499278076683 Marionette TRACE 360 <- [1,4,null,{}]
11:07:56 INFO - 1499278076685 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}]
11:07:56 INFO - TEST-START: test_click.py TestClick.test_container_element1499278076688 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:07:56 INFO - 1499278076689 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:07:56 INFO - 1499278076690 Marionette TRACE 360 <- [1,6,null,{}]
11:07:56 INFO - 1499278076692 Marionette DEBUG Closed connection 360
11:07:56 INFO - 1499278076693 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49876
11:07:56 INFO - 1499278076694 Marionette DEBUG Closed connection 361
11:07:56 INFO - 1499278076695 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49877
11:07:56 INFO - 1499278076696 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:56 INFO - 1499278076696 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076699 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076702 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0785eabc-720d-4eec-a653-873e3450388d","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076703 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:07:56 INFO - 1499278076705 Marionette TRACE 362 <- [1,2,null,{}]
11:07:56 INFO - 1499278076707 Marionette DEBUG Closed connection 362
11:07:56 INFO - 1499278076708 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49878
11:07:56 INFO - 1499278076709 Marionette DEBUG Closed connection 363
11:07:56 INFO - 1499278076710 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49879
11:07:56 INFO - 1499278076711 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:56 INFO - 1499278076711 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:56 INFO - 1499278076714 Marionette DEBUG Register listener.js for window 12
11:07:56 INFO - 1499278076718 Marionette TRACE 364 <- [1,1,null,{"sessionId":"84eceed9-b03d-4e1c-bba6-357cf6801935","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:56 INFO - 1499278076719 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"}]
11:07:56 INFO - 1499278076720 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/test.html"
11:07:56 INFO - 1499278076723 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/test.html"
11:07:56 INFO - 1499278076724 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/test.html"
11:07:56 INFO - 1499278076750 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"
11:07:56 INFO - 1499278076755 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"
11:07:56 INFO - 1499278076759 Marionette TRACE 364 <- [1,2,null,{}]
11:07:56 INFO - 1499278076760 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:07:56 INFO - 1499278076770 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7c1aadc-fcaa-40fb-89d8-838b533124c4","ELEMENT":"c7c1aadc-fcaa-40fb-89d8-838b533124c4"}}]
11:07:56 INFO - 1499278076773 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c7c1aadc-fcaa-40fb-89d8-838b533124c4"}]
11:07:57 INFO - 1499278077020 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:07:57 INFO - 1499278077022 Marionette TRACE 364 <- [1,4,null,{}]
11:07:57 INFO - 1499278077023 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c7c1aadc-fcaa-40fb-89d8-838b533124c4","name":"selected"}]
11:07:57 INFO - 1499278077029 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:07:57 INFO - 1499278077031 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}]
11:07:57 INFO - TEST-END: test_click.py TestClick.test_container_element1499278077034 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:07:57 INFO - 1499278077035 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:07:57 INFO - 1499278077037 Marionette TRACE 364 <- [1,7,null,{}]
11:07:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 372ms
11:07:57 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:07:57 INFO - 1499278077040 Marionette DEBUG Closed connection 364
11:07:57 INFO - 1499278077041 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49880
11:07:57 INFO - 1499278077042 Marionette DEBUG Closed connection 365
11:07:57 INFO - 1499278077043 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49881
11:07:57 INFO - 1499278077044 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077045 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077048 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077051 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c3ea76cf-0dcb-4049-94b0-057bff66869d","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077054 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:07:57 INFO - 1499278077054 Marionette TRACE 366 <- [1,2,null,{}]
11:07:57 INFO - 1499278077055 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:57 INFO - 1499278077056 Marionette TRACE 366 <- [1,3,null,{}]
11:07:57 INFO - 1499278077057 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:57 INFO - 1499278077057 Marionette TRACE 366 <- [1,4,null,{}]
11:07:57 INFO - 1499278077059 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}]
11:07:57 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499278077062 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:07:57 INFO - 1499278077063 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:07:57 INFO - 1499278077064 Marionette TRACE 366 <- [1,6,null,{}]
11:07:57 INFO - 1499278077067 Marionette DEBUG Closed connection 366
11:07:57 INFO - 1499278077067 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49882
11:07:57 INFO - 1499278077068 Marionette DEBUG Closed connection 367
11:07:57 INFO - 1499278077069 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49883
11:07:57 INFO - 1499278077070 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077071 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077074 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077077 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3961c86e-2424-4b31-901b-c67570d26662","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077078 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:07:57 INFO - 1499278077080 Marionette TRACE 368 <- [1,2,null,{}]
11:07:57 INFO - 1499278077082 Marionette DEBUG Closed connection 368
11:07:57 INFO - 1499278077083 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49884
11:07:57 INFO - 1499278077084 Marionette DEBUG Closed connection 369
11:07:57 INFO - 1499278077084 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49885
11:07:57 INFO - 1499278077086 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:57 INFO - 1499278077086 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077089 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077092 Marionette TRACE 370 <- [1,1,null,{"sessionId":"6e604f5c-1802-46bd-b06b-781eff13a5ca","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077095 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"}]
11:07:57 INFO - 1499278077097 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"
11:07:57 INFO - 1499278077100 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"
11:07:57 INFO - 1499278077101 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"
11:07:57 INFO - 1499278077120 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"
11:07:57 INFO - 1499278077124 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"
11:07:57 INFO - 1499278077128 Marionette TRACE 370 <- [1,2,null,{}]
11:07:57 INFO - 1499278077135 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:07:57 INFO - 1499278077138 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"37251e14-20dd-4573-a74e-fb57fd458325","ELEMENT":"37251e14-20dd-4573-a74e-fb57fd458325"}}]
11:07:57 INFO - 1499278077139 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"37251e14-20dd-4573-a74e-fb57fd458325"}]
11:07:57 INFO - 1499278077392 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:07:57 INFO - 1499278077393 Marionette TRACE 370 <- [1,4,null,{}]
11:07:57 INFO - 1499278077394 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"37251e14-20dd-4573-a74e-fb57fd458325","name":"selected"}]
11:07:57 INFO - 1499278077399 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:07:57 INFO - 1499278077401 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}]
11:07:57 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499278077404 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:07:57 INFO - 1499278077405 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:07:57 INFO - 1499278077406 Marionette TRACE 370 <- [1,7,null,{}]
11:07:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
11:07:57 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:07:57 INFO - 1499278077408 Marionette DEBUG Closed connection 370
11:07:57 INFO - 1499278077409 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49886
11:07:57 INFO - 1499278077410 Marionette DEBUG Closed connection 371
11:07:57 INFO - 1499278077411 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49887
11:07:57 INFO - 1499278077412 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077413 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077416 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077419 Marionette TRACE 372 <- [1,1,null,{"sessionId":"62844561-63d0-4daa-b9ba-018b1325322e","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077420 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:07:57 INFO - 1499278077421 Marionette TRACE 372 <- [1,2,null,{}]
11:07:57 INFO - 1499278077422 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:57 INFO - 1499278077423 Marionette TRACE 372 <- [1,3,null,{}]
11:07:57 INFO - 1499278077424 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:57 INFO - 1499278077424 Marionette TRACE 372 <- [1,4,null,{}]
11:07:57 INFO - 1499278077426 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}]
11:07:57 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499278077429 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:07:57 INFO - 1499278077430 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:07:57 INFO - 1499278077431 Marionette TRACE 372 <- [1,6,null,{}]
11:07:57 INFO - 1499278077433 Marionette DEBUG Closed connection 372
11:07:57 INFO - 1499278077434 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49888
11:07:57 INFO - 1499278077435 Marionette DEBUG Closed connection 373
11:07:57 INFO - 1499278077436 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49889
11:07:57 INFO - 1499278077437 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077437 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077440 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077444 Marionette TRACE 374 <- [1,1,null,{"sessionId":"87b7bfe5-775d-4026-b76f-14c75329195d","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077445 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:07:57 INFO - 1499278077446 Marionette TRACE 374 <- [1,2,null,{}]
11:07:57 INFO - 1499278077448 Marionette DEBUG Closed connection 374
11:07:57 INFO - 1499278077449 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49890
11:07:57 INFO - 1499278077450 Marionette DEBUG Closed connection 375
11:07:57 INFO - 1499278077451 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49891
11:07:57 INFO - 1499278077452 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:57 INFO - 1499278077453 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077455 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077459 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8f3a9a6e-5563-439b-9031-515c24949c98","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077460 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"}]
11:07:57 INFO - 1499278077462 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"
11:07:57 INFO - 1499278077467 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"
11:07:57 INFO - 1499278077467 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"
11:07:57 INFO - 1499278077489 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"
11:07:57 INFO - 1499278077493 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"
11:07:57 INFO - 1499278077504 Marionette TRACE 376 <- [1,2,null,{}]
11:07:57 INFO - 1499278077506 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:07:57 INFO - 1499278077509 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b17ffd6d-7b87-4a65-8d72-8438904a1ca8","ELEMENT":"b17ffd6d-7b87-4a65-8d72-8438904a1ca8"}}]
11:07:57 INFO - 1499278077510 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b17ffd6d-7b87-4a65-8d72-8438904a1ca8"}]
11:07:57 INFO - 1499278077753 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:07:57 INFO - 1499278077753 Marionette TRACE 376 <- [1,4,null,{}]
11:07:57 INFO - 1499278077755 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}]
11:07:57 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499278077759 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:07:57 INFO - 1499278077760 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:07:57 INFO - 1499278077761 Marionette TRACE 376 <- [1,6,null,{}]
11:07:57 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 355ms
11:07:57 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:07:57 INFO - 1499278077764 Marionette DEBUG Closed connection 376
11:07:57 INFO - 1499278077765 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49892
11:07:57 INFO - 1499278077765 Marionette DEBUG Closed connection 377
11:07:57 INFO - 1499278077766 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49893
11:07:57 INFO - 1499278077767 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077768 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077771 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077774 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0d62efa3-436d-41ca-8d47-5398f183664c","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077776 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:07:57 INFO - 1499278077776 Marionette TRACE 378 <- [1,2,null,{}]
11:07:57 INFO - 1499278077777 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:57 INFO - 1499278077778 Marionette TRACE 378 <- [1,3,null,{}]
11:07:57 INFO - 1499278077779 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:57 INFO - 1499278077779 Marionette TRACE 378 <- [1,4,null,{}]
11:07:57 INFO - 1499278077781 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}]
11:07:57 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499278077784 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:07:57 INFO - 1499278077785 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:07:57 INFO - 1499278077786 Marionette TRACE 378 <- [1,6,null,{}]
11:07:57 INFO - 1499278077789 Marionette DEBUG Closed connection 378
11:07:57 INFO - 1499278077789 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49894
11:07:57 INFO - 1499278077790 Marionette DEBUG Closed connection 379
11:07:57 INFO - 1499278077791 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49895
11:07:57 INFO - 1499278077792 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:57 INFO - 1499278077793 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077796 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077799 Marionette TRACE 380 <- [1,1,null,{"sessionId":"aa7d7f07-2968-40b6-9425-22eee345271f","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077800 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:07:57 INFO - 1499278077802 Marionette TRACE 380 <- [1,2,null,{}]
11:07:57 INFO - 1499278077804 Marionette DEBUG Closed connection 380
11:07:57 INFO - 1499278077805 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49896
11:07:57 INFO - 1499278077806 Marionette DEBUG Closed connection 381
11:07:57 INFO - 1499278077806 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49897
11:07:57 INFO - 1499278077807 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:57 INFO - 1499278077808 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:57 INFO - 1499278077811 Marionette DEBUG Register listener.js for window 12
11:07:57 INFO - 1499278077814 Marionette TRACE 382 <- [1,1,null,{"sessionId":"94e9c219-ae43-4a26-a49e-5f4919edf2c5","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:57 INFO - 1499278077815 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"}]
11:07:57 INFO - 1499278077817 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"
11:07:57 INFO - 1499278077820 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"
11:07:57 INFO - 1499278077820 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"
11:07:57 INFO - 1499278077841 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"
11:07:57 INFO - 1499278077846 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"
11:07:57 INFO - 1499278077857 Marionette TRACE 382 <- [1,2,null,{}]
11:07:57 INFO - 1499278077858 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:07:57 INFO - 1499278077861 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d61f20b4-1a38-4696-8274-37223ecf35a8","ELEMENT":"d61f20b4-1a38-4696-8274-37223ecf35a8"}}]
11:07:57 INFO - 1499278077863 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d61f20b4-1a38-4696-8274-37223ecf35a8"}]
11:07:58 INFO - 1499278078115 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:07:58 INFO - 1499278078115 Marionette TRACE 382 <- [1,4,null,{}]
11:07:58 INFO - 1499278078117 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}]
11:07:58 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499278078121 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:07:58 INFO - 1499278078122 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:07:58 INFO - 1499278078123 Marionette TRACE 382 <- [1,6,null,{}]
11:07:58 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 361ms
11:07:58 INFO - TEST-START | test_click.py TestClick.test_input_file
11:07:58 INFO - 1499278078125 Marionette DEBUG Closed connection 382
11:07:58 INFO - 1499278078126 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49898
11:07:58 INFO - 1499278078128 Marionette DEBUG Closed connection 383
11:07:58 INFO - 1499278078128 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49899
11:07:58 INFO - 1499278078129 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078130 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078133 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078137 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a890ea38-5933-4d47-aab5-87c2a2160307","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078138 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:07:58 INFO - 1499278078138 Marionette TRACE 384 <- [1,2,null,{}]
11:07:58 INFO - 1499278078139 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:58 INFO - 1499278078140 Marionette TRACE 384 <- [1,3,null,{}]
11:07:58 INFO - 1499278078141 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:58 INFO - 1499278078141 Marionette TRACE 384 <- [1,4,null,{}]
11:07:58 INFO - 1499278078143 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}]
11:07:58 INFO - TEST-START: test_click.py TestClick.test_input_file1499278078146 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:07:58 INFO - 1499278078147 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:07:58 INFO - 1499278078148 Marionette TRACE 384 <- [1,6,null,{}]
11:07:58 INFO - 1499278078151 Marionette DEBUG Closed connection 384
11:07:58 INFO - 1499278078151 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49900
11:07:58 INFO - 1499278078152 Marionette DEBUG Closed connection 385
11:07:58 INFO - 1499278078153 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49901
11:07:58 INFO - 1499278078154 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078155 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078157 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078161 Marionette TRACE 386 <- [1,1,null,{"sessionId":"68a85966-b027-4481-845b-1ef72dfb614d","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078162 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:07:58 INFO - 1499278078164 Marionette TRACE 386 <- [1,2,null,{}]
11:07:58 INFO - 1499278078166 Marionette DEBUG Closed connection 386
11:07:58 INFO - 1499278078167 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49902
11:07:58 INFO - 1499278078168 Marionette DEBUG Closed connection 387
11:07:58 INFO - 1499278078168 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49903
11:07:58 INFO - 1499278078170 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:58 INFO - 1499278078170 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078173 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078177 Marionette TRACE 388 <- [1,1,null,{"sessionId":"fe6a9030-0f43-4464-9853-9af6b4fc18cd","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078179 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:07:58 INFO - 1499278078181 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"
11:07:58 INFO - 1499278078183 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"
11:07:58 INFO - 1499278078184 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"
11:07:58 INFO - 1499278078204 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:07:58 INFO - 1499278078209 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:07:58 INFO - 1499278078219 Marionette TRACE 388 <- [1,2,null,{}]
11:07:58 INFO - 1499278078229 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:07:58 INFO - 1499278078232 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eda7f410-637e-4f58-ada6-29426422c016","ELEMENT":"eda7f410-637e-4f58-ada6-29426422c016"}}]
11:07:58 INFO - 1499278078234 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"eda7f410-637e-4f58-ada6-29426422c016"}]
11:07:58 INFO - 1499278078262 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:07:58 INFO - 1499278078264 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}]
11:07:58 INFO - TEST-END: test_click.py TestClick.test_input_file1499278078267 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:07:58 INFO - 1499278078268 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:07:58 INFO - 1499278078269 Marionette TRACE 388 <- [1,6,null,{}]
11:07:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 146ms
11:07:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:07:58 INFO - 1499278078272 Marionette DEBUG Closed connection 388
11:07:58 INFO - 1499278078273 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49904
11:07:58 INFO - 1499278078274 Marionette DEBUG Closed connection 389
11:07:58 INFO - 1499278078274 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49905
11:07:58 INFO - 1499278078275 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078276 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078279 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078283 Marionette TRACE 390 <- [1,1,null,{"sessionId":"91b10361-d6d1-4a2f-8897-73baeda050c6","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078284 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:07:58 INFO - 1499278078284 Marionette TRACE 390 <- [1,2,null,{}]
11:07:58 INFO - 1499278078285 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:58 INFO - 1499278078286 Marionette TRACE 390 <- [1,3,null,{}]
11:07:58 INFO - 1499278078287 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:58 INFO - 1499278078287 Marionette TRACE 390 <- [1,4,null,{}]
11:07:58 INFO - 1499278078289 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}]
11:07:58 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499278078292 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:07:58 INFO - 1499278078293 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:07:58 INFO - 1499278078295 Marionette TRACE 390 <- [1,6,null,{}]
11:07:58 INFO - 1499278078297 Marionette DEBUG Closed connection 390
11:07:58 INFO - 1499278078298 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49906
11:07:58 INFO - 1499278078299 Marionette DEBUG Closed connection 391
11:07:58 INFO - 1499278078299 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49907
11:07:58 INFO - 1499278078300 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078301 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078304 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078307 Marionette TRACE 392 <- [1,1,null,{"sessionId":"9e49ec41-d89c-4927-adb4-da640e92bcd7","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078308 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:07:58 INFO - 1499278078310 Marionette TRACE 392 <- [1,2,null,{}]
11:07:58 INFO - 1499278078312 Marionette DEBUG Closed connection 392
11:07:58 INFO - 1499278078313 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49908
11:07:58 INFO - 1499278078313 Marionette DEBUG Closed connection 393
11:07:58 INFO - 1499278078314 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49909
11:07:58 INFO - 1499278078315 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:58 INFO - 1499278078316 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078319 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078322 Marionette TRACE 394 <- [1,1,null,{"sessionId":"84cde106-dff6-4cf3-9eeb-550465b14939","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078323 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"}]
11:07:58 INFO - 1499278078325 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:07:58 INFO - 1499278078328 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:07:58 INFO - 1499278078328 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:07:58 INFO - 1499278078341 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"
11:07:58 INFO - 1499278078346 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"
11:07:58 INFO - 1499278078357 Marionette TRACE 394 <- [1,2,null,{}]
11:07:58 INFO - 1499278078358 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:07:58 INFO - 1499278078363 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2137982-853d-4943-b632-73d57bd9ed62","ELEMENT":"c2137982-853d-4943-b632-73d57bd9ed62"}}]
11:07:58 INFO - 1499278078365 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:07:58 INFO - 1499278078367 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38799d82-e5ab-4edf-b77c-337eb141ffbc","ELEMENT":"38799d82-e5ab-4edf-b77c-337eb141ffbc"}}]
11:07:58 INFO - 1499278078369 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c2137982-853d-4943-b632-73d57bd9ed62"}]
11:07:58 INFO - 1499278078608 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:07:58 INFO - 1499278078609 Marionette TRACE 394 <- [1,5,null,{}]
11:07:58 INFO - 1499278078610 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"38799d82-e5ab-4edf-b77c-337eb141ffbc"}]
11:07:58 INFO - 1499278078628 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:07:58 INFO - 1499278078630 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:07:58 INFO - 1499278078633 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:07:58 INFO - 1499278078635 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}]
11:07:58 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499278078638 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:07:58 INFO - 1499278078639 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:07:58 INFO - 1499278078640 Marionette TRACE 394 <- [1,9,null,{}]
11:07:58 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
11:07:58 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:07:58 INFO - 1499278078642 Marionette DEBUG Closed connection 394
11:07:58 INFO - 1499278078643 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49910
11:07:58 INFO - 1499278078644 Marionette DEBUG Closed connection 395
11:07:58 INFO - 1499278078645 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49911
11:07:58 INFO - 1499278078646 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078647 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078657 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078662 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a15c8d49-d119-4ee6-b06a-79b71dbdbc00","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078663 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:07:58 INFO - 1499278078664 Marionette TRACE 396 <- [1,2,null,{}]
11:07:58 INFO - 1499278078665 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:07:58 INFO - 1499278078665 Marionette TRACE 396 <- [1,3,null,{}]
11:07:58 INFO - 1499278078666 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:07:58 INFO - 1499278078667 Marionette TRACE 396 <- [1,4,null,{}]
11:07:58 INFO - 1499278078669 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}]
11:07:58 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499278078672 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:07:58 INFO - 1499278078673 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:07:58 INFO - 1499278078674 Marionette TRACE 396 <- [1,6,null,{}]
11:07:58 INFO - 1499278078676 Marionette DEBUG Closed connection 396
11:07:58 INFO - 1499278078677 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49912
11:07:58 INFO - 1499278078679 Marionette DEBUG Closed connection 397
11:07:58 INFO - 1499278078679 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49913
11:07:58 INFO - 1499278078681 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:07:58 INFO - 1499278078681 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078684 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078687 Marionette TRACE 398 <- [1,1,null,{"sessionId":"64c241c4-074d-449a-8e7b-30341e72c034","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078688 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:07:58 INFO - 1499278078690 Marionette TRACE 398 <- [1,2,null,{}]
11:07:58 INFO - 1499278078692 Marionette DEBUG Closed connection 398
11:07:58 INFO - 1499278078693 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49914
11:07:58 INFO - 1499278078694 Marionette DEBUG Closed connection 399
11:07:58 INFO - 1499278078694 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49915
11:07:58 INFO - 1499278078696 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:07:58 INFO - 1499278078696 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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}
11:07:58 INFO - 1499278078699 Marionette DEBUG Register listener.js for window 12
11:07:58 INFO - 1499278078702 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5105b5ff-9287-48f8-9d18-5630b58e2879","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":1248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpy0mlqp.mozrunner","moz:accessibilityChecks":false}}]
11:07:58 INFO - 1499278078703 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"}]
11:07:58 INFO - 1499278078705 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"
11:07:58 INFO - 1499278078708 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"
11:07:58 INFO - 1499278078708 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"
11:07:58 INFO - 1499278078731 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"
11:07:58 INFO - 1499278078733 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"
11:07:58 INFO - 1499278078737 Marionette TRACE 400 <- [1,2,null,{}]
11:07:58 INFO - 1499278078738 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:07:58 INFO - 1499278078741 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"408e8fbd-3d69-4eb7-8948-3392b9db5e27","ELEMENT":"408e8fbd-3d69-4eb7-8948-3392b9db5e27"}}]
11:07:58 INFO - 1499278078742 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"408e8fbd-3d69-4eb7-8948-3392b9db5e27"}]
11:07:58 INFO - 1499278078774 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:07:58 INFO - 1499278078775 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"408e8fbd-3d69-4eb7-8948-3392b9db5e27"}]
11:07:58 INFO - 1499278078798 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element