could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:55:04 INFO - 1499280904852 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
11:55:04 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499280904855 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:55:04 INFO - 1499280904856 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:55:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 158ms
11:55:04 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:55:04 INFO - 1499280904858 Marionette TRACE 352 <- [1,6,null,{}]
11:55:04 INFO - 1499280904860 Marionette DEBUG Closed connection 352
11:55:04 INFO - 1499280904861 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49812
11:55:04 INFO - 1499280904862 Marionette DEBUG Closed connection 353
11:55:04 INFO - 1499280904863 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49813
11:55:04 INFO - 1499280904864 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:04 INFO - 1499280904864 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:04 INFO - 1499280904867 Marionette DEBUG Register listener.js for window 12
11:55:04 INFO - 1499280904870 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9ce2a2a9-0cce-4261-a417-d7962920fa5a","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:04 INFO - 1499280904871 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:55:04 INFO - 1499280904871 Marionette TRACE 354 <- [1,2,null,{}]
11:55:04 INFO - 1499280904872 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:04 INFO - 1499280904872 Marionette TRACE 354 <- [1,3,null,{}]
11:55:04 INFO - 1499280904873 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:04 INFO - 1499280904874 Marionette TRACE 354 <- [1,4,null,{}]
11:55:04 INFO - 1499280904875 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
11:55:04 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499280904878 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:55:04 INFO - 1499280904879 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:55:04 INFO - 1499280904880 Marionette TRACE 354 <- [1,6,null,{}]
11:55:04 INFO - 1499280904881 Marionette DEBUG Closed connection 354
11:55:04 INFO - 1499280904882 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49814
11:55:04 INFO - 1499280904883 Marionette DEBUG Closed connection 355
11:55:04 INFO - 1499280904884 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49815
11:55:04 INFO - 1499280904885 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:04 INFO - 1499280904885 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:04 INFO - 1499280904887 Marionette DEBUG Register listener.js for window 12
11:55:04 INFO - 1499280904897 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a118b9ea-cd92-49cf-9a4e-928adbcda674","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:04 INFO - 1499280904898 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:55:04 INFO - 1499280904899 Marionette TRACE 356 <- [1,2,null,{}]
11:55:04 INFO - 1499280904901 Marionette DEBUG Closed connection 356
11:55:04 INFO - 1499280904902 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49816
11:55:04 INFO - 1499280904902 Marionette DEBUG Closed connection 357
11:55:04 INFO - 1499280904903 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49817
11:55:04 INFO - 1499280904904 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:04 INFO - 1499280904905 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:04 INFO - 1499280904907 Marionette DEBUG Register listener.js for window 12
11:55:04 INFO - 1499280904910 Marionette TRACE 358 <- [1,1,null,{"sessionId":"506c63ce-7558-40fd-9bdb-2c3bd329c555","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:04 INFO - 1499280904912 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49384/clicks.html"}]
11:55:04 INFO - 1499280904914 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49384/hidden.html"
11:55:04 INFO - 1499280904919 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49384/hidden.html"
11:55:04 INFO - 1499280904919 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49384/hidden.html"
11:55:04 INFO - 1499280904935 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49384/clicks.html"
11:55:04 INFO - 1499280904941 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49384/clicks.html"
11:55:04 INFO - 1499280904951 Marionette TRACE 358 <- [1,2,null,{}]
11:55:04 INFO - 1499280904952 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:55:04 INFO - 1499280904954 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70490d53-a1c9-40d3-9ff1-9597deb1c2e6","ELEMENT":"70490d53-a1c9-40d3-9ff1-9597deb1c2e6"}}]
11:55:04 INFO - 1499280904955 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"70490d53-a1c9-40d3-9ff1-9597deb1c2e6"}]
11:55:05 INFO - 1499280905006 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49384/clicks.html"
11:55:05 INFO - 1499280905013 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49384/clicks.html"
11:55:05 INFO - 1499280905014 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49384/clicks.html"
11:55:05 INFO - 1499280905030 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49384/test.html"
11:55:05 INFO - 1499280905031 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49384/test.html"
11:55:05 INFO - 1499280905034 Marionette TRACE 358 <- [1,4,null,{}]
11:55:05 INFO - 1499280905035 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:55:05 INFO - 1499280905038 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"186a2199-5fbd-44b3-9bc2-d133a776ffea","ELEMENT":"186a2199-5fbd-44b3-9bc2-d133a776ffea"}}]
11:55:05 INFO - 1499280905045 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:55:05 INFO - 1499280905046 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:55:05 INFO - 1499280905062 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
11:55:05 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499280905065 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:55:05 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
11:55:05 INFO - TEST-START | test_click.py TestClick.test_container_element
11:55:05 INFO - 1499280905069 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:55:05 INFO - 1499280905070 Marionette TRACE 358 <- [1,8,null,{}]
11:55:05 INFO - 1499280905072 Marionette DEBUG Closed connection 358
11:55:05 INFO - 1499280905073 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49820
11:55:05 INFO - 1499280905081 Marionette DEBUG Closed connection 359
11:55:05 INFO - 1499280905082 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49821
11:55:05 INFO - 1499280905083 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905084 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905086 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905089 Marionette TRACE 360 <- [1,1,null,{"sessionId":"84d46aad-953e-4f36-9409-a546fd36541e","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905091 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:55:05 INFO - 1499280905091 Marionette TRACE 360 <- [1,2,null,{}]
11:55:05 INFO - 1499280905092 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:05 INFO - 1499280905092 Marionette TRACE 360 <- [1,3,null,{}]
11:55:05 INFO - 1499280905093 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:05 INFO - 1499280905093 Marionette TRACE 360 <- [1,4,null,{}]
11:55:05 INFO - 1499280905095 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
11:55:05 INFO - TEST-START: test_click.py TestClick.test_container_element1499280905098 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:55:05 INFO - 1499280905099 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:55:05 INFO - 1499280905100 Marionette TRACE 360 <- [1,6,null,{}]
11:55:05 INFO - 1499280905102 Marionette DEBUG Closed connection 360
11:55:05 INFO - 1499280905102 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49822
11:55:05 INFO - 1499280905103 Marionette DEBUG Closed connection 361
11:55:05 INFO - 1499280905104 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49823
11:55:05 INFO - 1499280905105 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905105 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905107 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905110 Marionette TRACE 362 <- [1,1,null,{"sessionId":"017963c6-b955-4339-bd27-1cdb8fc0c451","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905111 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:55:05 INFO - 1499280905112 Marionette TRACE 362 <- [1,2,null,{}]
11:55:05 INFO - 1499280905114 Marionette DEBUG Closed connection 362
11:55:05 INFO - 1499280905114 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49824
11:55:05 INFO - 1499280905115 Marionette DEBUG Closed connection 363
11:55:05 INFO - 1499280905116 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49825
11:55:05 INFO - 1499280905117 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:05 INFO - 1499280905117 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905119 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905128 Marionette TRACE 364 <- [1,1,null,{"sessionId":"52ab5ddf-263a-42f0-8937-bb26d6fa6024","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905130 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:55:05 INFO - 1499280905131 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49384/test.html"
11:55:05 INFO - 1499280905133 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49384/test.html"
11:55:05 INFO - 1499280905134 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49384/test.html"
11:55:05 INFO - 1499280905151 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905155 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905158 Marionette TRACE 364 <- [1,2,null,{}]
11:55:05 INFO - 1499280905165 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:55:05 INFO - 1499280905169 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20d70215-b899-4faf-b733-af294f22c290","ELEMENT":"20d70215-b899-4faf-b733-af294f22c290"}}]
11:55:05 INFO - 1499280905170 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"20d70215-b899-4faf-b733-af294f22c290"}]
11:55:05 INFO - 1499280905409 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:55:05 INFO - 1499280905410 Marionette TRACE 364 <- [1,4,null,{}]
11:55:05 INFO - 1499280905411 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"20d70215-b899-4faf-b733-af294f22c290","name":"selected"}]
11:55:05 INFO - 1499280905415 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:55:05 INFO - 1499280905417 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
11:55:05 INFO - TEST-END: test_click.py TestClick.test_container_element1499280905419 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:55:05 INFO - 1499280905420 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:55:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
11:55:05 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:55:05 INFO - 1499280905421 Marionette TRACE 364 <- [1,7,null,{}]
11:55:05 INFO - 1499280905423 Marionette DEBUG Closed connection 364
11:55:05 INFO - 1499280905424 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49826
11:55:05 INFO - 1499280905425 Marionette DEBUG Closed connection 365
11:55:05 INFO - 1499280905426 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49827
11:55:05 INFO - 1499280905427 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905427 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905429 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905432 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d34fe8a8-fe8f-4b36-aad3-1f95968a442e","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905433 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:55:05 INFO - 1499280905433 Marionette TRACE 366 <- [1,2,null,{}]
11:55:05 INFO - 1499280905434 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:05 INFO - 1499280905434 Marionette TRACE 366 <- [1,3,null,{}]
11:55:05 INFO - 1499280905435 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:05 INFO - 1499280905436 Marionette TRACE 366 <- [1,4,null,{}]
11:55:05 INFO - 1499280905437 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
11:55:05 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499280905440 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:55:05 INFO - 1499280905441 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:55:05 INFO - 1499280905442 Marionette TRACE 366 <- [1,6,null,{}]
11:55:05 INFO - 1499280905444 Marionette DEBUG Closed connection 366
11:55:05 INFO - 1499280905444 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49828
11:55:05 INFO - 1499280905445 Marionette DEBUG Closed connection 367
11:55:05 INFO - 1499280905446 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49829
11:55:05 INFO - 1499280905447 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905448 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905449 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905452 Marionette TRACE 368 <- [1,1,null,{"sessionId":"aab3a3f6-4ed1-4b0c-9bba-9c8aec1fe5f7","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905453 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:55:05 INFO - 1499280905454 Marionette TRACE 368 <- [1,2,null,{}]
11:55:05 INFO - 1499280905457 Marionette DEBUG Closed connection 368
11:55:05 INFO - 1499280905457 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49830
11:55:05 INFO - 1499280905458 Marionette DEBUG Closed connection 369
11:55:05 INFO - 1499280905459 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49831
11:55:05 INFO - 1499280905460 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:05 INFO - 1499280905460 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905462 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905465 Marionette TRACE 370 <- [1,1,null,{"sessionId":"10dda28b-1680-4574-b15f-4a645f1a4efc","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905466 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:55:05 INFO - 1499280905467 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905471 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905471 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905497 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905502 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905507 Marionette TRACE 370 <- [1,2,null,{}]
11:55:05 INFO - 1499280905514 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:55:05 INFO - 1499280905516 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fec48466-9501-4a9f-87b6-8b954fa10ed0","ELEMENT":"fec48466-9501-4a9f-87b6-8b954fa10ed0"}}]
11:55:05 INFO - 1499280905517 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fec48466-9501-4a9f-87b6-8b954fa10ed0"}]
11:55:05 INFO - 1499280905756 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:55:05 INFO - 1499280905756 Marionette TRACE 370 <- [1,4,null,{}]
11:55:05 INFO - 1499280905757 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fec48466-9501-4a9f-87b6-8b954fa10ed0","name":"selected"}]
11:55:05 INFO - 1499280905762 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:55:05 INFO - 1499280905763 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
11:55:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499280905766 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:55:05 INFO - 1499280905766 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:55:05 INFO - 1499280905767 Marionette TRACE 370 <- [1,7,null,{}]
11:55:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 346ms
11:55:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:55:05 INFO - 1499280905769 Marionette DEBUG Closed connection 370
11:55:05 INFO - 1499280905770 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49832
11:55:05 INFO - 1499280905771 Marionette DEBUG Closed connection 371
11:55:05 INFO - 1499280905772 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49833
11:55:05 INFO - 1499280905773 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905773 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905776 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905778 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2a960b35-9474-4ab0-b1e2-7192efe68b9a","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905779 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:55:05 INFO - 1499280905780 Marionette TRACE 372 <- [1,2,null,{}]
11:55:05 INFO - 1499280905780 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:05 INFO - 1499280905781 Marionette TRACE 372 <- [1,3,null,{}]
11:55:05 INFO - 1499280905782 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:05 INFO - 1499280905782 Marionette TRACE 372 <- [1,4,null,{}]
11:55:05 INFO - 1499280905784 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
11:55:05 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499280905786 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:55:05 INFO - 1499280905787 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:55:05 INFO - 1499280905788 Marionette TRACE 372 <- [1,6,null,{}]
11:55:05 INFO - 1499280905790 Marionette DEBUG Closed connection 372
11:55:05 INFO - 1499280905790 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49834
11:55:05 INFO - 1499280905791 Marionette DEBUG Closed connection 373
11:55:05 INFO - 1499280905792 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49835
11:55:05 INFO - 1499280905793 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:05 INFO - 1499280905793 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905795 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905798 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6e9277b1-f774-4760-9d28-201a7207424b","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905799 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:55:05 INFO - 1499280905800 Marionette TRACE 374 <- [1,2,null,{}]
11:55:05 INFO - 1499280905802 Marionette DEBUG Closed connection 374
11:55:05 INFO - 1499280905802 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49836
11:55:05 INFO - 1499280905803 Marionette DEBUG Closed connection 375
11:55:05 INFO - 1499280905804 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49837
11:55:05 INFO - 1499280905805 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:05 INFO - 1499280905805 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:05 INFO - 1499280905807 Marionette DEBUG Register listener.js for window 12
11:55:05 INFO - 1499280905810 Marionette TRACE 376 <- [1,1,null,{"sessionId":"413231f7-baca-47c4-99da-b7b3d907eb93","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:05 INFO - 1499280905811 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
11:55:05 INFO - 1499280905812 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905814 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905815 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:05 INFO - 1499280905833 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
11:55:05 INFO - 1499280905837 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
11:55:05 INFO - 1499280905839 Marionette TRACE 376 <- [1,2,null,{}]
11:55:05 INFO - 1499280905847 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:55:05 INFO - 1499280905849 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"011932bd-3be1-41ce-a955-4aea5324d186","ELEMENT":"011932bd-3be1-41ce-a955-4aea5324d186"}}]
11:55:05 INFO - 1499280905851 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"011932bd-3be1-41ce-a955-4aea5324d186"}]
11:55:06 INFO - 1499280906093 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:55:06 INFO - 1499280906094 Marionette TRACE 376 <- [1,4,null,{}]
11:55:06 INFO - 1499280906096 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
11:55:06 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499280906099 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906100 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906101 Marionette TRACE 376 <- [1,6,null,{}]
11:55:06 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 334ms
11:55:06 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:55:06 INFO - 1499280906103 Marionette DEBUG Closed connection 376
11:55:06 INFO - 1499280906104 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49838
11:55:06 INFO - 1499280906105 Marionette DEBUG Closed connection 377
11:55:06 INFO - 1499280906105 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49839
11:55:06 INFO - 1499280906106 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906107 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906109 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906112 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4d555ed7-6a89-4e6d-bc58-ad670fda877c","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906113 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:55:06 INFO - 1499280906113 Marionette TRACE 378 <- [1,2,null,{}]
11:55:06 INFO - 1499280906114 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:06 INFO - 1499280906114 Marionette TRACE 378 <- [1,3,null,{}]
11:55:06 INFO - 1499280906115 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:06 INFO - 1499280906115 Marionette TRACE 378 <- [1,4,null,{}]
11:55:06 INFO - 1499280906117 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
11:55:06 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499280906119 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906120 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906121 Marionette TRACE 378 <- [1,6,null,{}]
11:55:06 INFO - 1499280906123 Marionette DEBUG Closed connection 378
11:55:06 INFO - 1499280906123 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49840
11:55:06 INFO - 1499280906124 Marionette DEBUG Closed connection 379
11:55:06 INFO - 1499280906125 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49841
11:55:06 INFO - 1499280906126 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906126 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906128 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906131 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0e38bdea-5c64-4fd5-a36e-340b3a88e227","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906132 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:55:06 INFO - 1499280906133 Marionette TRACE 380 <- [1,2,null,{}]
11:55:06 INFO - 1499280906134 Marionette DEBUG Closed connection 380
11:55:06 INFO - 1499280906135 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49842
11:55:06 INFO - 1499280906136 Marionette DEBUG Closed connection 381
11:55:06 INFO - 1499280906136 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49843
11:55:06 INFO - 1499280906137 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:06 INFO - 1499280906138 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906139 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906142 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d23156a0-4f24-48c1-b3ae-87b3c7d44009","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906143 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
11:55:06 INFO - 1499280906145 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
11:55:06 INFO - 1499280906147 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
11:55:06 INFO - 1499280906147 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
11:55:06 INFO - 1499280906164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:06 INFO - 1499280906165 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:06 INFO - 1499280906168 Marionette TRACE 382 <- [1,2,null,{}]
11:55:06 INFO - 1499280906169 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:55:06 INFO - 1499280906177 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"825a603d-3b6d-45fd-8c86-1630d17577be","ELEMENT":"825a603d-3b6d-45fd-8c86-1630d17577be"}}]
11:55:06 INFO - 1499280906180 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"825a603d-3b6d-45fd-8c86-1630d17577be"}]
11:55:06 INFO - 1499280906419 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:55:06 INFO - 1499280906420 Marionette TRACE 382 <- [1,4,null,{}]
11:55:06 INFO - 1499280906422 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
11:55:06 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499280906425 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906425 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906427 Marionette TRACE 382 <- [1,6,null,{}]
11:55:06 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 325ms
11:55:06 INFO - TEST-START | test_click.py TestClick.test_input_file
11:55:06 INFO - 1499280906428 Marionette DEBUG Closed connection 382
11:55:06 INFO - 1499280906429 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49844
11:55:06 INFO - 1499280906430 Marionette DEBUG Closed connection 383
11:55:06 INFO - 1499280906431 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49845
11:55:06 INFO - 1499280906432 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906432 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906434 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906437 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5bbb9aa8-edf6-49f9-8e51-50f3466ad9c5","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906438 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:55:06 INFO - 1499280906439 Marionette TRACE 384 <- [1,2,null,{}]
11:55:06 INFO - 1499280906440 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:06 INFO - 1499280906440 Marionette TRACE 384 <- [1,3,null,{}]
11:55:06 INFO - 1499280906441 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:06 INFO - 1499280906441 Marionette TRACE 384 <- [1,4,null,{}]
11:55:06 INFO - 1499280906443 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
11:55:06 INFO - TEST-START: test_click.py TestClick.test_input_file1499280906446 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906446 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906447 Marionette TRACE 384 <- [1,6,null,{}]
11:55:06 INFO - 1499280906449 Marionette DEBUG Closed connection 384
11:55:06 INFO - 1499280906450 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49846
11:55:06 INFO - 1499280906451 Marionette DEBUG Closed connection 385
11:55:06 INFO - 1499280906451 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49847
11:55:06 INFO - 1499280906452 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906453 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906455 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906458 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67ce7ea4-d41b-4e08-b90c-23bdae450646","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906459 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:55:06 INFO - 1499280906460 Marionette TRACE 386 <- [1,2,null,{}]
11:55:06 INFO - 1499280906461 Marionette DEBUG Closed connection 386
11:55:06 INFO - 1499280906462 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49848
11:55:06 INFO - 1499280906463 Marionette DEBUG Closed connection 387
11:55:06 INFO - 1499280906463 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49849
11:55:06 INFO - 1499280906464 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:06 INFO - 1499280906465 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906467 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906469 Marionette TRACE 388 <- [1,1,null,{"sessionId":"aba1d37a-1646-4243-af4f-fc760fa2718e","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906470 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:55:06 INFO - 1499280906471 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:06 INFO - 1499280906473 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:06 INFO - 1499280906474 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
11:55:06 INFO - 1499280906483 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:55:06 INFO - 1499280906488 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:55:06 INFO - 1499280906490 Marionette TRACE 388 <- [1,2,null,{}]
11:55:06 INFO - 1499280906499 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:55:06 INFO - 1499280906502 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43660c02-7d30-4168-8bdc-6170551647f7","ELEMENT":"43660c02-7d30-4168-8bdc-6170551647f7"}}]
11:55:06 INFO - 1499280906503 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"43660c02-7d30-4168-8bdc-6170551647f7"}]
11:55:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
11:55:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:55:06 INFO - 1499280906533 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:55:06 INFO - 1499280906535 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
11:55:06 INFO - TEST-END: test_click.py TestClick.test_input_file1499280906537 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906538 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906539 Marionette TRACE 388 <- [1,6,null,{}]
11:55:06 INFO - 1499280906541 Marionette DEBUG Closed connection 388
11:55:06 INFO - 1499280906542 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49850
11:55:06 INFO - 1499280906543 Marionette DEBUG Closed connection 389
11:55:06 INFO - 1499280906543 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49851
11:55:06 INFO - 1499280906544 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906545 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906547 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906550 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b0731fd3-e527-4d90-a60b-da0eea58ce0d","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906551 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:55:06 INFO - 1499280906551 Marionette TRACE 390 <- [1,2,null,{}]
11:55:06 INFO - 1499280906552 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:06 INFO - 1499280906552 Marionette TRACE 390 <- [1,3,null,{}]
11:55:06 INFO - 1499280906553 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:06 INFO - 1499280906553 Marionette TRACE 390 <- [1,4,null,{}]
11:55:06 INFO - 1499280906555 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
11:55:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499280906557 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906558 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906559 Marionette TRACE 390 <- [1,6,null,{}]
11:55:06 INFO - 1499280906561 Marionette DEBUG Closed connection 390
11:55:06 INFO - 1499280906562 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49852
11:55:06 INFO - 1499280906562 Marionette DEBUG Closed connection 391
11:55:06 INFO - 1499280906563 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49853
11:55:06 INFO - 1499280906564 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906565 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906566 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906569 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fec100a5-612c-4df6-9c21-3ab007256ab9","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906570 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:55:06 INFO - 1499280906571 Marionette TRACE 392 <- [1,2,null,{}]
11:55:06 INFO - 1499280906573 Marionette DEBUG Closed connection 392
11:55:06 INFO - 1499280906573 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49854
11:55:06 INFO - 1499280906574 Marionette DEBUG Closed connection 393
11:55:06 INFO - 1499280906575 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49855
11:55:06 INFO - 1499280906576 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:06 INFO - 1499280906576 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906578 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906581 Marionette TRACE 394 <- [1,1,null,{"sessionId":"66e1db4b-e27b-4c3e-8bb5-2a56e400bfc9","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906582 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
11:55:06 INFO - 1499280906583 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:55:06 INFO - 1499280906585 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:55:06 INFO - 1499280906585 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:55:06 INFO - 1499280906603 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
11:55:06 INFO - 1499280906605 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
11:55:06 INFO - 1499280906614 Marionette TRACE 394 <- [1,2,null,{}]
11:55:06 INFO - 1499280906615 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:55:06 INFO - 1499280906617 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4bd40ca3-6f3b-4b32-a059-fb7467058109","ELEMENT":"4bd40ca3-6f3b-4b32-a059-fb7467058109"}}]
11:55:06 INFO - 1499280906618 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:55:06 INFO - 1499280906620 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"adcf6087-cf86-4d27-970e-a800eb6178eb","ELEMENT":"adcf6087-cf86-4d27-970e-a800eb6178eb"}}]
11:55:06 INFO - 1499280906621 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"4bd40ca3-6f3b-4b32-a059-fb7467058109"}]
11:55:06 INFO - 1499280906856 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:55:06 INFO - 1499280906857 Marionette TRACE 394 <- [1,5,null,{}]
11:55:06 INFO - 1499280906858 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"adcf6087-cf86-4d27-970e-a800eb6178eb"}]
11:55:06 INFO - 1499280906871 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
11:55:06 INFO - 1499280906873 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:55:06 INFO - 1499280906876 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:55:06 INFO - 1499280906877 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
11:55:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499280906880 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:55:06 INFO - 1499280906880 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:55:06 INFO - 1499280906882 Marionette TRACE 394 <- [1,9,null,{}]
11:55:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 342ms
11:55:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:55:06 INFO - 1499280906883 Marionette DEBUG Closed connection 394
11:55:06 INFO - 1499280906884 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49856
11:55:06 INFO - 1499280906885 Marionette DEBUG Closed connection 395
11:55:06 INFO - 1499280906886 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49857
11:55:06 INFO - 1499280906887 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906887 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906889 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906892 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bb9c54a1-f1a2-4a2a-b2db-139004d68658","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906893 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:55:06 INFO - 1499280906894 Marionette TRACE 396 <- [1,2,null,{}]
11:55:06 INFO - 1499280906894 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:55:06 INFO - 1499280906895 Marionette TRACE 396 <- [1,3,null,{}]
11:55:06 INFO - 1499280906896 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:55:06 INFO - 1499280906896 Marionette TRACE 396 <- [1,4,null,{}]
11:55:06 INFO - 1499280906898 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
11:55:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499280906900 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:55:06 INFO - 1499280906901 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:55:06 INFO - 1499280906902 Marionette TRACE 396 <- [1,6,null,{}]
11:55:06 INFO - 1499280906904 Marionette DEBUG Closed connection 396
11:55:06 INFO - 1499280906904 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49858
11:55:06 INFO - 1499280906905 Marionette DEBUG Closed connection 397
11:55:06 INFO - 1499280906906 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49859
11:55:06 INFO - 1499280906907 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:55:06 INFO - 1499280906907 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906909 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906912 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4c543051-61c6-4da9-a8a6-730598383dc7","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906913 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:55:06 INFO - 1499280906914 Marionette TRACE 398 <- [1,2,null,{}]
11:55:06 INFO - 1499280906916 Marionette DEBUG Closed connection 398
11:55:06 INFO - 1499280906916 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49860
11:55:06 INFO - 1499280906917 Marionette DEBUG Closed connection 399
11:55:06 INFO - 1499280906918 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49861
11:55:06 INFO - 1499280906918 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:55:06 INFO - 1499280906919 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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}
11:55:06 INFO - 1499280906921 Marionette DEBUG Register listener.js for window 12
11:55:06 INFO - 1499280906924 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6431b121-f9b6-419f-b9e9-306b86f47bd0","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":644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp_vrvfj.mozrunner","moz:accessibilityChecks":false}}]
11:55:06 INFO - 1499280906925 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
11:55:06 INFO - 1499280906926 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
11:55:06 INFO - 1499280906928 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
11:55:06 INFO - 1499280906928 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
11:55:06 INFO - 1499280906939 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
11:55:06 INFO - 1499280906942 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
11:55:06 INFO - 1499280906951 Marionette TRACE 400 <- [1,2,null,{}]
11:55:06 INFO - 1499280906952 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:55:06 INFO - 1499280906955 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4864b0e7-6b20-4da7-9834-0da8ecbcefaf","ELEMENT":"4864b0e7-6b20-4da7-9834-0da8ecbcefaf"}}]
11:55:06 INFO - 1499280906957 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4864b0e7-6b20-4da7-9834-0da8ecbcefaf"}]
11:55:06 INFO - 1499280906985 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:55:06 INFO - 1499280906986 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"4864b0e7-6b20-4da7-9834-0da8ecbcefaf"}]
11:55:07 INFO - 1499280907000 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element