could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
15:51:49 INFO - 1498863109370 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}]
15:51:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498863109374 Marionette TRACE 358 <- [1,5,null,{"value":null}]
15:51:49 INFO - 1498863109376 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
15:51:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 233ms
15:51:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:51:49 INFO - 1498863109378 Marionette TRACE 358 <- [1,6,null,{}]
15:51:49 INFO - 1498863109381 Marionette DEBUG Closed connection 358
15:51:49 INFO - 1498863109382 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49740
15:51:49 INFO - 1498863109383 Marionette DEBUG Closed connection 359
15:51:49 INFO - 1498863109384 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49741
15:51:49 INFO - 1498863109386 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:49 INFO - 1498863109387 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109390 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109395 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3c32c0b6-918a-4fc3-9afa-97e749b9869d","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109397 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:51:49 INFO - 1498863109397 Marionette TRACE 360 <- [1,2,null,{}]
15:51:49 INFO - 1498863109400 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:49 INFO - 1498863109402 Marionette TRACE 360 <- [1,3,null,{}]
15:51:49 INFO - 1498863109403 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:49 INFO - 1498863109404 Marionette TRACE 360 <- [1,4,null,{}]
15:51:49 INFO - 1498863109406 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}]
15:51:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498863109410 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:51:49 INFO - 1498863109411 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:51:49 INFO - 1498863109413 Marionette TRACE 360 <- [1,6,null,{}]
15:51:49 INFO - 1498863109415 Marionette DEBUG Closed connection 360
15:51:49 INFO - 1498863109416 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49742
15:51:49 INFO - 1498863109417 Marionette DEBUG Closed connection 361
15:51:49 INFO - 1498863109418 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49743
15:51:49 INFO - 1498863109419 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:49 INFO - 1498863109420 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109423 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109428 Marionette TRACE 362 <- [1,1,null,{"sessionId":"43fc8812-5989-46d3-afd8-6f2df00410db","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109468 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:51:49 INFO - 1498863109470 Marionette TRACE 362 <- [1,2,null,{}]
15:51:49 INFO - 1498863109473 Marionette DEBUG Closed connection 362
15:51:49 INFO - 1498863109474 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49744
15:51:49 INFO - 1498863109475 Marionette DEBUG Closed connection 363
15:51:49 INFO - 1498863109476 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49745
15:51:49 INFO - 1498863109477 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:49 INFO - 1498863109478 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109481 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109487 Marionette TRACE 364 <- [1,1,null,{"sessionId":"81dad9c3-2138-4043-a83c-410a5b6db082","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109553 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49343/clicks.html"}]
15:51:49 INFO - 1498863109555 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/hidden.html"
15:51:49 INFO - 1498863109563 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/hidden.html"
15:51:49 INFO - 1498863109563 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/hidden.html"
15:51:49 INFO - 1498863109587 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49343/clicks.html"
15:51:49 INFO - 1498863109598 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49343/clicks.html"
15:51:49 INFO - 1498863109603 Marionette TRACE 364 <- [1,2,null,{}]
15:51:49 INFO - 1498863109614 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:51:49 INFO - 1498863109618 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0085718f-1d46-4cb3-8077-88c777499f6e","ELEMENT":"0085718f-1d46-4cb3-8077-88c777499f6e"}}]
15:51:49 INFO - 1498863109621 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0085718f-1d46-4cb3-8077-88c777499f6e"}]
15:51:49 INFO - 1498863109670 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/clicks.html"
15:51:49 INFO - 1498863109681 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/clicks.html"
15:51:49 INFO - 1498863109681 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/clicks.html"
15:51:49 INFO - 1498863109715 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49343/test.html"
15:51:49 INFO - 1498863109720 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49343/test.html"
15:51:49 INFO - 1498863109734 Marionette TRACE 364 <- [1,4,null,{}]
15:51:49 INFO - 1498863109736 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:51:49 INFO - 1498863109741 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4b184e4-e7fa-4e6c-b016-f026dbbbaff8","ELEMENT":"c4b184e4-e7fa-4e6c-b016-f026dbbbaff8"}}]
15:51:49 INFO - 1498863109746 Marionette TRACE 364 -> [0,6,"getTitle",{}]
15:51:49 INFO - 1498863109749 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
15:51:49 INFO - 1498863109751 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}]
15:51:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498863109756 Marionette TRACE 364 <- [1,7,null,{"value":null}]
15:51:49 INFO - 1498863109757 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
15:51:49 INFO - 1498863109759 Marionette TRACE 364 <- [1,8,null,{}]
15:51:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 382ms
15:51:49 INFO - TEST-START | test_click.py TestClick.test_container_element
15:51:49 INFO - 1498863109771 Marionette DEBUG Closed connection 364
15:51:49 INFO - 1498863109772 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49748
15:51:49 INFO - 1498863109773 Marionette DEBUG Closed connection 365
15:51:49 INFO - 1498863109774 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49749
15:51:49 INFO - 1498863109775 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:49 INFO - 1498863109778 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109781 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109787 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6a96f604-b332-4219-ba0d-258857725e0e","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109789 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:51:49 INFO - 1498863109790 Marionette TRACE 366 <- [1,2,null,{}]
15:51:49 INFO - 1498863109792 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:49 INFO - 1498863109792 Marionette TRACE 366 <- [1,3,null,{}]
15:51:49 INFO - 1498863109795 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:49 INFO - 1498863109797 Marionette TRACE 366 <- [1,4,null,{}]
15:51:49 INFO - 1498863109799 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}]
15:51:49 INFO - TEST-START: test_click.py TestClick.test_container_element1498863109802 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:51:49 INFO - 1498863109803 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:51:49 INFO - 1498863109805 Marionette TRACE 366 <- [1,6,null,{}]
15:51:49 INFO - 1498863109809 Marionette DEBUG Closed connection 366
15:51:49 INFO - 1498863109810 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49750
15:51:49 INFO - 1498863109811 Marionette DEBUG Closed connection 367
15:51:49 INFO - 1498863109812 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49751
15:51:49 INFO - 1498863109814 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:49 INFO - 1498863109815 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109818 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109824 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d90fa2c2-e097-421c-aa01-ae355679741a","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109825 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:51:49 INFO - 1498863109827 Marionette TRACE 368 <- [1,2,null,{}]
15:51:49 INFO - 1498863109829 Marionette DEBUG Closed connection 368
15:51:49 INFO - 1498863109830 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49752
15:51:49 INFO - 1498863109831 Marionette DEBUG Closed connection 369
15:51:49 INFO - 1498863109832 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49753
15:51:49 INFO - 1498863109833 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:49 INFO - 1498863109834 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:49 INFO - 1498863109837 Marionette DEBUG Register listener.js for window 7
15:51:49 INFO - 1498863109842 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b48d9f28-06e7-4c59-ade5-5dc3a1d94af8","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:49 INFO - 1498863109843 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"}]
15:51:49 INFO - 1498863109846 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49343/test.html"
15:51:49 INFO - 1498863109849 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49343/test.html"
15:51:49 INFO - 1498863109849 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49343/test.html"
15:51:49 INFO - 1498863109877 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"
15:51:49 INFO - 1498863109880 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"
15:51:49 INFO - 1498863109885 Marionette TRACE 370 <- [1,2,null,{}]
15:51:49 INFO - 1498863109886 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:51:49 INFO - 1498863109908 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e42fdd2d-65a1-4a9a-8951-4e7ef238fb0e","ELEMENT":"e42fdd2d-65a1-4a9a-8951-4e7ef238fb0e"}}]
15:51:49 INFO - 1498863109910 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"e42fdd2d-65a1-4a9a-8951-4e7ef238fb0e"}]
15:51:50 INFO - 1498863110156 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:51:50 INFO - 1498863110157 Marionette TRACE 370 <- [1,4,null,{}]
15:51:50 INFO - 1498863110165 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"e42fdd2d-65a1-4a9a-8951-4e7ef238fb0e","name":"selected"}]
15:51:50 INFO - 1498863110178 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:51:50 INFO - 1498863110180 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}]
15:51:50 INFO - TEST-END: test_click.py TestClick.test_container_element1498863110184 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:51:50 INFO - 1498863110202 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:51:50 INFO - 1498863110203 Marionette TRACE 370 <- [1,7,null,{}]
15:51:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 443ms
15:51:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:51:50 INFO - 1498863110206 Marionette DEBUG Closed connection 370
15:51:50 INFO - 1498863110207 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49754
15:51:50 INFO - 1498863110210 Marionette DEBUG Closed connection 371
15:51:50 INFO - 1498863110211 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49755
15:51:50 INFO - 1498863110212 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:50 INFO - 1498863110213 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110216 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110221 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7f0deaf3-c25f-4170-ad1c-0db519fe9fc0","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110249 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:51:50 INFO - 1498863110250 Marionette TRACE 372 <- [1,2,null,{}]
15:51:50 INFO - 1498863110251 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:50 INFO - 1498863110252 Marionette TRACE 372 <- [1,3,null,{}]
15:51:50 INFO - 1498863110253 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:50 INFO - 1498863110254 Marionette TRACE 372 <- [1,4,null,{}]
15:51:50 INFO - 1498863110260 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}]
15:51:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498863110263 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:51:50 INFO - 1498863110264 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:51:50 INFO - 1498863110266 Marionette TRACE 372 <- [1,6,null,{}]
15:51:50 INFO - 1498863110269 Marionette DEBUG Closed connection 372
15:51:50 INFO - 1498863110270 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49756
15:51:50 INFO - 1498863110271 Marionette DEBUG Closed connection 373
15:51:50 INFO - 1498863110272 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49757
15:51:50 INFO - 1498863110274 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:50 INFO - 1498863110275 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110278 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110283 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ccab8486-860d-4012-aeb9-bb000db7fb4d","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110284 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:51:50 INFO - 1498863110286 Marionette TRACE 374 <- [1,2,null,{}]
15:51:50 INFO - 1498863110290 Marionette DEBUG Closed connection 374
15:51:50 INFO - 1498863110292 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49758
15:51:50 INFO - 1498863110294 Marionette DEBUG Closed connection 375
15:51:50 INFO - 1498863110295 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49759
15:51:50 INFO - 1498863110296 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:50 INFO - 1498863110297 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110300 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110305 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c7ab614f-3cbc-416a-ae44-9afd76e12fc3","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110306 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"}]
15:51:50 INFO - 1498863110308 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"
15:51:50 INFO - 1498863110311 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"
15:51:50 INFO - 1498863110312 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"
15:51:50 INFO - 1498863110333 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"
15:51:50 INFO - 1498863110338 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"
15:51:50 INFO - 1498863110349 Marionette TRACE 376 <- [1,2,null,{}]
15:51:50 INFO - 1498863110351 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:51:50 INFO - 1498863110354 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b72ac6c-e434-4e08-8e01-58fbd9f2f46a","ELEMENT":"0b72ac6c-e434-4e08-8e01-58fbd9f2f46a"}}]
15:51:50 INFO - 1498863110355 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"0b72ac6c-e434-4e08-8e01-58fbd9f2f46a"}]
15:51:50 INFO - 1498863110602 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:51:50 INFO - 1498863110603 Marionette TRACE 376 <- [1,4,null,{}]
15:51:50 INFO - 1498863110647 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"0b72ac6c-e434-4e08-8e01-58fbd9f2f46a","name":"selected"}]
15:51:50 INFO - 1498863110653 Marionette TRACE 376 <- [1,5,null,{"value":true}]
15:51:50 INFO - 1498863110667 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}]
15:51:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498863110671 Marionette TRACE 376 <- [1,6,null,{"value":null}]
15:51:50 INFO - 1498863110673 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
15:51:50 INFO - 1498863110675 Marionette TRACE 376 <- [1,7,null,{}]
15:51:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 471ms
15:51:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:51:50 INFO - 1498863110678 Marionette DEBUG Closed connection 376
15:51:50 INFO - 1498863110679 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49760
15:51:50 INFO - 1498863110680 Marionette DEBUG Closed connection 377
15:51:50 INFO - 1498863110681 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49761
15:51:50 INFO - 1498863110682 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:50 INFO - 1498863110683 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110686 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110690 Marionette TRACE 378 <- [1,1,null,{"sessionId":"3f6056a1-0828-4e9d-830e-268e00f74146","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110691 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:51:50 INFO - 1498863110692 Marionette TRACE 378 <- [1,2,null,{}]
15:51:50 INFO - 1498863110693 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:50 INFO - 1498863110693 Marionette TRACE 378 <- [1,3,null,{}]
15:51:50 INFO - 1498863110694 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:50 INFO - 1498863110695 Marionette TRACE 378 <- [1,4,null,{}]
15:51:50 INFO - 1498863110697 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}]
15:51:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498863110701 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:51:50 INFO - 1498863110702 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:51:50 INFO - 1498863110703 Marionette TRACE 378 <- [1,6,null,{}]
15:51:50 INFO - 1498863110706 Marionette DEBUG Closed connection 378
15:51:50 INFO - 1498863110706 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49762
15:51:50 INFO - 1498863110707 Marionette DEBUG Closed connection 379
15:51:50 INFO - 1498863110708 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49763
15:51:50 INFO - 1498863110709 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:50 INFO - 1498863110710 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110713 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110717 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9a89b26f-9c6a-4f64-9f35-755777b4835f","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110719 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:51:50 INFO - 1498863110720 Marionette TRACE 380 <- [1,2,null,{}]
15:51:50 INFO - 1498863110723 Marionette DEBUG Closed connection 380
15:51:50 INFO - 1498863110724 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49764
15:51:50 INFO - 1498863110725 Marionette DEBUG Closed connection 381
15:51:50 INFO - 1498863110726 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49765
15:51:50 INFO - 1498863110727 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:50 INFO - 1498863110728 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:50 INFO - 1498863110731 Marionette DEBUG Register listener.js for window 7
15:51:50 INFO - 1498863110735 Marionette TRACE 382 <- [1,1,null,{"sessionId":"fb66d369-e3db-45ca-b421-4f5c240fc3b3","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:50 INFO - 1498863110737 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"}]
15:51:50 INFO - 1498863110738 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"
15:51:50 INFO - 1498863110742 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"
15:51:50 INFO - 1498863110742 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"
15:51:50 INFO - 1498863110767 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"
15:51:50 INFO - 1498863110770 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"
15:51:50 INFO - 1498863110782 Marionette TRACE 382 <- [1,2,null,{}]
15:51:50 INFO - 1498863110784 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:51:50 INFO - 1498863110787 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"137cb4de-6fc3-4657-93bd-ea3ff95137af","ELEMENT":"137cb4de-6fc3-4657-93bd-ea3ff95137af"}}]
15:51:50 INFO - 1498863110788 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"137cb4de-6fc3-4657-93bd-ea3ff95137af"}]
15:51:51 INFO - 1498863111031 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:51:51 INFO - 1498863111032 Marionette TRACE 382 <- [1,4,null,{}]
15:51:51 INFO - 1498863111034 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}]
15:51:51 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498863111037 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111038 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:51:51 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 364ms
15:51:51 INFO - 1498863111040 Marionette TRACE 382 <- [1,6,null,{}]
15:51:51 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:51:51 INFO - 1498863111042 Marionette DEBUG Closed connection 382
15:51:51 INFO - 1498863111044 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49766
15:51:51 INFO - 1498863111045 Marionette DEBUG Closed connection 383
15:51:51 INFO - 1498863111045 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49767
15:51:51 INFO - 1498863111047 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111048 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111050 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111054 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3d1af1b1-8385-4f79-9a7f-b47e227ddc0f","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111056 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:51:51 INFO - 1498863111056 Marionette TRACE 384 <- [1,2,null,{}]
15:51:51 INFO - 1498863111057 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:51 INFO - 1498863111058 Marionette TRACE 384 <- [1,3,null,{}]
15:51:51 INFO - 1498863111059 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:51 INFO - 1498863111059 Marionette TRACE 384 <- [1,4,null,{}]
15:51:51 INFO - 1498863111061 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}]
15:51:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498863111064 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111065 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:51:51 INFO - 1498863111067 Marionette TRACE 384 <- [1,6,null,{}]
15:51:51 INFO - 1498863111069 Marionette DEBUG Closed connection 384
15:51:51 INFO - 1498863111070 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49768
15:51:51 INFO - 1498863111071 Marionette DEBUG Closed connection 385
15:51:51 INFO - 1498863111072 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49769
15:51:51 INFO - 1498863111073 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111074 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111078 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111093 Marionette TRACE 386 <- [1,1,null,{"sessionId":"17a266c6-109b-41c7-9855-035e6ece910f","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111094 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:51:51 INFO - 1498863111096 Marionette TRACE 386 <- [1,2,null,{}]
15:51:51 INFO - 1498863111098 Marionette DEBUG Closed connection 386
15:51:51 INFO - 1498863111099 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49770
15:51:51 INFO - 1498863111100 Marionette DEBUG Closed connection 387
15:51:51 INFO - 1498863111101 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49771
15:51:51 INFO - 1498863111102 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:51 INFO - 1498863111103 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111106 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111109 Marionette TRACE 388 <- [1,1,null,{"sessionId":"37279e5e-afa5-4536-ba72-c38a157b6b56","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111111 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"}]
15:51:51 INFO - 1498863111113 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"
15:51:51 INFO - 1498863111117 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"
15:51:51 INFO - 1498863111118 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"
15:51:51 INFO - 1498863111142 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"
15:51:51 INFO - 1498863111148 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"
15:51:51 INFO - 1498863111152 Marionette TRACE 388 <- [1,2,null,{}]
15:51:51 INFO - 1498863111154 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:51:51 INFO - 1498863111165 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"574b56d9-6d93-4632-864c-831e9308883b","ELEMENT":"574b56d9-6d93-4632-864c-831e9308883b"}}]
15:51:51 INFO - 1498863111166 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"574b56d9-6d93-4632-864c-831e9308883b"}]
15:51:51 INFO - 1498863111411 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:51:51 INFO - 1498863111412 Marionette TRACE 388 <- [1,4,null,{}]
15:51:51 INFO - 1498863111414 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}]
15:51:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498863111418 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111419 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:51:51 INFO - 1498863111420 Marionette TRACE 388 <- [1,6,null,{}]
15:51:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 381ms
15:51:51 INFO - TEST-START | test_click.py TestClick.test_input_file
15:51:51 INFO - 1498863111423 Marionette DEBUG Closed connection 388
15:51:51 INFO - 1498863111424 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49772
15:51:51 INFO - 1498863111425 Marionette DEBUG Closed connection 389
15:51:51 INFO - 1498863111427 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49773
15:51:51 INFO - 1498863111478 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111478 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111481 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111485 Marionette TRACE 390 <- [1,1,null,{"sessionId":"22585e90-5070-4529-b60f-a0352c972646","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111495 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:51:51 INFO - 1498863111496 Marionette TRACE 390 <- [1,2,null,{}]
15:51:51 INFO - 1498863111497 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:51 INFO - 1498863111498 Marionette TRACE 390 <- [1,3,null,{}]
15:51:51 INFO - 1498863111499 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:51 INFO - 1498863111500 Marionette TRACE 390 <- [1,4,null,{}]
15:51:51 INFO - 1498863111501 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}]
15:51:51 INFO - TEST-START: test_click.py TestClick.test_input_file1498863111505 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111506 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:51:51 INFO - 1498863111507 Marionette TRACE 390 <- [1,6,null,{}]
15:51:51 INFO - 1498863111509 Marionette DEBUG Closed connection 390
15:51:51 INFO - 1498863111510 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49774
15:51:51 INFO - 1498863111511 Marionette DEBUG Closed connection 391
15:51:51 INFO - 1498863111512 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49775
15:51:51 INFO - 1498863111513 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111513 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111516 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111520 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ab3f26b3-3f7d-4f6f-b0da-6ffe585c6eba","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111521 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:51:51 INFO - 1498863111522 Marionette TRACE 392 <- [1,2,null,{}]
15:51:51 INFO - 1498863111524 Marionette DEBUG Closed connection 392
15:51:51 INFO - 1498863111525 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49776
15:51:51 INFO - 1498863111526 Marionette DEBUG Closed connection 393
15:51:51 INFO - 1498863111527 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49777
15:51:51 INFO - 1498863111528 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:51 INFO - 1498863111529 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111532 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111535 Marionette TRACE 394 <- [1,1,null,{"sessionId":"51245cc1-de4e-489d-b9a1-0ef6da521c10","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111537 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:51:51 INFO - 1498863111538 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"
15:51:51 INFO - 1498863111541 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"
15:51:51 INFO - 1498863111542 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"
15:51:51 INFO - 1498863111565 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:51:51 INFO - 1498863111567 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:51:51 INFO - 1498863111571 Marionette TRACE 394 <- [1,2,null,{}]
15:51:51 INFO - 1498863111581 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:51:51 INFO - 1498863111584 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e48e5e48-f626-4fc4-9beb-899d4468b219","ELEMENT":"e48e5e48-f626-4fc4-9beb-899d4468b219"}}]
15:51:51 INFO - 1498863111585 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"e48e5e48-f626-4fc4-9beb-899d4468b219"}]
15:51:51 INFO - 1498863111613 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
15:51:51 INFO - 1498863111615 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}]
15:51:51 INFO - TEST-END: test_click.py TestClick.test_input_file1498863111619 Marionette TRACE 394 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111620 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
15:51:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 201ms
15:51:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:51:51 INFO - 1498863111622 Marionette TRACE 394 <- [1,6,null,{}]
15:51:51 INFO - 1498863111625 Marionette DEBUG Closed connection 394
15:51:51 INFO - 1498863111625 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49778
15:51:51 INFO - 1498863111626 Marionette DEBUG Closed connection 395
15:51:51 INFO - 1498863111627 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49779
15:51:51 INFO - 1498863111628 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111629 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111632 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111636 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8150a363-42b6-4eab-b46e-a49f36413b21","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111637 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:51:51 INFO - 1498863111637 Marionette TRACE 396 <- [1,2,null,{}]
15:51:51 INFO - 1498863111638 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:51 INFO - 1498863111639 Marionette TRACE 396 <- [1,3,null,{}]
15:51:51 INFO - 1498863111640 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:51 INFO - 1498863111640 Marionette TRACE 396 <- [1,4,null,{}]
15:51:51 INFO - 1498863111642 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}]
15:51:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498863111645 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:51:51 INFO - 1498863111646 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:51:51 INFO - 1498863111648 Marionette TRACE 396 <- [1,6,null,{}]
15:51:51 INFO - 1498863111650 Marionette DEBUG Closed connection 396
15:51:51 INFO - 1498863111651 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49780
15:51:51 INFO - 1498863111652 Marionette DEBUG Closed connection 397
15:51:51 INFO - 1498863111653 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49781
15:51:51 INFO - 1498863111654 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:51 INFO - 1498863111654 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111657 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111661 Marionette TRACE 398 <- [1,1,null,{"sessionId":"37d1f483-90db-4de2-8d2e-8f189bd15c7f","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111663 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:51:51 INFO - 1498863111664 Marionette TRACE 398 <- [1,2,null,{}]
15:51:51 INFO - 1498863111666 Marionette DEBUG Closed connection 398
15:51:51 INFO - 1498863111667 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49782
15:51:51 INFO - 1498863111668 Marionette DEBUG Closed connection 399
15:51:51 INFO - 1498863111669 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49783
15:51:51 INFO - 1498863111670 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:51 INFO - 1498863111671 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:51 INFO - 1498863111674 Marionette DEBUG Register listener.js for window 7
15:51:51 INFO - 1498863111685 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a7bd6f62-c895-4c30-8d0f-b855a8b1fbd9","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:51 INFO - 1498863111688 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"}]
15:51:51 INFO - 1498863111690 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:51:51 INFO - 1498863111694 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:51:51 INFO - 1498863111694 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:51:51 INFO - 1498863111720 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"
15:51:51 INFO - 1498863111723 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"
15:51:51 INFO - 1498863111734 Marionette TRACE 400 <- [1,2,null,{}]
15:51:51 INFO - 1498863111736 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:51:51 INFO - 1498863111739 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"639ba580-2cc0-4f88-9668-1dcdfed19eaf","ELEMENT":"639ba580-2cc0-4f88-9668-1dcdfed19eaf"}}]
15:51:51 INFO - 1498863111740 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:51:51 INFO - 1498863111743 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"917457f9-5c09-4cf9-9684-97b0c6d72195","ELEMENT":"917457f9-5c09-4cf9-9684-97b0c6d72195"}}]
15:51:51 INFO - 1498863111744 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"639ba580-2cc0-4f88-9668-1dcdfed19eaf"}]
15:51:52 INFO - 1498863111992 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:51:52 INFO - 1498863111993 Marionette TRACE 400 <- [1,5,null,{}]
15:51:52 INFO - 1498863111994 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"917457f9-5c09-4cf9-9684-97b0c6d72195"}]
15:51:52 INFO - 1498863112012 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:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
15:51:52 INFO - 1498863112014 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:51:52 INFO - 1498863112017 Marionette TRACE 400 <- [1,7,null,{"value":false}]
15:51:52 INFO - 1498863112019 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}]
15:51:52 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498863112022 Marionette TRACE 400 <- [1,8,null,{"value":null}]
15:51:52 INFO - 1498863112023 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
15:51:52 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 401ms
15:51:52 INFO - 1498863112024 Marionette TRACE 400 <- [1,9,null,{}]
15:51:52 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:51:52 INFO - 1498863112026 Marionette DEBUG Closed connection 400
15:51:52 INFO - 1498863112028 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49784
15:51:52 INFO - 1498863112029 Marionette DEBUG Closed connection 401
15:51:52 INFO - 1498863112029 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49785
15:51:52 INFO - 1498863112030 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:52 INFO - 1498863112031 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:52 INFO - 1498863112034 Marionette DEBUG Register listener.js for window 7
15:51:52 INFO - 1498863112038 Marionette TRACE 402 <- [1,1,null,{"sessionId":"e1d16893-aaea-4b81-9e38-65eb9634b9df","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:52 INFO - 1498863112039 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
15:51:52 INFO - 1498863112040 Marionette TRACE 402 <- [1,2,null,{}]
15:51:52 INFO - 1498863112041 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:51:52 INFO - 1498863112041 Marionette TRACE 402 <- [1,3,null,{}]
15:51:52 INFO - 1498863112042 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
15:51:52 INFO - 1498863112043 Marionette TRACE 402 <- [1,4,null,{}]
15:51:52 INFO - 1498863112044 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}]
15:51:52 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498863112047 Marionette TRACE 402 <- [1,5,null,{"value":null}]
15:51:52 INFO - 1498863112048 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
15:51:52 INFO - 1498863112049 Marionette TRACE 402 <- [1,6,null,{}]
15:51:52 INFO - 1498863112052 Marionette DEBUG Closed connection 402
15:51:52 INFO - 1498863112053 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49786
15:51:52 INFO - 1498863112053 Marionette DEBUG Closed connection 403
15:51:52 INFO - 1498863112054 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49787
15:51:52 INFO - 1498863112056 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:51:52 INFO - 1498863112056 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:52 INFO - 1498863112059 Marionette DEBUG Register listener.js for window 7
15:51:52 INFO - 1498863112062 Marionette TRACE 404 <- [1,1,null,{"sessionId":"c0f9d061-9fc5-4328-b846-9728b5aa4b94","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:52 INFO - 1498863112063 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
15:51:52 INFO - 1498863112065 Marionette TRACE 404 <- [1,2,null,{}]
15:51:52 INFO - 1498863112067 Marionette DEBUG Closed connection 404
15:51:52 INFO - 1498863112068 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49788
15:51:52 INFO - 1498863112069 Marionette DEBUG Closed connection 405
15:51:52 INFO - 1498863112070 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49789
15:51:52 INFO - 1498863112071 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:51:52 INFO - 1498863112071 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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}
15:51:52 INFO - 1498863112074 Marionette DEBUG Register listener.js for window 7
15:51:52 INFO - 1498863112077 Marionette TRACE 406 <- [1,1,null,{"sessionId":"c240f679-c02e-42ec-93d5-b4f6a1414b0b","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":4092,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptkrnfq.mozrunner","moz:accessibilityChecks":false}}]
15:51:52 INFO - 1498863112080 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"}]
15:51:52 INFO - 1498863112081 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"
15:51:52 INFO - 1498863112085 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"
15:51:52 INFO - 1498863112085 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"
15:51:52 INFO - 1498863112108 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"
15:51:52 INFO - 1498863112111 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"
15:51:52 INFO - 1498863112115 Marionette TRACE 406 <- [1,2,null,{}]
15:51:52 INFO - 1498863112116 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:51:52 INFO - 1498863112119 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2913af90-78b6-4f8f-a42c-eccb0f75b8f6","ELEMENT":"2913af90-78b6-4f8f-a42c-eccb0f75b8f6"}}]
15:51:52 INFO - 1498863112120 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2913af90-78b6-4f8f-a42c-eccb0f75b8f6"}]
15:51:52 INFO - 1498863112151 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
15:51:52 INFO - 1498863112153 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"2913af90-78b6-4f8f-a42c-eccb0f75b8f6"}]
15:51:52 INFO - 1498863112176 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element