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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:50:55 INFO - 1499435455537 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}]
06:50:55 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499435455539 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:50:55 INFO - 1499435455540 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:50:55 INFO - 1499435455541 Marionette TRACE 352 <- [1,6,null,{}]
06:50:55 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
06:50:55 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:50:55 INFO - 1499435455543 Marionette DEBUG Closed connection 352
06:50:55 INFO - 1499435455544 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49940
06:50:55 INFO - 1499435455545 Marionette DEBUG Closed connection 353
06:50:55 INFO - 1499435455546 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49941
06:50:55 INFO - 1499435455547 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:55 INFO - 1499435455548 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455550 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455553 Marionette TRACE 354 <- [1,1,null,{"sessionId":"35a7f3cf-a7dc-4bec-b33f-227a56c38013","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455555 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:50:55 INFO - 1499435455555 Marionette TRACE 354 <- [1,2,null,{}]
06:50:55 INFO - 1499435455556 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:55 INFO - 1499435455557 Marionette TRACE 354 <- [1,3,null,{}]
06:50:55 INFO - 1499435455557 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:55 INFO - 1499435455558 Marionette TRACE 354 <- [1,4,null,{}]
06:50:55 INFO - 1499435455560 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}]
06:50:55 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499435455562 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:50:55 INFO - 1499435455563 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:50:55 INFO - 1499435455565 Marionette TRACE 354 <- [1,6,null,{}]
06:50:55 INFO - 1499435455567 Marionette DEBUG Closed connection 354
06:50:55 INFO - 1499435455567 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49942
06:50:55 INFO - 1499435455568 Marionette DEBUG Closed connection 355
06:50:55 INFO - 1499435455569 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49943
06:50:55 INFO - 1499435455570 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:55 INFO - 1499435455577 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455579 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455582 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bea62dd4-06e6-4f8b-9fe4-c1898df65a99","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455584 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:50:55 INFO - 1499435455585 Marionette TRACE 356 <- [1,2,null,{}]
06:50:55 INFO - 1499435455587 Marionette DEBUG Closed connection 356
06:50:55 INFO - 1499435455588 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49944
06:50:55 INFO - 1499435455589 Marionette DEBUG Closed connection 357
06:50:55 INFO - 1499435455590 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49945
06:50:55 INFO - 1499435455591 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:55 INFO - 1499435455591 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455593 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455596 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e70c72f8-c0a5-4fd6-a1ea-0ecfb4076b86","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455598 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49513/clicks.html"}]
06:50:55 INFO - 1499435455600 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49513/hidden.html"
06:50:55 INFO - 1499435455605 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49513/hidden.html"
06:50:55 INFO - 1499435455606 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49513/hidden.html"
06:50:55 INFO - 1499435455622 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49513/clicks.html"
06:50:55 INFO - 1499435455627 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49513/clicks.html"
06:50:55 INFO - 1499435455631 Marionette TRACE 358 <- [1,2,null,{}]
06:50:55 INFO - 1499435455638 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:50:55 INFO - 1499435455641 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a593567-672e-4a36-bdd2-0b92c5e152a7","ELEMENT":"8a593567-672e-4a36-bdd2-0b92c5e152a7"}}]
06:50:55 INFO - 1499435455642 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"8a593567-672e-4a36-bdd2-0b92c5e152a7"}]
06:50:55 INFO - 1499435455681 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49513/clicks.html"
06:50:55 INFO - 1499435455689 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49513/clicks.html"
06:50:55 INFO - 1499435455689 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49513/clicks.html"
06:50:55 INFO - 1499435455704 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49513/test.html"
06:50:55 INFO - 1499435455708 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49513/test.html"
06:50:55 INFO - 1499435455711 Marionette TRACE 358 <- [1,4,null,{}]
06:50:55 INFO - 1499435455719 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:50:55 INFO - 1499435455723 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5bbe0371-0142-4693-a078-8b3e0173169e","ELEMENT":"5bbe0371-0142-4693-a078-8b3e0173169e"}}]
06:50:55 INFO - 1499435455724 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:50:55 INFO - 1499435455725 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:50:55 INFO - 1499435455727 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}]
06:50:55 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499435455730 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:50:55 INFO - 1499435455732 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:50:55 INFO - 1499435455733 Marionette TRACE 358 <- [1,8,null,{}]
06:50:55 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 191ms
06:50:55 INFO - TEST-START | test_click.py TestClick.test_container_element
06:50:55 INFO - 1499435455735 Marionette DEBUG Closed connection 358
06:50:55 INFO - 1499435455736 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49948
06:50:55 INFO - 1499435455736 Marionette DEBUG Closed connection 359
06:50:55 INFO - 1499435455737 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49949
06:50:55 INFO - 1499435455738 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:55 INFO - 1499435455739 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455741 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455744 Marionette TRACE 360 <- [1,1,null,{"sessionId":"97dc7b43-f52d-41f5-8444-aeb97ce6eb5c","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455746 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:50:55 INFO - 1499435455746 Marionette TRACE 360 <- [1,2,null,{}]
06:50:55 INFO - 1499435455747 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:55 INFO - 1499435455747 Marionette TRACE 360 <- [1,3,null,{}]
06:50:55 INFO - 1499435455748 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:55 INFO - 1499435455749 Marionette TRACE 360 <- [1,4,null,{}]
06:50:55 INFO - 1499435455750 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}]
06:50:55 INFO - TEST-START: test_click.py TestClick.test_container_element1499435455753 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:50:55 INFO - 1499435455754 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:50:55 INFO - 1499435455755 Marionette TRACE 360 <- [1,6,null,{}]
06:50:55 INFO - 1499435455757 Marionette DEBUG Closed connection 360
06:50:55 INFO - 1499435455757 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49950
06:50:55 INFO - 1499435455758 Marionette DEBUG Closed connection 361
06:50:55 INFO - 1499435455759 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49951
06:50:55 INFO - 1499435455760 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:55 INFO - 1499435455760 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455762 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455765 Marionette TRACE 362 <- [1,1,null,{"sessionId":"84cec6dc-c200-4ede-b720-c09518d48056","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455767 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:50:55 INFO - 1499435455768 Marionette TRACE 362 <- [1,2,null,{}]
06:50:55 INFO - 1499435455770 Marionette DEBUG Closed connection 362
06:50:55 INFO - 1499435455770 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49952
06:50:55 INFO - 1499435455771 Marionette DEBUG Closed connection 363
06:50:55 INFO - 1499435455772 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49953
06:50:55 INFO - 1499435455773 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:55 INFO - 1499435455773 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:55 INFO - 1499435455775 Marionette DEBUG Register listener.js for window 12
06:50:55 INFO - 1499435455778 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ee98147f-c4d7-4d6c-b771-4c516a18761e","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:55 INFO - 1499435455781 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"}]
06:50:55 INFO - 1499435455782 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49513/test.html"
06:50:55 INFO - 1499435455786 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49513/test.html"
06:50:55 INFO - 1499435455786 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49513/test.html"
06:50:55 INFO - 1499435455819 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"
06:50:55 INFO - 1499435455822 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"
06:50:55 INFO - 1499435455827 Marionette TRACE 364 <- [1,2,null,{}]
06:50:55 INFO - 1499435455835 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:50:55 INFO - 1499435455837 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cd98f89c-6c89-4594-971f-57cffb9a7567","ELEMENT":"cd98f89c-6c89-4594-971f-57cffb9a7567"}}]
06:50:55 INFO - 1499435455838 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cd98f89c-6c89-4594-971f-57cffb9a7567"}]
06:50:56 INFO - 1499435456077 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:50:56 INFO - 1499435456078 Marionette TRACE 364 <- [1,4,null,{}]
06:50:56 INFO - 1499435456080 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cd98f89c-6c89-4594-971f-57cffb9a7567","name":"selected"}]
06:50:56 INFO - 1499435456084 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:50:56 INFO - 1499435456086 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}]
06:50:56 INFO - TEST-END: test_click.py TestClick.test_container_element1499435456088 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:50:56 INFO - 1499435456089 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:50:56 INFO - 1499435456090 Marionette TRACE 364 <- [1,7,null,{}]
06:50:56 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
06:50:56 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:50:56 INFO - 1499435456092 Marionette DEBUG Closed connection 364
06:50:56 INFO - 1499435456094 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49954
06:50:56 INFO - 1499435456094 Marionette DEBUG Closed connection 365
06:50:56 INFO - 1499435456095 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49955
06:50:56 INFO - 1499435456097 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456098 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456100 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456103 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6f672565-553d-4ae0-9b6f-001222334571","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456104 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:50:56 INFO - 1499435456105 Marionette TRACE 366 <- [1,2,null,{}]
06:50:56 INFO - 1499435456106 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:56 INFO - 1499435456106 Marionette TRACE 366 <- [1,3,null,{}]
06:50:56 INFO - 1499435456107 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:56 INFO - 1499435456108 Marionette TRACE 366 <- [1,4,null,{}]
06:50:56 INFO - 1499435456109 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}]
06:50:56 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499435456112 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:50:56 INFO - 1499435456113 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:50:56 INFO - 1499435456114 Marionette TRACE 366 <- [1,6,null,{}]
06:50:56 INFO - 1499435456116 Marionette DEBUG Closed connection 366
06:50:56 INFO - 1499435456116 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49956
06:50:56 INFO - 1499435456117 Marionette DEBUG Closed connection 367
06:50:56 INFO - 1499435456118 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49957
06:50:56 INFO - 1499435456119 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456119 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456121 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456131 Marionette TRACE 368 <- [1,1,null,{"sessionId":"a0e0f019-51cc-4339-85c6-d8e4ee0b7a1b","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456133 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:50:56 INFO - 1499435456134 Marionette TRACE 368 <- [1,2,null,{}]
06:50:56 INFO - 1499435456136 Marionette DEBUG Closed connection 368
06:50:56 INFO - 1499435456137 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49958
06:50:56 INFO - 1499435456137 Marionette DEBUG Closed connection 369
06:50:56 INFO - 1499435456138 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49959
06:50:56 INFO - 1499435456139 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:56 INFO - 1499435456140 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456142 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456145 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0c84b818-7acb-4686-8888-53bfb0db64bf","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456147 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"}]
06:50:56 INFO - 1499435456149 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"
06:50:56 INFO - 1499435456152 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"
06:50:56 INFO - 1499435456152 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"
06:50:56 INFO - 1499435456170 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"
06:50:56 INFO - 1499435456171 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"
06:50:56 INFO - 1499435456174 Marionette TRACE 370 <- [1,2,null,{}]
06:50:56 INFO - 1499435456176 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:50:56 INFO - 1499435456178 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"72e97fcf-d0a1-43d6-b7fa-3026f84b4b42","ELEMENT":"72e97fcf-d0a1-43d6-b7fa-3026f84b4b42"}}]
06:50:56 INFO - 1499435456184 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"72e97fcf-d0a1-43d6-b7fa-3026f84b4b42"}]
06:50:56 INFO - 1499435456424 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:50:56 INFO - 1499435456424 Marionette TRACE 370 <- [1,4,null,{}]
06:50:56 INFO - 1499435456426 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"72e97fcf-d0a1-43d6-b7fa-3026f84b4b42","name":"selected"}]
06:50:56 INFO - 1499435456430 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:50:56 INFO - 1499435456431 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}]
06:50:56 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499435456434 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:50:56 INFO - 1499435456435 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:50:56 INFO - 1499435456436 Marionette TRACE 370 <- [1,7,null,{}]
06:50:56 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 345ms
06:50:56 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:50:56 INFO - 1499435456438 Marionette DEBUG Closed connection 370
06:50:56 INFO - 1499435456439 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49960
06:50:56 INFO - 1499435456440 Marionette DEBUG Closed connection 371
06:50:56 INFO - 1499435456441 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49961
06:50:56 INFO - 1499435456442 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456442 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456444 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456447 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3c34cdbf-9d78-40f0-bac2-6d835f545db6","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456449 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:50:56 INFO - 1499435456450 Marionette TRACE 372 <- [1,2,null,{}]
06:50:56 INFO - 1499435456451 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:56 INFO - 1499435456451 Marionette TRACE 372 <- [1,3,null,{}]
06:50:56 INFO - 1499435456452 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:56 INFO - 1499435456452 Marionette TRACE 372 <- [1,4,null,{}]
06:50:56 INFO - 1499435456454 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}]
06:50:56 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499435456457 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:50:56 INFO - 1499435456457 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:50:56 INFO - 1499435456459 Marionette TRACE 372 <- [1,6,null,{}]
06:50:56 INFO - 1499435456460 Marionette DEBUG Closed connection 372
06:50:56 INFO - 1499435456461 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49962
06:50:56 INFO - 1499435456462 Marionette DEBUG Closed connection 373
06:50:56 INFO - 1499435456463 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49963
06:50:56 INFO - 1499435456464 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456464 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456466 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456469 Marionette TRACE 374 <- [1,1,null,{"sessionId":"41cf0adb-2502-4604-a1df-b7d5e7d5da58","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456471 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:50:56 INFO - 1499435456472 Marionette TRACE 374 <- [1,2,null,{}]
06:50:56 INFO - 1499435456474 Marionette DEBUG Closed connection 374
06:50:56 INFO - 1499435456475 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49964
06:50:56 INFO - 1499435456475 Marionette DEBUG Closed connection 375
06:50:56 INFO - 1499435456476 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49965
06:50:56 INFO - 1499435456477 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:56 INFO - 1499435456478 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456480 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456483 Marionette TRACE 376 <- [1,1,null,{"sessionId":"acb7f8c3-2e76-406f-a44d-b87f565b893e","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456485 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"}]
06:50:56 INFO - 1499435456487 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"
06:50:56 INFO - 1499435456490 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"
06:50:56 INFO - 1499435456491 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"
06:50:56 INFO - 1499435456510 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"
06:50:56 INFO - 1499435456513 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"
06:50:56 INFO - 1499435456522 Marionette TRACE 376 <- [1,2,null,{}]
06:50:56 INFO - 1499435456524 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:50:56 INFO - 1499435456526 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7673316a-5d99-4a17-8231-3fe36419df05","ELEMENT":"7673316a-5d99-4a17-8231-3fe36419df05"}}]
06:50:56 INFO - 1499435456527 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"7673316a-5d99-4a17-8231-3fe36419df05"}]
06:50:56 INFO - 1499435456763 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:50:56 INFO - 1499435456763 Marionette TRACE 376 <- [1,4,null,{}]
06:50:56 INFO - 1499435456765 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}]
06:50:56 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499435456768 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:50:56 INFO - 1499435456769 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:50:56 INFO - 1499435456770 Marionette TRACE 376 <- [1,6,null,{}]
06:50:56 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 333ms
06:50:56 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:50:56 INFO - 1499435456772 Marionette DEBUG Closed connection 376
06:50:56 INFO - 1499435456773 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49966
06:50:56 INFO - 1499435456774 Marionette DEBUG Closed connection 377
06:50:56 INFO - 1499435456775 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49967
06:50:56 INFO - 1499435456776 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456776 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456778 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456781 Marionette TRACE 378 <- [1,1,null,{"sessionId":"14afc75e-31d3-496b-be83-027311b13801","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456783 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:50:56 INFO - 1499435456784 Marionette TRACE 378 <- [1,2,null,{}]
06:50:56 INFO - 1499435456785 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:56 INFO - 1499435456785 Marionette TRACE 378 <- [1,3,null,{}]
06:50:56 INFO - 1499435456786 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:56 INFO - 1499435456787 Marionette TRACE 378 <- [1,4,null,{}]
06:50:56 INFO - 1499435456788 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}]
06:50:56 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499435456791 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:50:56 INFO - 1499435456792 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:50:56 INFO - 1499435456793 Marionette TRACE 378 <- [1,6,null,{}]
06:50:56 INFO - 1499435456795 Marionette DEBUG Closed connection 378
06:50:56 INFO - 1499435456795 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49968
06:50:56 INFO - 1499435456796 Marionette DEBUG Closed connection 379
06:50:56 INFO - 1499435456797 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49969
06:50:56 INFO - 1499435456798 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:56 INFO - 1499435456798 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456801 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456803 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3d66704a-27f3-40e6-8793-847cb55c0e7c","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456812 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:50:56 INFO - 1499435456813 Marionette TRACE 380 <- [1,2,null,{}]
06:50:56 INFO - 1499435456815 Marionette DEBUG Closed connection 380
06:50:56 INFO - 1499435456816 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49970
06:50:56 INFO - 1499435456816 Marionette DEBUG Closed connection 381
06:50:56 INFO - 1499435456817 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49971
06:50:56 INFO - 1499435456818 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:56 INFO - 1499435456819 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:56 INFO - 1499435456821 Marionette DEBUG Register listener.js for window 12
06:50:56 INFO - 1499435456823 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1fe9507b-f5d7-4490-abaa-1e90054b9e9a","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:56 INFO - 1499435456825 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"}]
06:50:56 INFO - 1499435456827 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"
06:50:56 INFO - 1499435456829 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"
06:50:56 INFO - 1499435456829 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"
06:50:56 INFO - 1499435456844 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"
06:50:56 INFO - 1499435456850 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"
06:50:56 INFO - 1499435456853 Marionette TRACE 382 <- [1,2,null,{}]
06:50:56 INFO - 1499435456854 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:50:56 INFO - 1499435456856 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf3fd05d-979b-45f8-8982-2fea7c352c61","ELEMENT":"bf3fd05d-979b-45f8-8982-2fea7c352c61"}}]
06:50:56 INFO - 1499435456857 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"bf3fd05d-979b-45f8-8982-2fea7c352c61"}]
06:50:57 INFO - 1499435457103 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:50:57 INFO - 1499435457103 Marionette TRACE 382 <- [1,4,null,{}]
06:50:57 INFO - 1499435457106 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}]
06:50:57 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499435457108 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:50:57 INFO - 1499435457109 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:50:57 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
06:50:57 INFO - TEST-START | test_click.py TestClick.test_input_file
06:50:57 INFO - 1499435457110 Marionette TRACE 382 <- [1,6,null,{}]
06:50:57 INFO - 1499435457112 Marionette DEBUG Closed connection 382
06:50:57 INFO - 1499435457113 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49972
06:50:57 INFO - 1499435457114 Marionette DEBUG Closed connection 383
06:50:57 INFO - 1499435457115 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49973
06:50:57 INFO - 1499435457116 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457117 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457119 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457121 Marionette TRACE 384 <- [1,1,null,{"sessionId":"006cae92-c1af-4c55-9720-a12184d59317","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457123 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:50:57 INFO - 1499435457124 Marionette TRACE 384 <- [1,2,null,{}]
06:50:57 INFO - 1499435457125 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:57 INFO - 1499435457125 Marionette TRACE 384 <- [1,3,null,{}]
06:50:57 INFO - 1499435457126 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:57 INFO - 1499435457126 Marionette TRACE 384 <- [1,4,null,{}]
06:50:57 INFO - 1499435457128 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}]
06:50:57 INFO - TEST-START: test_click.py TestClick.test_input_file1499435457131 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:50:57 INFO - 1499435457132 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:50:57 INFO - 1499435457133 Marionette TRACE 384 <- [1,6,null,{}]
06:50:57 INFO - 1499435457135 Marionette DEBUG Closed connection 384
06:50:57 INFO - 1499435457135 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49974
06:50:57 INFO - 1499435457136 Marionette DEBUG Closed connection 385
06:50:57 INFO - 1499435457137 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49975
06:50:57 INFO - 1499435457138 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457138 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457141 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457143 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f4b5ea4c-7cd4-4b9f-b30e-1bc93c8fff47","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457145 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:50:57 INFO - 1499435457146 Marionette TRACE 386 <- [1,2,null,{}]
06:50:57 INFO - 1499435457148 Marionette DEBUG Closed connection 386
06:50:57 INFO - 1499435457149 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49976
06:50:57 INFO - 1499435457149 Marionette DEBUG Closed connection 387
06:50:57 INFO - 1499435457150 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49977
06:50:57 INFO - 1499435457151 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:57 INFO - 1499435457152 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457154 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457157 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6037516a-dab4-46c2-8c9b-f35e3d237691","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457159 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:50:57 INFO - 1499435457160 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"
06:50:57 INFO - 1499435457162 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"
06:50:57 INFO - 1499435457163 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"
06:50:57 INFO - 1499435457185 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:50:57 INFO - 1499435457186 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:50:57 INFO - 1499435457189 Marionette TRACE 388 <- [1,2,null,{}]
06:50:57 INFO - 1499435457190 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:50:57 INFO - 1499435457193 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23f74340-72a0-45f3-bfee-a799c55005d8","ELEMENT":"23f74340-72a0-45f3-bfee-a799c55005d8"}}]
06:50:57 INFO - 1499435457194 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"23f74340-72a0-45f3-bfee-a799c55005d8"}]
06:50:57 INFO - 1499435457224 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:50:57 INFO - 1499435457227 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}]
06:50:57 INFO - TEST-END: test_click.py TestClick.test_input_file1499435457230 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:50:57 INFO - 1499435457231 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:50:57 INFO - 1499435457232 Marionette TRACE 388 <- [1,6,null,{}]
06:50:57 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
06:50:57 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:50:57 INFO - 1499435457234 Marionette DEBUG Closed connection 388
06:50:57 INFO - 1499435457235 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49978
06:50:57 INFO - 1499435457235 Marionette DEBUG Closed connection 389
06:50:57 INFO - 1499435457236 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49979
06:50:57 INFO - 1499435457237 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457238 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457240 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457243 Marionette TRACE 390 <- [1,1,null,{"sessionId":"bf5d988f-16d6-4576-a74e-e61134cc7a16","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457245 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:50:57 INFO - 1499435457245 Marionette TRACE 390 <- [1,2,null,{}]
06:50:57 INFO - 1499435457246 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:57 INFO - 1499435457247 Marionette TRACE 390 <- [1,3,null,{}]
06:50:57 INFO - 1499435457247 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:57 INFO - 1499435457248 Marionette TRACE 390 <- [1,4,null,{}]
06:50:57 INFO - 1499435457249 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}]
06:50:57 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499435457252 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:50:57 INFO - 1499435457253 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:50:57 INFO - 1499435457254 Marionette TRACE 390 <- [1,6,null,{}]
06:50:57 INFO - 1499435457256 Marionette DEBUG Closed connection 390
06:50:57 INFO - 1499435457256 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49980
06:50:57 INFO - 1499435457257 Marionette DEBUG Closed connection 391
06:50:57 INFO - 1499435457258 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49981
06:50:57 INFO - 1499435457259 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457259 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457261 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457264 Marionette TRACE 392 <- [1,1,null,{"sessionId":"20eed781-c840-4bad-ac36-8a604139ac0f","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457266 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:50:57 INFO - 1499435457267 Marionette TRACE 392 <- [1,2,null,{}]
06:50:57 INFO - 1499435457269 Marionette DEBUG Closed connection 392
06:50:57 INFO - 1499435457269 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49982
06:50:57 INFO - 1499435457270 Marionette DEBUG Closed connection 393
06:50:57 INFO - 1499435457271 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49983
06:50:57 INFO - 1499435457272 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:57 INFO - 1499435457272 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457275 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457284 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3d06efe7-9ce7-447f-98ae-c3acf1c26bf4","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457286 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"}]
06:50:57 INFO - 1499435457288 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:50:57 INFO - 1499435457290 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:50:57 INFO - 1499435457290 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:50:57 INFO - 1499435457308 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"
06:50:57 INFO - 1499435457311 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"
06:50:57 INFO - 1499435457313 Marionette TRACE 394 <- [1,2,null,{}]
06:50:57 INFO - 1499435457321 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:50:57 INFO - 1499435457323 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7234e74-da59-4750-b4cb-2509346868bb","ELEMENT":"e7234e74-da59-4750-b4cb-2509346868bb"}}]
06:50:57 INFO - 1499435457324 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:50:57 INFO - 1499435457326 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18309339-98eb-4f94-9b27-7d1318a79fe6","ELEMENT":"18309339-98eb-4f94-9b27-7d1318a79fe6"}}]
06:50:57 INFO - 1499435457327 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e7234e74-da59-4750-b4cb-2509346868bb"}]
06:50:57 INFO - 1499435457561 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:50:57 INFO - 1499435457561 Marionette TRACE 394 <- [1,5,null,{}]
06:50:57 INFO - 1499435457563 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"18309339-98eb-4f94-9b27-7d1318a79fe6"}]
06:50:57 INFO - 1499435457577 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:50:57 INFO - 1499435457579 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:50:57 INFO - 1499435457581 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:50:57 INFO - 1499435457583 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}]
06:50:57 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499435457585 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:50:57 INFO - 1499435457586 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:50:57 INFO - 1499435457587 Marionette TRACE 394 <- [1,9,null,{}]
06:50:57 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 355ms
06:50:57 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:50:57 INFO - 1499435457589 Marionette DEBUG Closed connection 394
06:50:57 INFO - 1499435457590 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49984
06:50:57 INFO - 1499435457591 Marionette DEBUG Closed connection 395
06:50:57 INFO - 1499435457592 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49985
06:50:57 INFO - 1499435457593 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457593 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457596 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457598 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6f0ab1bf-54cb-499c-9e9c-50158945f143","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457600 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:50:57 INFO - 1499435457601 Marionette TRACE 396 <- [1,2,null,{}]
06:50:57 INFO - 1499435457602 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:50:57 INFO - 1499435457602 Marionette TRACE 396 <- [1,3,null,{}]
06:50:57 INFO - 1499435457603 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:50:57 INFO - 1499435457603 Marionette TRACE 396 <- [1,4,null,{}]
06:50:57 INFO - 1499435457605 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}]
06:50:57 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499435457608 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:50:57 INFO - 1499435457608 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:50:57 INFO - 1499435457610 Marionette TRACE 396 <- [1,6,null,{}]
06:50:57 INFO - 1499435457611 Marionette DEBUG Closed connection 396
06:50:57 INFO - 1499435457612 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49986
06:50:57 INFO - 1499435457613 Marionette DEBUG Closed connection 397
06:50:57 INFO - 1499435457614 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49987
06:50:57 INFO - 1499435457615 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:50:57 INFO - 1499435457615 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457618 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457620 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7859ccab-a85a-449e-92a5-1f6eb68386af","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457622 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:50:57 INFO - 1499435457623 Marionette TRACE 398 <- [1,2,null,{}]
06:50:57 INFO - 1499435457625 Marionette DEBUG Closed connection 398
06:50:57 INFO - 1499435457626 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49988
06:50:57 INFO - 1499435457626 Marionette DEBUG Closed connection 399
06:50:57 INFO - 1499435457627 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49989
06:50:57 INFO - 1499435457629 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:50:57 INFO - 1499435457630 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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}
06:50:57 INFO - 1499435457632 Marionette DEBUG Register listener.js for window 12
06:50:57 INFO - 1499435457634 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8c8709a4-8ffa-42ef-8891-4f15748d8b12","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":2880,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpehsvwl.mozrunner","moz:accessibilityChecks":false}}]
06:50:57 INFO - 1499435457636 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"}]
06:50:57 INFO - 1499435457638 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"
06:50:57 INFO - 1499435457640 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"
06:50:57 INFO - 1499435457640 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"
06:50:57 INFO - 1499435457657 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"
06:50:57 INFO - 1499435457659 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"
06:50:57 INFO - 1499435457662 Marionette TRACE 400 <- [1,2,null,{}]
06:50:57 INFO - 1499435457670 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:50:57 INFO - 1499435457672 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80db2606-54ce-4b78-b910-5d2796ef2091","ELEMENT":"80db2606-54ce-4b78-b910-5d2796ef2091"}}]
06:50:57 INFO - 1499435457673 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"80db2606-54ce-4b78-b910-5d2796ef2091"}]
06:50:57 INFO - 1499435457695 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:50:57 INFO - 1499435457697 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"80db2606-54ce-4b78-b910-5d2796ef2091"}]
06:50:57 INFO - 1499435457711 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element