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:1330:5\n"},null]
18:37:57 INFO - 1499132277399 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}]
18:37:57 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 150ms
18:37:57 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:37:57 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499132277408 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:37:57 INFO - 1499132277409 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:37:57 INFO - 1499132277411 Marionette TRACE 352 <- [1,6,null,{}]
18:37:57 INFO - 1499132277412 Marionette DEBUG Closed connection 352
18:37:57 INFO - 1499132277413 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50105
18:37:57 INFO - 1499132277414 Marionette DEBUG Closed connection 353
18:37:57 INFO - 1499132277415 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50106
18:37:57 INFO - 1499132277416 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:57 INFO - 1499132277417 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277436 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277439 Marionette TRACE 354 <- [1,1,null,{"sessionId":"15a002b1-920f-40d6-8e32-911b6e3c2fba","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277441 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:37:57 INFO - 1499132277441 Marionette TRACE 354 <- [1,2,null,{}]
18:37:57 INFO - 1499132277442 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:57 INFO - 1499132277443 Marionette TRACE 354 <- [1,3,null,{}]
18:37:57 INFO - 1499132277444 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:57 INFO - 1499132277444 Marionette TRACE 354 <- [1,4,null,{}]
18:37:57 INFO - 1499132277446 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}]
18:37:57 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499132277449 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:37:57 INFO - 1499132277450 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:37:57 INFO - 1499132277452 Marionette TRACE 354 <- [1,6,null,{}]
18:37:57 INFO - 1499132277454 Marionette DEBUG Closed connection 354
18:37:57 INFO - 1499132277455 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50107
18:37:57 INFO - 1499132277455 Marionette DEBUG Closed connection 355
18:37:57 INFO - 1499132277456 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50108
18:37:57 INFO - 1499132277457 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:57 INFO - 1499132277458 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277461 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277464 Marionette TRACE 356 <- [1,1,null,{"sessionId":"797b9b5d-4e68-4904-af1c-8b325085a4f6","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277466 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:37:57 INFO - 1499132277467 Marionette TRACE 356 <- [1,2,null,{}]
18:37:57 INFO - 1499132277469 Marionette DEBUG Closed connection 356
18:37:57 INFO - 1499132277470 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50109
18:37:57 INFO - 1499132277470 Marionette DEBUG Closed connection 357
18:37:57 INFO - 1499132277471 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50110
18:37:57 INFO - 1499132277472 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:57 INFO - 1499132277473 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277476 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277479 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c3313816-d3a0-4389-a20a-02db30b73736","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277480 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49660/clicks.html"}]
18:37:57 INFO - 1499132277482 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49660/hidden.html"
18:37:57 INFO - 1499132277492 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49660/hidden.html"
18:37:57 INFO - 1499132277492 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49660/hidden.html"
18:37:57 INFO - 1499132277511 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49660/clicks.html"
18:37:57 INFO - 1499132277514 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49660/clicks.html"
18:37:57 INFO - 1499132277523 Marionette TRACE 358 <- [1,2,null,{}]
18:37:57 INFO - 1499132277531 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:37:57 INFO - 1499132277539 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"baae79f4-d7c5-4a65-bc9e-3249c8e947de","ELEMENT":"baae79f4-d7c5-4a65-bc9e-3249c8e947de"}}]
18:37:57 INFO - 1499132277541 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"baae79f4-d7c5-4a65-bc9e-3249c8e947de"}]
18:37:57 INFO - 1499132277594 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49660/clicks.html"
18:37:57 INFO - 1499132277617 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49660/clicks.html"
18:37:57 INFO - 1499132277618 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49660/clicks.html"
18:37:57 INFO - 1499132277628 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49660/test.html"
18:37:57 INFO - 1499132277635 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49660/test.html"
18:37:57 INFO - 1499132277639 Marionette TRACE 358 <- [1,4,null,{}]
18:37:57 INFO - 1499132277644 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:37:57 INFO - 1499132277648 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86252dcf-06cc-4d1f-9c2b-1dd6d6ea992d","ELEMENT":"86252dcf-06cc-4d1f-9c2b-1dd6d6ea992d"}}]
18:37:57 INFO - 1499132277649 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:37:57 INFO - 1499132277651 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:37:57 INFO - 1499132277657 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}]
18:37:57 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 261ms
18:37:57 INFO - TEST-START | test_click.py TestClick.test_container_element
18:37:57 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499132277670 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:37:57 INFO - 1499132277671 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:37:57 INFO - 1499132277672 Marionette TRACE 358 <- [1,8,null,{}]
18:37:57 INFO - 1499132277674 Marionette DEBUG Closed connection 358
18:37:57 INFO - 1499132277675 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50113
18:37:57 INFO - 1499132277676 Marionette DEBUG Closed connection 359
18:37:57 INFO - 1499132277677 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50114
18:37:57 INFO - 1499132277678 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:57 INFO - 1499132277679 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277682 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277686 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cccbe632-8b0a-4bfa-aad0-5236892b3f79","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277687 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:37:57 INFO - 1499132277687 Marionette TRACE 360 <- [1,2,null,{}]
18:37:57 INFO - 1499132277688 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:57 INFO - 1499132277689 Marionette TRACE 360 <- [1,3,null,{}]
18:37:57 INFO - 1499132277690 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:57 INFO - 1499132277690 Marionette TRACE 360 <- [1,4,null,{}]
18:37:57 INFO - 1499132277692 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}]
18:37:57 INFO - TEST-START: test_click.py TestClick.test_container_element1499132277695 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:37:57 INFO - 1499132277696 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:37:57 INFO - 1499132277697 Marionette TRACE 360 <- [1,6,null,{}]
18:37:57 INFO - 1499132277699 Marionette DEBUG Closed connection 360
18:37:57 INFO - 1499132277700 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50115
18:37:57 INFO - 1499132277701 Marionette DEBUG Closed connection 361
18:37:57 INFO - 1499132277702 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50116
18:37:57 INFO - 1499132277703 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:57 INFO - 1499132277703 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277706 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277709 Marionette TRACE 362 <- [1,1,null,{"sessionId":"aeb26331-ad86-4d7f-bb5e-1d88c467e7ad","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277711 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:37:57 INFO - 1499132277712 Marionette TRACE 362 <- [1,2,null,{}]
18:37:57 INFO - 1499132277714 Marionette DEBUG Closed connection 362
18:37:57 INFO - 1499132277715 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50117
18:37:57 INFO - 1499132277715 Marionette DEBUG Closed connection 363
18:37:57 INFO - 1499132277716 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50118
18:37:57 INFO - 1499132277718 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:57 INFO - 1499132277718 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:57 INFO - 1499132277727 Marionette DEBUG Register listener.js for window 4294967297
18:37:57 INFO - 1499132277731 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5fd94ae4-b6a8-4fbd-8253-57f19b8dad5f","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:57 INFO - 1499132277732 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"}]
18:37:57 INFO - 1499132277742 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49660/test.html"
18:37:57 INFO - 1499132277745 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49660/test.html"
18:37:57 INFO - 1499132277745 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49660/test.html"
18:37:57 INFO - 1499132277758 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"
18:37:57 INFO - 1499132277759 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"
18:37:57 INFO - 1499132277770 Marionette TRACE 364 <- [1,2,null,{}]
18:37:57 INFO - 1499132277775 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:37:57 INFO - 1499132277779 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f423e291-a140-4b35-b75d-db659d04e126","ELEMENT":"f423e291-a140-4b35-b75d-db659d04e126"}}]
18:37:57 INFO - 1499132277780 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f423e291-a140-4b35-b75d-db659d04e126"}]
18:37:58 INFO - 1499132278030 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:37:58 INFO - 1499132278032 Marionette TRACE 364 <- [1,4,null,{}]
18:37:58 INFO - 1499132278033 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f423e291-a140-4b35-b75d-db659d04e126","name":"selected"}]
18:37:58 INFO - 1499132278038 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:37:58 INFO - 1499132278040 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}]
18:37:58 INFO - TEST-END: test_click.py TestClick.test_container_element1499132278043 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:37:58 INFO - 1499132278044 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:37:58 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 374ms
18:37:58 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:37:58 INFO - 1499132278046 Marionette TRACE 364 <- [1,7,null,{}]
18:37:58 INFO - 1499132278048 Marionette DEBUG Closed connection 364
18:37:58 INFO - 1499132278049 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50119
18:37:58 INFO - 1499132278050 Marionette DEBUG Closed connection 365
18:37:58 INFO - 1499132278050 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50120
18:37:58 INFO - 1499132278052 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278052 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278055 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278059 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1549693d-862a-45f9-b709-f2b06d8c7581","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278060 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:37:58 INFO - 1499132278061 Marionette TRACE 366 <- [1,2,null,{}]
18:37:58 INFO - 1499132278062 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:58 INFO - 1499132278062 Marionette TRACE 366 <- [1,3,null,{}]
18:37:58 INFO - 1499132278063 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:58 INFO - 1499132278064 Marionette TRACE 366 <- [1,4,null,{}]
18:37:58 INFO - 1499132278066 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}]
18:37:58 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499132278069 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:37:58 INFO - 1499132278070 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:37:58 INFO - 1499132278071 Marionette TRACE 366 <- [1,6,null,{}]
18:37:58 INFO - 1499132278073 Marionette DEBUG Closed connection 366
18:37:58 INFO - 1499132278074 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50121
18:37:58 INFO - 1499132278075 Marionette DEBUG Closed connection 367
18:37:58 INFO - 1499132278075 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50122
18:37:58 INFO - 1499132278076 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278077 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278080 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278083 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c092bdde-1289-4e0f-a7b8-69d94f033e58","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278086 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:37:58 INFO - 1499132278087 Marionette TRACE 368 <- [1,2,null,{}]
18:37:58 INFO - 1499132278089 Marionette DEBUG Closed connection 368
18:37:58 INFO - 1499132278090 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50123
18:37:58 INFO - 1499132278090 Marionette DEBUG Closed connection 369
18:37:58 INFO - 1499132278091 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50124
18:37:58 INFO - 1499132278092 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:58 INFO - 1499132278093 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278096 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278099 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3e68936c-a74c-4d46-86a8-27b19050a8d1","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278100 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"}]
18:37:58 INFO - 1499132278108 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"
18:37:58 INFO - 1499132278110 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"
18:37:58 INFO - 1499132278111 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"
18:37:58 INFO - 1499132278121 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"
18:37:58 INFO - 1499132278123 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"
18:37:58 INFO - 1499132278132 Marionette TRACE 370 <- [1,2,null,{}]
18:37:58 INFO - 1499132278140 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:37:58 INFO - 1499132278148 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b1241ab-cc60-43d9-918d-b4e99cc36093","ELEMENT":"2b1241ab-cc60-43d9-918d-b4e99cc36093"}}]
18:37:58 INFO - 1499132278149 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"2b1241ab-cc60-43d9-918d-b4e99cc36093"}]
18:37:58 INFO - 1499132278395 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:37:58 INFO - 1499132278396 Marionette TRACE 370 <- [1,4,null,{}]
18:37:58 INFO - 1499132278397 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"2b1241ab-cc60-43d9-918d-b4e99cc36093","name":"selected"}]
18:37:58 INFO - 1499132278402 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:37:58 INFO - 1499132278404 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}]
18:37:58 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499132278407 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:37:58 INFO - 1499132278408 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:37:58 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
18:37:58 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:37:58 INFO - 1499132278410 Marionette TRACE 370 <- [1,7,null,{}]
18:37:58 INFO - 1499132278412 Marionette DEBUG Closed connection 370
18:37:58 INFO - 1499132278413 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50125
18:37:58 INFO - 1499132278414 Marionette DEBUG Closed connection 371
18:37:58 INFO - 1499132278414 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50126
18:37:58 INFO - 1499132278415 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278416 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278419 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278423 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d1da326b-a8ce-4310-be04-58f55341d093","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278424 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:37:58 INFO - 1499132278424 Marionette TRACE 372 <- [1,2,null,{}]
18:37:58 INFO - 1499132278425 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:58 INFO - 1499132278426 Marionette TRACE 372 <- [1,3,null,{}]
18:37:58 INFO - 1499132278427 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:58 INFO - 1499132278427 Marionette TRACE 372 <- [1,4,null,{}]
18:37:58 INFO - 1499132278429 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}]
18:37:58 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499132278432 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:37:58 INFO - 1499132278433 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:37:58 INFO - 1499132278434 Marionette TRACE 372 <- [1,6,null,{}]
18:37:58 INFO - 1499132278436 Marionette DEBUG Closed connection 372
18:37:58 INFO - 1499132278437 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50127
18:37:58 INFO - 1499132278438 Marionette DEBUG Closed connection 373
18:37:58 INFO - 1499132278439 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50128
18:37:58 INFO - 1499132278440 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278440 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278443 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278446 Marionette TRACE 374 <- [1,1,null,{"sessionId":"fe480ccc-d35d-4860-a060-c25142a4fec9","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278447 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:37:58 INFO - 1499132278449 Marionette TRACE 374 <- [1,2,null,{}]
18:37:58 INFO - 1499132278451 Marionette DEBUG Closed connection 374
18:37:58 INFO - 1499132278451 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50129
18:37:58 INFO - 1499132278452 Marionette DEBUG Closed connection 375
18:37:58 INFO - 1499132278453 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50130
18:37:58 INFO - 1499132278454 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:58 INFO - 1499132278455 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278458 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278461 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e75217c3-a607-4a88-851d-f289b7377baa","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278462 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"}]
18:37:58 INFO - 1499132278464 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"
18:37:58 INFO - 1499132278467 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"
18:37:58 INFO - 1499132278467 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"
18:37:58 INFO - 1499132278474 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"
18:37:58 INFO - 1499132278477 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"
18:37:58 INFO - 1499132278483 Marionette TRACE 376 <- [1,2,null,{}]
18:37:58 INFO - 1499132278495 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:37:58 INFO - 1499132278498 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"595de01d-1dd3-4fb7-9e29-072714c1779d","ELEMENT":"595de01d-1dd3-4fb7-9e29-072714c1779d"}}]
18:37:58 INFO - 1499132278499 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"595de01d-1dd3-4fb7-9e29-072714c1779d"}]
18:37:58 INFO - 1499132278740 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:37:58 INFO - 1499132278742 Marionette TRACE 376 <- [1,4,null,{}]
18:37:58 INFO - 1499132278744 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}]
18:37:58 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499132278747 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:37:58 INFO - 1499132278748 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:37:58 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 340ms
18:37:58 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:37:58 INFO - 1499132278750 Marionette TRACE 376 <- [1,6,null,{}]
18:37:58 INFO - 1499132278752 Marionette DEBUG Closed connection 376
18:37:58 INFO - 1499132278753 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50131
18:37:58 INFO - 1499132278754 Marionette DEBUG Closed connection 377
18:37:58 INFO - 1499132278754 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50132
18:37:58 INFO - 1499132278755 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278756 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278759 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278763 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6b3d3de7-6295-4710-90fd-0f8c9b2bfb86","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278764 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:37:58 INFO - 1499132278764 Marionette TRACE 378 <- [1,2,null,{}]
18:37:58 INFO - 1499132278765 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:58 INFO - 1499132278766 Marionette TRACE 378 <- [1,3,null,{}]
18:37:58 INFO - 1499132278767 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:58 INFO - 1499132278767 Marionette TRACE 378 <- [1,4,null,{}]
18:37:58 INFO - 1499132278769 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}]
18:37:58 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499132278772 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:37:58 INFO - 1499132278773 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:37:58 INFO - 1499132278775 Marionette TRACE 378 <- [1,6,null,{}]
18:37:58 INFO - 1499132278776 Marionette DEBUG Closed connection 378
18:37:58 INFO - 1499132278777 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50133
18:37:58 INFO - 1499132278778 Marionette DEBUG Closed connection 379
18:37:58 INFO - 1499132278779 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50134
18:37:58 INFO - 1499132278780 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:58 INFO - 1499132278780 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278783 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278786 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5577a422-aaa1-4c64-a48a-d9b53c0e002b","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278788 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:37:58 INFO - 1499132278789 Marionette TRACE 380 <- [1,2,null,{}]
18:37:58 INFO - 1499132278791 Marionette DEBUG Closed connection 380
18:37:58 INFO - 1499132278792 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50135
18:37:58 INFO - 1499132278793 Marionette DEBUG Closed connection 381
18:37:58 INFO - 1499132278793 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50136
18:37:58 INFO - 1499132278794 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:58 INFO - 1499132278795 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:58 INFO - 1499132278798 Marionette DEBUG Register listener.js for window 4294967297
18:37:58 INFO - 1499132278801 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8ea1cbd7-3d29-4324-b07f-27e22c565239","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:58 INFO - 1499132278802 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"}]
18:37:58 INFO - 1499132278804 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"
18:37:58 INFO - 1499132278806 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"
18:37:58 INFO - 1499132278807 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"
18:37:58 INFO - 1499132278814 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"
18:37:58 INFO - 1499132278818 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"
18:37:58 INFO - 1499132278826 Marionette TRACE 382 <- [1,2,null,{}]
18:37:58 INFO - 1499132278831 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:37:58 INFO - 1499132278840 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79ecee11-41e6-4886-b965-c535d1d31548","ELEMENT":"79ecee11-41e6-4886-b965-c535d1d31548"}}]
18:37:58 INFO - 1499132278841 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"79ecee11-41e6-4886-b965-c535d1d31548"}]
18:37:59 INFO - 1499132279093 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:37:59 INFO - 1499132279094 Marionette TRACE 382 <- [1,4,null,{}]
18:37:59 INFO - 1499132279096 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}]
18:37:59 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499132279100 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:37:59 INFO - 1499132279101 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:37:59 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 352ms
18:37:59 INFO - TEST-START | test_click.py TestClick.test_input_file
18:37:59 INFO - 1499132279103 Marionette TRACE 382 <- [1,6,null,{}]
18:37:59 INFO - 1499132279104 Marionette DEBUG Closed connection 382
18:37:59 INFO - 1499132279105 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50137
18:37:59 INFO - 1499132279106 Marionette DEBUG Closed connection 383
18:37:59 INFO - 1499132279107 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50138
18:37:59 INFO - 1499132279108 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279109 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279117 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279132 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b7369b38-35d8-4c77-bfd5-7a99109c81a5","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279133 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:37:59 INFO - 1499132279134 Marionette TRACE 384 <- [1,2,null,{}]
18:37:59 INFO - 1499132279135 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:59 INFO - 1499132279135 Marionette TRACE 384 <- [1,3,null,{}]
18:37:59 INFO - 1499132279136 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:59 INFO - 1499132279137 Marionette TRACE 384 <- [1,4,null,{}]
18:37:59 INFO - 1499132279139 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}]
18:37:59 INFO - TEST-START: test_click.py TestClick.test_input_file1499132279142 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:37:59 INFO - 1499132279143 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:37:59 INFO - 1499132279144 Marionette TRACE 384 <- [1,6,null,{}]
18:37:59 INFO - 1499132279146 Marionette DEBUG Closed connection 384
18:37:59 INFO - 1499132279147 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50139
18:37:59 INFO - 1499132279150 Marionette DEBUG Closed connection 385
18:37:59 INFO - 1499132279151 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50140
18:37:59 INFO - 1499132279152 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279152 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279155 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279159 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67c0a654-b6f5-4961-8277-19012e0416ae","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279160 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:37:59 INFO - 1499132279161 Marionette TRACE 386 <- [1,2,null,{}]
18:37:59 INFO - 1499132279163 Marionette DEBUG Closed connection 386
18:37:59 INFO - 1499132279164 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50141
18:37:59 INFO - 1499132279165 Marionette DEBUG Closed connection 387
18:37:59 INFO - 1499132279165 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50142
18:37:59 INFO - 1499132279166 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:59 INFO - 1499132279167 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279170 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279173 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2338dfd5-4b75-4ad3-81b7-beab0f778e05","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279174 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:37:59 INFO - 1499132279176 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"
18:37:59 INFO - 1499132279178 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"
18:37:59 INFO - 1499132279179 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"
18:37:59 INFO - 1499132279190 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:37:59 INFO - 1499132279191 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:37:59 INFO - 1499132279204 Marionette TRACE 388 <- [1,2,null,{}]
18:37:59 INFO - 1499132279207 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:37:59 INFO - 1499132279211 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4c0031e6-301b-4aa6-817f-14870f779ecb","ELEMENT":"4c0031e6-301b-4aa6-817f-14870f779ecb"}}]
18:37:59 INFO - 1499132279212 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4c0031e6-301b-4aa6-817f-14870f779ecb"}]
18:37:59 INFO - 1499132279241 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:1330:5\n"},null]
18:37:59 INFO - 1499132279243 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}]
18:37:59 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 151ms
18:37:59 INFO - TEST-END: test_click.py TestClick.test_input_file1499132279251 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:37:59 INFO - 1499132279252 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:37:59 INFO - 1499132279254 Marionette TRACE 388 <- [1,6,null,{}]
18:37:59 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:37:59 INFO - 1499132279256 Marionette DEBUG Closed connection 388
18:37:59 INFO - 1499132279257 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50143
18:37:59 INFO - 1499132279258 Marionette DEBUG Closed connection 389
18:37:59 INFO - 1499132279258 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50144
18:37:59 INFO - 1499132279260 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279260 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279263 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279267 Marionette TRACE 390 <- [1,1,null,{"sessionId":"74451127-562d-4256-a4d8-744092a478f2","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279268 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:37:59 INFO - 1499132279268 Marionette TRACE 390 <- [1,2,null,{}]
18:37:59 INFO - 1499132279269 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:59 INFO - 1499132279270 Marionette TRACE 390 <- [1,3,null,{}]
18:37:59 INFO - 1499132279271 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:59 INFO - 1499132279271 Marionette TRACE 390 <- [1,4,null,{}]
18:37:59 INFO - 1499132279273 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}]
18:37:59 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499132279276 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:37:59 INFO - 1499132279277 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:37:59 INFO - 1499132279279 Marionette TRACE 390 <- [1,6,null,{}]
18:37:59 INFO - 1499132279280 Marionette DEBUG Closed connection 390
18:37:59 INFO - 1499132279281 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50145
18:37:59 INFO - 1499132279282 Marionette DEBUG Closed connection 391
18:37:59 INFO - 1499132279283 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50146
18:37:59 INFO - 1499132279284 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279285 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279287 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279291 Marionette TRACE 392 <- [1,1,null,{"sessionId":"81d80723-68dd-4d1a-a52a-f54e7899df05","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279292 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:37:59 INFO - 1499132279293 Marionette TRACE 392 <- [1,2,null,{}]
18:37:59 INFO - 1499132279295 Marionette DEBUG Closed connection 392
18:37:59 INFO - 1499132279296 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50147
18:37:59 INFO - 1499132279297 Marionette DEBUG Closed connection 393
18:37:59 INFO - 1499132279297 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50148
18:37:59 INFO - 1499132279298 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:59 INFO - 1499132279299 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279302 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279305 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4b0300fd-c3ef-4941-9b3b-103cbf0e653b","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279306 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"}]
18:37:59 INFO - 1499132279308 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:37:59 INFO - 1499132279310 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:37:59 INFO - 1499132279311 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:37:59 INFO - 1499132279321 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"
18:37:59 INFO - 1499132279322 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"
18:37:59 INFO - 1499132279331 Marionette TRACE 394 <- [1,2,null,{}]
18:37:59 INFO - 1499132279340 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:37:59 INFO - 1499132279352 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fd7a2e02-ad0b-4f4b-96eb-671a077ecb14","ELEMENT":"fd7a2e02-ad0b-4f4b-96eb-671a077ecb14"}}]
18:37:59 INFO - 1499132279353 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:37:59 INFO - 1499132279369 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63fd838f-ef53-4f3e-be32-6a364fe0af6f","ELEMENT":"63fd838f-ef53-4f3e-be32-6a364fe0af6f"}}]
18:37:59 INFO - 1499132279370 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fd7a2e02-ad0b-4f4b-96eb-671a077ecb14"}]
18:37:59 INFO - 1499132279609 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:37:59 INFO - 1499132279611 Marionette TRACE 394 <- [1,5,null,{}]
18:37:59 INFO - 1499132279612 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"63fd838f-ef53-4f3e-be32-6a364fe0af6f"}]
18:37:59 INFO - 1499132279631 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:1330:5\n"},null]
18:37:59 INFO - 1499132279632 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:37:59 INFO - 1499132279636 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:37:59 INFO - 1499132279637 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}]
18:37:59 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499132279640 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:37:59 INFO - 1499132279641 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:37:59 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 389ms
18:37:59 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:37:59 INFO - 1499132279643 Marionette TRACE 394 <- [1,9,null,{}]
18:37:59 INFO - 1499132279645 Marionette DEBUG Closed connection 394
18:37:59 INFO - 1499132279646 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50149
18:37:59 INFO - 1499132279647 Marionette DEBUG Closed connection 395
18:37:59 INFO - 1499132279647 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50150
18:37:59 INFO - 1499132279648 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279649 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279652 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279656 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5959a15e-5711-4211-ab48-dd0cf38511cd","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279657 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:37:59 INFO - 1499132279657 Marionette TRACE 396 <- [1,2,null,{}]
18:37:59 INFO - 1499132279658 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:37:59 INFO - 1499132279659 Marionette TRACE 396 <- [1,3,null,{}]
18:37:59 INFO - 1499132279660 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:37:59 INFO - 1499132279660 Marionette TRACE 396 <- [1,4,null,{}]
18:37:59 INFO - 1499132279662 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}]
18:37:59 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499132279665 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:37:59 INFO - 1499132279666 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:37:59 INFO - 1499132279668 Marionette TRACE 396 <- [1,6,null,{}]
18:37:59 INFO - 1499132279670 Marionette DEBUG Closed connection 396
18:37:59 INFO - 1499132279670 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50151
18:37:59 INFO - 1499132279671 Marionette DEBUG Closed connection 397
18:37:59 INFO - 1499132279672 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50152
18:37:59 INFO - 1499132279673 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:37:59 INFO - 1499132279674 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279677 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279680 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e025662b-e8b8-4966-b6ef-5c1169613be4","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279681 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:37:59 INFO - 1499132279683 Marionette TRACE 398 <- [1,2,null,{}]
18:37:59 INFO - 1499132279685 Marionette DEBUG Closed connection 398
18:37:59 INFO - 1499132279685 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50153
18:37:59 INFO - 1499132279686 Marionette DEBUG Closed connection 399
18:37:59 INFO - 1499132279687 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50154
18:37:59 INFO - 1499132279688 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:37:59 INFO - 1499132279689 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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}
18:37:59 INFO - 1499132279691 Marionette DEBUG Register listener.js for window 4294967297
18:37:59 INFO - 1499132279694 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e63cafc2-d849-4a96-af4e-b15368e15668","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":2396,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphykfly.mozrunner","moz:accessibilityChecks":false}}]
18:37:59 INFO - 1499132279696 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"}]
18:37:59 INFO - 1499132279698 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"
18:37:59 INFO - 1499132279700 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"
18:37:59 INFO - 1499132279700 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"
18:37:59 INFO - 1499132279708 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"
18:37:59 INFO - 1499132279711 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"
18:37:59 INFO - 1499132279723 Marionette TRACE 400 <- [1,2,null,{}]
18:37:59 INFO - 1499132279728 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:37:59 INFO - 1499132279731 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7fc7a3e-a265-4138-a6a6-54ccb3e82590","ELEMENT":"d7fc7a3e-a265-4138-a6a6-54ccb3e82590"}}]
18:37:59 INFO - 1499132279732 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d7fc7a3e-a265-4138-a6a6-54ccb3e82590"}]
18:37:59 INFO - 1499132279762 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:37:59 INFO - 1499132279763 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d7fc7a3e-a265-4138-a6a6-54ccb3e82590"}]
18:37:59 INFO - 1499132279781 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element