could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:31 INFO - 1498748071501 Marionette TRACE 358 -> [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}]
07:54:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498748071506 Marionette TRACE 358 <- [1,5,null,{"value":null}]
07:54:31 INFO - 1498748071507 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
07:54:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 180ms
07:54:31 INFO - 1498748071509 Marionette TRACE 358 <- [1,6,null,{}]
07:54:31 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:54:31 INFO - 1498748071512 Marionette DEBUG Closed connection 358
07:54:31 INFO - 1498748071513 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50090
07:54:31 INFO - 1498748071514 Marionette DEBUG Closed connection 359
07:54:31 INFO - 1498748071515 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50091
07:54:31 INFO - 1498748071516 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:31 INFO - 1498748071517 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071521 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071525 Marionette TRACE 360 <- [1,1,null,{"sessionId":"69d56c26-b637-400d-8585-979c026cc0c1","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071526 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:54:31 INFO - 1498748071527 Marionette TRACE 360 <- [1,2,null,{}]
07:54:31 INFO - 1498748071528 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:31 INFO - 1498748071529 Marionette TRACE 360 <- [1,3,null,{}]
07:54:31 INFO - 1498748071530 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:31 INFO - 1498748071531 Marionette TRACE 360 <- [1,4,null,{}]
07:54:31 INFO - 1498748071533 Marionette TRACE 360 -> [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}]
07:54:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498748071538 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:54:31 INFO - 1498748071540 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:54:31 INFO - 1498748071542 Marionette TRACE 360 <- [1,6,null,{}]
07:54:31 INFO - 1498748071544 Marionette DEBUG Closed connection 360
07:54:31 INFO - 1498748071545 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50092
07:54:31 INFO - 1498748071546 Marionette DEBUG Closed connection 361
07:54:31 INFO - 1498748071547 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50093
07:54:31 INFO - 1498748071548 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:31 INFO - 1498748071549 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071552 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071557 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0fc26a0a-b999-4db8-ba52-39ce1b35147c","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071558 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:54:31 INFO - 1498748071560 Marionette TRACE 362 <- [1,2,null,{}]
07:54:31 INFO - 1498748071562 Marionette DEBUG Closed connection 362
07:54:31 INFO - 1498748071563 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50094
07:54:31 INFO - 1498748071564 Marionette DEBUG Closed connection 363
07:54:31 INFO - 1498748071565 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50095
07:54:31 INFO - 1498748071567 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:31 INFO - 1498748071568 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071571 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071575 Marionette TRACE 364 <- [1,1,null,{"sessionId":"190ee25a-6607-4cf5-a7de-62419146fbad","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071577 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49689/clicks.html"}]
07:54:31 INFO - 1498748071579 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49689/hidden.html"
07:54:31 INFO - 1498748071590 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49689/hidden.html"
07:54:31 INFO - 1498748071590 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49689/hidden.html"
07:54:31 INFO - 1498748071599 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49689/clicks.html"
07:54:31 INFO - 1498748071608 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49689/clicks.html"
07:54:31 INFO - 1498748071612 Marionette TRACE 364 <- [1,2,null,{}]
07:54:31 INFO - 1498748071618 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:54:31 INFO - 1498748071626 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2bd1ff2f-bda7-4dc7-bd22-64fa09795206","ELEMENT":"2bd1ff2f-bda7-4dc7-bd22-64fa09795206"}}]
07:54:31 INFO - 1498748071628 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"2bd1ff2f-bda7-4dc7-bd22-64fa09795206"}]
07:54:31 INFO - 1498748071674 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49689/clicks.html"
07:54:31 INFO - 1498748071683 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49689/clicks.html"
07:54:31 INFO - 1498748071684 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49689/clicks.html"
07:54:31 INFO - 1498748071692 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49689/test.html"
07:54:31 INFO - 1498748071697 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49689/test.html"
07:54:31 INFO - 1498748071703 Marionette TRACE 364 <- [1,4,null,{}]
07:54:31 INFO - 1498748071715 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:54:31 INFO - 1498748071718 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42f046d7-77db-4059-a8a6-302a168edf89","ELEMENT":"42f046d7-77db-4059-a8a6-302a168edf89"}}]
07:54:31 INFO - 1498748071720 Marionette TRACE 364 -> [0,6,"getTitle",{}]
07:54:31 INFO - 1498748071728 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
07:54:31 INFO - 1498748071731 Marionette TRACE 364 -> [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}]
07:54:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498748071753 Marionette TRACE 364 <- [1,7,null,{"value":null}]
07:54:31 INFO - 1498748071755 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
07:54:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 247ms
07:54:31 INFO - TEST-START | test_click.py TestClick.test_container_element
07:54:31 INFO - 1498748071757 Marionette TRACE 364 <- [1,8,null,{}]
07:54:31 INFO - 1498748071759 Marionette DEBUG Closed connection 364
07:54:31 INFO - 1498748071760 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50098
07:54:31 INFO - 1498748071761 Marionette DEBUG Closed connection 365
07:54:31 INFO - 1498748071762 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50099
07:54:31 INFO - 1498748071763 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:31 INFO - 1498748071764 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071767 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071772 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a6e205f6-3581-44a2-914a-fab384f19d7a","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071773 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:54:31 INFO - 1498748071774 Marionette TRACE 366 <- [1,2,null,{}]
07:54:31 INFO - 1498748071775 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:31 INFO - 1498748071776 Marionette TRACE 366 <- [1,3,null,{}]
07:54:31 INFO - 1498748071777 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:31 INFO - 1498748071778 Marionette TRACE 366 <- [1,4,null,{}]
07:54:31 INFO - 1498748071781 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')","sandbox":"simpletest","line":316}]
07:54:31 INFO - TEST-START: test_click.py TestClick.test_container_element1498748071785 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:54:31 INFO - 1498748071786 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:54:31 INFO - 1498748071788 Marionette TRACE 366 <- [1,6,null,{}]
07:54:31 INFO - 1498748071791 Marionette DEBUG Closed connection 366
07:54:31 INFO - 1498748071791 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50100
07:54:31 INFO - 1498748071792 Marionette DEBUG Closed connection 367
07:54:31 INFO - 1498748071793 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50101
07:54:31 INFO - 1498748071795 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:31 INFO - 1498748071796 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071799 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071803 Marionette TRACE 368 <- [1,1,null,{"sessionId":"17b17c8e-c87f-450a-a80a-dced495a97e9","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071805 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:54:31 INFO - 1498748071807 Marionette TRACE 368 <- [1,2,null,{}]
07:54:31 INFO - 1498748071809 Marionette DEBUG Closed connection 368
07:54:31 INFO - 1498748071810 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50102
07:54:31 INFO - 1498748071811 Marionette DEBUG Closed connection 369
07:54:31 INFO - 1498748071812 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50103
07:54:31 INFO - 1498748071814 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:31 INFO - 1498748071824 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:31 INFO - 1498748071827 Marionette DEBUG Register listener.js for window 2147483649
07:54:31 INFO - 1498748071832 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e21a6f66-1156-4e71-a10e-6358b93be0f9","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:31 INFO - 1498748071834 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%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"}]
07:54:31 INFO - 1498748071837 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49689/test.html"
07:54:31 INFO - 1498748071840 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49689/test.html"
07:54:31 INFO - 1498748071840 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49689/test.html"
07:54:31 INFO - 1498748071848 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"
07:54:31 INFO - 1498748071853 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"
07:54:31 INFO - 1498748071865 Marionette TRACE 370 <- [1,2,null,{}]
07:54:31 INFO - 1498748071876 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:54:31 INFO - 1498748071880 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12a4fbd6-ae95-4568-b3c7-aff25593031a","ELEMENT":"12a4fbd6-ae95-4568-b3c7-aff25593031a"}}]
07:54:31 INFO - 1498748071881 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"12a4fbd6-ae95-4568-b3c7-aff25593031a"}]
07:54:32 INFO - 1498748072131 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:32 INFO - 1498748072132 Marionette TRACE 370 <- [1,4,null,{}]
07:54:32 INFO - 1498748072133 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"12a4fbd6-ae95-4568-b3c7-aff25593031a","name":"selected"}]
07:54:32 INFO - 1498748072140 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:54:32 INFO - 1498748072142 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')","sandbox":"simpletest","line":328}]
07:54:32 INFO - TEST-END: test_click.py TestClick.test_container_element1498748072157 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:54:32 INFO - 1498748072159 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:54:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 404ms
07:54:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:54:32 INFO - 1498748072161 Marionette TRACE 370 <- [1,7,null,{}]
07:54:32 INFO - 1498748072163 Marionette DEBUG Closed connection 370
07:54:32 INFO - 1498748072164 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50104
07:54:32 INFO - 1498748072165 Marionette DEBUG Closed connection 371
07:54:32 INFO - 1498748072166 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50105
07:54:32 INFO - 1498748072168 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072171 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072175 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072179 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2d395f12-11fe-41ba-b078-89c7e9b7f0af","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072181 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:54:32 INFO - 1498748072182 Marionette TRACE 372 <- [1,2,null,{}]
07:54:32 INFO - 1498748072183 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:32 INFO - 1498748072183 Marionette TRACE 372 <- [1,3,null,{}]
07:54:32 INFO - 1498748072185 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:32 INFO - 1498748072185 Marionette TRACE 372 <- [1,4,null,{}]
07:54:32 INFO - 1498748072187 Marionette TRACE 372 -> [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}]
07:54:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498748072191 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:54:32 INFO - 1498748072193 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:54:32 INFO - 1498748072195 Marionette TRACE 372 <- [1,6,null,{}]
07:54:32 INFO - 1498748072197 Marionette DEBUG Closed connection 372
07:54:32 INFO - 1498748072198 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50106
07:54:32 INFO - 1498748072199 Marionette DEBUG Closed connection 373
07:54:32 INFO - 1498748072200 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50107
07:54:32 INFO - 1498748072201 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072202 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072205 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072209 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2a54773f-dd0f-4e9b-bcce-53e2f11c08c7","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072210 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:54:32 INFO - 1498748072212 Marionette TRACE 374 <- [1,2,null,{}]
07:54:32 INFO - 1498748072215 Marionette DEBUG Closed connection 374
07:54:32 INFO - 1498748072215 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50108
07:54:32 INFO - 1498748072217 Marionette DEBUG Closed connection 375
07:54:32 INFO - 1498748072217 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50109
07:54:32 INFO - 1498748072219 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:32 INFO - 1498748072220 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072223 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072227 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a0141228-6764-417d-93e5-4f66d0b843b0","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072229 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%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"}]
07:54:32 INFO - 1498748072231 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"
07:54:32 INFO - 1498748072234 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"
07:54:32 INFO - 1498748072234 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"
07:54:32 INFO - 1498748072244 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"
07:54:32 INFO - 1498748072248 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"
07:54:32 INFO - 1498748072260 Marionette TRACE 376 <- [1,2,null,{}]
07:54:32 INFO - 1498748072265 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:54:32 INFO - 1498748072269 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4d758183-659c-4bc0-b8cf-6b3b48d025c2","ELEMENT":"4d758183-659c-4bc0-b8cf-6b3b48d025c2"}}]
07:54:32 INFO - 1498748072277 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4d758183-659c-4bc0-b8cf-6b3b48d025c2"}]
07:54:32 INFO - 1498748072535 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:32 INFO - 1498748072536 Marionette TRACE 376 <- [1,4,null,{}]
07:54:32 INFO - 1498748072537 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"4d758183-659c-4bc0-b8cf-6b3b48d025c2","name":"selected"}]
07:54:32 INFO - 1498748072543 Marionette TRACE 376 <- [1,5,null,{"value":true}]
07:54:32 INFO - 1498748072545 Marionette TRACE 376 -> [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}]
07:54:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498748072549 Marionette TRACE 376 <- [1,6,null,{"value":null}]
07:54:32 INFO - 1498748072551 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
07:54:32 INFO - 1498748072553 Marionette TRACE 376 <- [1,7,null,{}]
07:54:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 392ms
07:54:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:54:32 INFO - 1498748072555 Marionette DEBUG Closed connection 376
07:54:32 INFO - 1498748072556 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50110
07:54:32 INFO - 1498748072557 Marionette DEBUG Closed connection 377
07:54:32 INFO - 1498748072558 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50111
07:54:32 INFO - 1498748072560 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072560 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072564 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072574 Marionette TRACE 378 <- [1,1,null,{"sessionId":"090e4cc6-a900-46cc-a7fb-80fa57256439","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072576 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:54:32 INFO - 1498748072577 Marionette TRACE 378 <- [1,2,null,{}]
07:54:32 INFO - 1498748072578 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:32 INFO - 1498748072579 Marionette TRACE 378 <- [1,3,null,{}]
07:54:32 INFO - 1498748072580 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:32 INFO - 1498748072581 Marionette TRACE 378 <- [1,4,null,{}]
07:54:32 INFO - 1498748072584 Marionette TRACE 378 -> [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}]
07:54:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498748072587 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:54:32 INFO - 1498748072589 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:54:32 INFO - 1498748072592 Marionette TRACE 378 <- [1,6,null,{}]
07:54:32 INFO - 1498748072594 Marionette DEBUG Closed connection 378
07:54:32 INFO - 1498748072595 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50112
07:54:32 INFO - 1498748072596 Marionette DEBUG Closed connection 379
07:54:32 INFO - 1498748072597 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50113
07:54:32 INFO - 1498748072599 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072600 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072603 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072607 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8ca198b2-8b22-4530-a94a-a52d26c4b8cf","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072608 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:54:32 INFO - 1498748072610 Marionette TRACE 380 <- [1,2,null,{}]
07:54:32 INFO - 1498748072612 Marionette DEBUG Closed connection 380
07:54:32 INFO - 1498748072613 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50114
07:54:32 INFO - 1498748072614 Marionette DEBUG Closed connection 381
07:54:32 INFO - 1498748072615 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50115
07:54:32 INFO - 1498748072617 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:32 INFO - 1498748072617 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072621 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072625 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dc2703f0-94c8-4ce6-89af-1960cb8ed898","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072626 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%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"}]
07:54:32 INFO - 1498748072629 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"
07:54:32 INFO - 1498748072632 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"
07:54:32 INFO - 1498748072632 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"
07:54:32 INFO - 1498748072651 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"
07:54:32 INFO - 1498748072653 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"
07:54:32 INFO - 1498748072669 Marionette TRACE 382 <- [1,2,null,{}]
07:54:32 INFO - 1498748072681 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:54:32 INFO - 1498748072686 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cec1f9f3-c042-439c-98f5-7c65a0778278","ELEMENT":"cec1f9f3-c042-439c-98f5-7c65a0778278"}}]
07:54:32 INFO - 1498748072694 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"cec1f9f3-c042-439c-98f5-7c65a0778278"}]
07:54:32 INFO - 1498748072943 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:32 INFO - 1498748072944 Marionette TRACE 382 <- [1,4,null,{}]
07:54:32 INFO - 1498748072946 Marionette TRACE 382 -> [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}]
07:54:32 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 409ms
07:54:32 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:54:32 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498748072958 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:54:32 INFO - 1498748072960 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:54:32 INFO - 1498748072962 Marionette TRACE 382 <- [1,6,null,{}]
07:54:32 INFO - 1498748072964 Marionette DEBUG Closed connection 382
07:54:32 INFO - 1498748072965 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50116
07:54:32 INFO - 1498748072966 Marionette DEBUG Closed connection 383
07:54:32 INFO - 1498748072967 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50117
07:54:32 INFO - 1498748072968 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072969 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:32 INFO - 1498748072972 Marionette DEBUG Register listener.js for window 2147483649
07:54:32 INFO - 1498748072976 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c84893b9-8782-45d1-a7dc-44b1625f1763","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:32 INFO - 1498748072978 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:54:32 INFO - 1498748072978 Marionette TRACE 384 <- [1,2,null,{}]
07:54:32 INFO - 1498748072980 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:32 INFO - 1498748072980 Marionette TRACE 384 <- [1,3,null,{}]
07:54:32 INFO - 1498748072981 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:32 INFO - 1498748072982 Marionette TRACE 384 <- [1,4,null,{}]
07:54:32 INFO - 1498748072984 Marionette TRACE 384 -> [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}]
07:54:32 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498748072988 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:54:32 INFO - 1498748072989 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:54:32 INFO - 1498748072991 Marionette TRACE 384 <- [1,6,null,{}]
07:54:32 INFO - 1498748072993 Marionette DEBUG Closed connection 384
07:54:32 INFO - 1498748072994 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50118
07:54:32 INFO - 1498748072995 Marionette DEBUG Closed connection 385
07:54:32 INFO - 1498748072996 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50119
07:54:32 INFO - 1498748072998 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:32 INFO - 1498748072998 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073002 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073006 Marionette TRACE 386 <- [1,1,null,{"sessionId":"548027ad-8e3f-4770-b392-fe26b145eafc","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073007 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:54:33 INFO - 1498748073009 Marionette TRACE 386 <- [1,2,null,{}]
07:54:33 INFO - 1498748073011 Marionette DEBUG Closed connection 386
07:54:33 INFO - 1498748073012 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50120
07:54:33 INFO - 1498748073013 Marionette DEBUG Closed connection 387
07:54:33 INFO - 1498748073014 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50121
07:54:33 INFO - 1498748073016 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:33 INFO - 1498748073017 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073020 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073024 Marionette TRACE 388 <- [1,1,null,{"sessionId":"cdfeedf1-c7ed-4a6c-9801-51c8c564d7e5","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073026 Marionette TRACE 388 -> [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"}]
07:54:33 INFO - 1498748073028 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"
07:54:33 INFO - 1498748073032 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"
07:54:33 INFO - 1498748073033 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"
07:54:33 INFO - 1498748073043 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"
07:54:33 INFO - 1498748073050 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"
07:54:33 INFO - 1498748073060 Marionette TRACE 388 <- [1,2,null,{}]
07:54:33 INFO - 1498748073066 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:54:33 INFO - 1498748073070 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61882c33-5954-4207-af02-caebb3a430d4","ELEMENT":"61882c33-5954-4207-af02-caebb3a430d4"}}]
07:54:33 INFO - 1498748073077 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"61882c33-5954-4207-af02-caebb3a430d4"}]
07:54:33 INFO - 1498748073328 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:33 INFO - 1498748073329 Marionette TRACE 388 <- [1,4,null,{}]
07:54:33 INFO - 1498748073331 Marionette TRACE 388 -> [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}]
07:54:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498748073335 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:54:33 INFO - 1498748073336 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:54:33 INFO - 1498748073338 Marionette TRACE 388 <- [1,6,null,{}]
07:54:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 376ms
07:54:33 INFO - TEST-START | test_click.py TestClick.test_input_file
07:54:33 INFO - 1498748073341 Marionette DEBUG Closed connection 388
07:54:33 INFO - 1498748073341 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50122
07:54:33 INFO - 1498748073342 Marionette DEBUG Closed connection 389
07:54:33 INFO - 1498748073343 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50123
07:54:33 INFO - 1498748073345 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073345 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073349 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073353 Marionette TRACE 390 <- [1,1,null,{"sessionId":"995a412c-c793-47bd-8db4-c6aaab9b2033","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073355 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:54:33 INFO - 1498748073355 Marionette TRACE 390 <- [1,2,null,{}]
07:54:33 INFO - 1498748073356 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:33 INFO - 1498748073357 Marionette TRACE 390 <- [1,3,null,{}]
07:54:33 INFO - 1498748073358 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:33 INFO - 1498748073359 Marionette TRACE 390 <- [1,4,null,{}]
07:54:33 INFO - 1498748073361 Marionette TRACE 390 -> [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}]
07:54:33 INFO - TEST-START: test_click.py TestClick.test_input_file1498748073365 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:54:33 INFO - 1498748073366 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:54:33 INFO - 1498748073368 Marionette TRACE 390 <- [1,6,null,{}]
07:54:33 INFO - 1498748073371 Marionette DEBUG Closed connection 390
07:54:33 INFO - 1498748073371 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50124
07:54:33 INFO - 1498748073373 Marionette DEBUG Closed connection 391
07:54:33 INFO - 1498748073373 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50125
07:54:33 INFO - 1498748073375 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073376 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073379 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073383 Marionette TRACE 392 <- [1,1,null,{"sessionId":"75f8556c-62d7-4de1-ba52-c8894b471cf6","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073384 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:54:33 INFO - 1498748073386 Marionette TRACE 392 <- [1,2,null,{}]
07:54:33 INFO - 1498748073388 Marionette DEBUG Closed connection 392
07:54:33 INFO - 1498748073389 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50126
07:54:33 INFO - 1498748073390 Marionette DEBUG Closed connection 393
07:54:33 INFO - 1498748073391 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50127
07:54:33 INFO - 1498748073392 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:33 INFO - 1498748073393 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073397 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073401 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c930b349-92bc-494d-a61a-46baa9ecb465","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073402 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:54:33 INFO - 1498748073405 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"
07:54:33 INFO - 1498748073408 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"
07:54:33 INFO - 1498748073408 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"
07:54:33 INFO - 1498748073417 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:33 INFO - 1498748073423 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:33 INFO - 1498748073435 Marionette TRACE 394 <- [1,2,null,{}]
07:54:33 INFO - 1498748073446 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:54:33 INFO - 1498748073450 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d5f3c880-7fb1-446f-ba25-fd2a9021cc22","ELEMENT":"d5f3c880-7fb1-446f-ba25-fd2a9021cc22"}}]
07:54:33 INFO - 1498748073451 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"d5f3c880-7fb1-446f-ba25-fd2a9021cc22"}]
07:54:33 INFO - 1498748073488 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:33 INFO - 1498748073491 Marionette TRACE 394 -> [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}]
07:54:33 INFO - TEST-END: test_click.py TestClick.test_input_file1498748073495 Marionette TRACE 394 <- [1,5,null,{"value":null}]
07:54:33 INFO - 1498748073496 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
07:54:33 INFO - 1498748073498 Marionette TRACE 394 <- [1,6,null,{}]
07:54:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 159ms
07:54:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:54:33 INFO - 1498748073500 Marionette DEBUG Closed connection 394
07:54:33 INFO - 1498748073501 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50128
07:54:33 INFO - 1498748073502 Marionette DEBUG Closed connection 395
07:54:33 INFO - 1498748073503 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50129
07:54:33 INFO - 1498748073504 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073505 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073508 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073513 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c5e89458-5de1-440e-83c9-cd23e06f3a8b","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073514 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:54:33 INFO - 1498748073515 Marionette TRACE 396 <- [1,2,null,{}]
07:54:33 INFO - 1498748073516 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:33 INFO - 1498748073517 Marionette TRACE 396 <- [1,3,null,{}]
07:54:33 INFO - 1498748073518 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:33 INFO - 1498748073518 Marionette TRACE 396 <- [1,4,null,{}]
07:54:33 INFO - 1498748073521 Marionette TRACE 396 -> [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}]
07:54:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498748073525 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:54:33 INFO - 1498748073526 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:54:33 INFO - 1498748073528 Marionette TRACE 396 <- [1,6,null,{}]
07:54:33 INFO - 1498748073530 Marionette DEBUG Closed connection 396
07:54:33 INFO - 1498748073531 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50130
07:54:33 INFO - 1498748073532 Marionette DEBUG Closed connection 397
07:54:33 INFO - 1498748073533 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50131
07:54:33 INFO - 1498748073535 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073535 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073539 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073543 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3b940efd-e0fa-4acc-9d2d-78a35c6a1c9e","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073544 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:54:33 INFO - 1498748073546 Marionette TRACE 398 <- [1,2,null,{}]
07:54:33 INFO - 1498748073548 Marionette DEBUG Closed connection 398
07:54:33 INFO - 1498748073549 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50132
07:54:33 INFO - 1498748073550 Marionette DEBUG Closed connection 399
07:54:33 INFO - 1498748073551 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50133
07:54:33 INFO - 1498748073553 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:33 INFO - 1498748073553 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073557 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073561 Marionette TRACE 400 <- [1,1,null,{"sessionId":"690a99f9-0d44-40fd-a864-76e0e12282a7","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073562 Marionette TRACE 400 -> [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"}]
07:54:33 INFO - 1498748073565 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:33 INFO - 1498748073568 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:33 INFO - 1498748073568 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:33 INFO - 1498748073577 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"
07:54:33 INFO - 1498748073581 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"
07:54:33 INFO - 1498748073585 Marionette TRACE 400 <- [1,2,null,{}]
07:54:33 INFO - 1498748073598 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:54:33 INFO - 1498748073602 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab5811f4-5157-432d-ab84-73da967d1abf","ELEMENT":"ab5811f4-5157-432d-ab84-73da967d1abf"}}]
07:54:33 INFO - 1498748073609 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:54:33 INFO - 1498748073621 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6fb62d0f-aefd-49ce-b525-f7e65f68f085","ELEMENT":"6fb62d0f-aefd-49ce-b525-f7e65f68f085"}}]
07:54:33 INFO - 1498748073622 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ab5811f4-5157-432d-ab84-73da967d1abf"}]
07:54:33 INFO - 1498748073868 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:33 INFO - 1498748073869 Marionette TRACE 400 <- [1,5,null,{}]
07:54:33 INFO - 1498748073870 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"6fb62d0f-aefd-49ce-b525-f7e65f68f085"}]
07:54:33 INFO - 1498748073892 Marionette TRACE 400 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:33 INFO - 1498748073894 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
07:54:33 INFO - 1498748073898 Marionette TRACE 400 <- [1,7,null,{"value":false}]
07:54:33 INFO - 1498748073900 Marionette TRACE 400 -> [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}]
07:54:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498748073904 Marionette TRACE 400 <- [1,8,null,{"value":null}]
07:54:33 INFO - 1498748073906 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
07:54:33 INFO - 1498748073908 Marionette TRACE 400 <- [1,9,null,{}]
07:54:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 410ms
07:54:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:54:33 INFO - 1498748073910 Marionette DEBUG Closed connection 400
07:54:33 INFO - 1498748073911 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50134
07:54:33 INFO - 1498748073911 Marionette DEBUG Closed connection 401
07:54:33 INFO - 1498748073913 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50135
07:54:33 INFO - 1498748073914 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073915 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073918 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073922 Marionette TRACE 402 <- [1,1,null,{"sessionId":"4f4d8049-ef95-42b2-ae15-752ab3a0c2fa","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073924 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
07:54:33 INFO - 1498748073924 Marionette TRACE 402 <- [1,2,null,{}]
07:54:33 INFO - 1498748073926 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:33 INFO - 1498748073926 Marionette TRACE 402 <- [1,3,null,{}]
07:54:33 INFO - 1498748073927 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:33 INFO - 1498748073928 Marionette TRACE 402 <- [1,4,null,{}]
07:54:33 INFO - 1498748073930 Marionette TRACE 402 -> [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}]
07:54:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498748073934 Marionette TRACE 402 <- [1,5,null,{"value":null}]
07:54:33 INFO - 1498748073936 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
07:54:33 INFO - 1498748073938 Marionette TRACE 402 <- [1,6,null,{}]
07:54:33 INFO - 1498748073940 Marionette DEBUG Closed connection 402
07:54:33 INFO - 1498748073941 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50136
07:54:33 INFO - 1498748073942 Marionette DEBUG Closed connection 403
07:54:33 INFO - 1498748073943 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50137
07:54:33 INFO - 1498748073944 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:33 INFO - 1498748073945 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073948 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073953 Marionette TRACE 404 <- [1,1,null,{"sessionId":"50aea6cf-6a64-49ff-b683-03144f581141","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073954 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
07:54:33 INFO - 1498748073956 Marionette TRACE 404 <- [1,2,null,{}]
07:54:33 INFO - 1498748073958 Marionette DEBUG Closed connection 404
07:54:33 INFO - 1498748073959 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50138
07:54:33 INFO - 1498748073960 Marionette DEBUG Closed connection 405
07:54:33 INFO - 1498748073961 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50139
07:54:33 INFO - 1498748073963 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:33 INFO - 1498748073964 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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}
07:54:33 INFO - 1498748073967 Marionette DEBUG Register listener.js for window 2147483649
07:54:33 INFO - 1498748073972 Marionette TRACE 406 <- [1,1,null,{"sessionId":"a5a4220d-5237-427d-a6df-6efe6204bfb2","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":1472,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpltw9eh.mozrunner","moz:accessibilityChecks":false}}]
07:54:33 INFO - 1498748073974 Marionette TRACE 406 -> [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"}]
07:54:33 INFO - 1498748073976 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"
07:54:33 INFO - 1498748073979 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"
07:54:33 INFO - 1498748073979 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"
07:54:33 INFO - 1498748073990 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"
07:54:33 INFO - 1498748073992 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"
07:54:34 INFO - 1498748074001 Marionette TRACE 406 <- [1,2,null,{}]
07:54:34 INFO - 1498748074012 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:54:34 INFO - 1498748074018 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5d96c524-de30-44ba-9d72-7a3424053f06","ELEMENT":"5d96c524-de30-44ba-9d72-7a3424053f06"}}]
07:54:34 INFO - 1498748074020 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5d96c524-de30-44ba-9d72-7a3424053f06"}]
07:54:34 INFO - 1498748074046 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
07:54:34 INFO - 1498748074048 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"5d96c524-de30-44ba-9d72-7a3424053f06"}]
07:54:34 INFO - 1498748074069 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element