could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:27:29 INFO - 1499689649141 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}]
05:27:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499689649144 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:27:29 INFO - 1499689649145 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:27:29 INFO - 1499689649146 Marionette TRACE 352 <- [1,6,null,{}]
05:27:29 INFO - 1499689649147 Marionette DEBUG Closed connection 352
05:27:29 INFO - 1499689649149 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49767
05:27:29 INFO - 1499689649149 Marionette DEBUG Closed connection 353
05:27:29 INFO - 1499689649150 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49768
05:27:29 INFO - 1499689649151 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649152 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649154 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649156 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ba253761-7287-4c20-8328-1307e8706492","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649158 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:27:29 INFO - 1499689649159 Marionette TRACE 354 <- [1,2,null,{}]
05:27:29 INFO - 1499689649160 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:29 INFO - 1499689649160 Marionette TRACE 354 <- [1,3,null,{}]
05:27:29 INFO - 1499689649161 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:29 INFO - 1499689649161 Marionette TRACE 354 <- [1,4,null,{}]
05:27:29 INFO - 1499689649163 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}]
05:27:29 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499689649165 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:27:29 INFO - 1499689649166 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:27:29 INFO - 1499689649167 Marionette TRACE 354 <- [1,6,null,{}]
05:27:29 INFO - 1499689649169 Marionette DEBUG Closed connection 354
05:27:29 INFO - 1499689649169 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49769
05:27:29 INFO - 1499689649170 Marionette DEBUG Closed connection 355
05:27:29 INFO - 1499689649171 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49770
05:27:29 INFO - 1499689649172 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649172 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649174 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649176 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ae8e5dfe-e1c8-4f37-bd65-97b779041817","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649178 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:27:29 INFO - 1499689649179 Marionette TRACE 356 <- [1,2,null,{}]
05:27:29 INFO - 1499689649181 Marionette DEBUG Closed connection 356
05:27:29 INFO - 1499689649182 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49771
05:27:29 INFO - 1499689649182 Marionette DEBUG Closed connection 357
05:27:29 INFO - 1499689649183 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49772
05:27:29 INFO - 1499689649184 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:29 INFO - 1499689649184 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649186 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649189 Marionette TRACE 358 <- [1,1,null,{"sessionId":"81fee300-662f-49ed-8a01-fda68540c578","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649191 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49340/clicks.html"}]
05:27:29 INFO - 1499689649193 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/hidden.html"
05:27:29 INFO - 1499689649198 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/hidden.html"
05:27:29 INFO - 1499689649198 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/hidden.html"
05:27:29 INFO - 1499689649213 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/clicks.html"
05:27:29 INFO - 1499689649218 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/clicks.html"
05:27:29 INFO - 1499689649222 Marionette TRACE 358 <- [1,2,null,{}]
05:27:29 INFO - 1499689649229 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:27:29 INFO - 1499689649232 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7acde39-3a6e-4bfc-acee-e6c208e404ae","ELEMENT":"c7acde39-3a6e-4bfc-acee-e6c208e404ae"}}]
05:27:29 INFO - 1499689649233 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c7acde39-3a6e-4bfc-acee-e6c208e404ae"}]
05:27:29 INFO - 1499689649271 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/clicks.html"
05:27:29 INFO - 1499689649278 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/clicks.html"
05:27:29 INFO - 1499689649279 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/clicks.html"
05:27:29 INFO - 1499689649295 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/test.html"
05:27:29 INFO - 1499689649297 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/test.html"
05:27:29 INFO - 1499689649299 Marionette TRACE 358 <- [1,4,null,{}]
05:27:29 INFO - 1499689649300 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:27:29 INFO - 1499689649302 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dbb88447-ff95-482c-bad5-101b3c252566","ELEMENT":"dbb88447-ff95-482c-bad5-101b3c252566"}}]
05:27:29 INFO - 1499689649303 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:27:29 INFO - 1499689649304 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:27:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
05:27:29 INFO - TEST-START | test_click.py TestClick.test_container_element
05:27:29 INFO - 1499689649313 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}]
05:27:29 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499689649316 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:27:29 INFO - 1499689649317 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:27:29 INFO - 1499689649318 Marionette TRACE 358 <- [1,8,null,{}]
05:27:29 INFO - 1499689649320 Marionette DEBUG Closed connection 358
05:27:29 INFO - 1499689649322 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49775
05:27:29 INFO - 1499689649322 Marionette DEBUG Closed connection 359
05:27:29 INFO - 1499689649323 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49776
05:27:29 INFO - 1499689649324 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649326 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649328 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649331 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a8f23ffb-3c05-49da-bd9e-d8b44b5f80ba","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649332 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:27:29 INFO - 1499689649333 Marionette TRACE 360 <- [1,2,null,{}]
05:27:29 INFO - 1499689649334 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:29 INFO - 1499689649334 Marionette TRACE 360 <- [1,3,null,{}]
05:27:29 INFO - 1499689649335 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:29 INFO - 1499689649335 Marionette TRACE 360 <- [1,4,null,{}]
05:27:29 INFO - 1499689649337 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}]
05:27:29 INFO - TEST-START: test_click.py TestClick.test_container_element1499689649339 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:27:29 INFO - 1499689649341 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:27:29 INFO - 1499689649342 Marionette TRACE 360 <- [1,6,null,{}]
05:27:29 INFO - 1499689649344 Marionette DEBUG Closed connection 360
05:27:29 INFO - 1499689649344 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49777
05:27:29 INFO - 1499689649345 Marionette DEBUG Closed connection 361
05:27:29 INFO - 1499689649346 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49778
05:27:29 INFO - 1499689649347 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649347 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649349 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649358 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5bef39ac-a18c-434f-bba2-3af91d86b28b","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649360 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:27:29 INFO - 1499689649361 Marionette TRACE 362 <- [1,2,null,{}]
05:27:29 INFO - 1499689649363 Marionette DEBUG Closed connection 362
05:27:29 INFO - 1499689649364 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49779
05:27:29 INFO - 1499689649364 Marionette DEBUG Closed connection 363
05:27:29 INFO - 1499689649365 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49780
05:27:29 INFO - 1499689649366 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:29 INFO - 1499689649366 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649368 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649371 Marionette TRACE 364 <- [1,1,null,{"sessionId":"b7a6997b-a789-473f-aece-e2b6da6c4cfc","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649373 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"}]
05:27:29 INFO - 1499689649374 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/test.html"
05:27:29 INFO - 1499689649376 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/test.html"
05:27:29 INFO - 1499689649376 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/test.html"
05:27:29 INFO - 1499689649387 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"
05:27:29 INFO - 1499689649397 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"
05:27:29 INFO - 1499689649400 Marionette TRACE 364 <- [1,2,null,{}]
05:27:29 INFO - 1499689649401 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:27:29 INFO - 1499689649404 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c0a53ed-5a2a-4a1c-a8c7-f5c547b539dc","ELEMENT":"7c0a53ed-5a2a-4a1c-a8c7-f5c547b539dc"}}]
05:27:29 INFO - 1499689649412 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7c0a53ed-5a2a-4a1c-a8c7-f5c547b539dc"}]
05:27:29 INFO - 1499689649649 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:27:29 INFO - 1499689649649 Marionette TRACE 364 <- [1,4,null,{}]
05:27:29 INFO - 1499689649650 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7c0a53ed-5a2a-4a1c-a8c7-f5c547b539dc","name":"selected"}]
05:27:29 INFO - 1499689649654 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:27:29 INFO - 1499689649656 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}]
05:27:29 INFO - TEST-END: test_click.py TestClick.test_container_element1499689649659 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:27:29 INFO - 1499689649659 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:27:29 INFO - 1499689649661 Marionette TRACE 364 <- [1,7,null,{}]
05:27:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 342ms
05:27:29 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:27:29 INFO - 1499689649662 Marionette DEBUG Closed connection 364
05:27:29 INFO - 1499689649663 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49781
05:27:29 INFO - 1499689649664 Marionette DEBUG Closed connection 365
05:27:29 INFO - 1499689649665 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49782
05:27:29 INFO - 1499689649666 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649666 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649668 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649671 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9150fce0-8319-42e7-889b-b108b8db73d3","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649673 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:27:29 INFO - 1499689649673 Marionette TRACE 366 <- [1,2,null,{}]
05:27:29 INFO - 1499689649674 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:29 INFO - 1499689649675 Marionette TRACE 366 <- [1,3,null,{}]
05:27:29 INFO - 1499689649676 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:29 INFO - 1499689649676 Marionette TRACE 366 <- [1,4,null,{}]
05:27:29 INFO - 1499689649678 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}]
05:27:29 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499689649680 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:27:29 INFO - 1499689649681 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:27:29 INFO - 1499689649682 Marionette TRACE 366 <- [1,6,null,{}]
05:27:29 INFO - 1499689649683 Marionette DEBUG Closed connection 366
05:27:29 INFO - 1499689649684 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49783
05:27:29 INFO - 1499689649685 Marionette DEBUG Closed connection 367
05:27:29 INFO - 1499689649685 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49784
05:27:29 INFO - 1499689649686 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:29 INFO - 1499689649687 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649689 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649692 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b081c3e5-9e8a-4517-b690-150c358f4f0f","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649694 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:27:29 INFO - 1499689649695 Marionette TRACE 368 <- [1,2,null,{}]
05:27:29 INFO - 1499689649697 Marionette DEBUG Closed connection 368
05:27:29 INFO - 1499689649697 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49785
05:27:29 INFO - 1499689649698 Marionette DEBUG Closed connection 369
05:27:29 INFO - 1499689649699 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49786
05:27:29 INFO - 1499689649700 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:29 INFO - 1499689649700 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:29 INFO - 1499689649702 Marionette DEBUG Register listener.js for window 12
05:27:29 INFO - 1499689649704 Marionette TRACE 370 <- [1,1,null,{"sessionId":"56052042-2e2e-4fdd-b466-c69b83095160","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:29 INFO - 1499689649707 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"}]
05:27:29 INFO - 1499689649708 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"
05:27:29 INFO - 1499689649710 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"
05:27:29 INFO - 1499689649711 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"
05:27:29 INFO - 1499689649727 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"
05:27:29 INFO - 1499689649729 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"
05:27:29 INFO - 1499689649732 Marionette TRACE 370 <- [1,2,null,{}]
05:27:29 INFO - 1499689649733 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:27:29 INFO - 1499689649737 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82940b39-fe48-4786-aeed-17e37118ef7e","ELEMENT":"82940b39-fe48-4786-aeed-17e37118ef7e"}}]
05:27:29 INFO - 1499689649742 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"82940b39-fe48-4786-aeed-17e37118ef7e"}]
05:27:29 INFO - 1499689649981 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:27:29 INFO - 1499689649981 Marionette TRACE 370 <- [1,4,null,{}]
05:27:29 INFO - 1499689649982 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"82940b39-fe48-4786-aeed-17e37118ef7e","name":"selected"}]
05:27:29 INFO - 1499689649986 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:27:29 INFO - 1499689649988 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}]
05:27:29 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499689649990 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:27:29 INFO - 1499689649991 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:27:29 INFO - 1499689649992 Marionette TRACE 370 <- [1,7,null,{}]
05:27:29 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 331ms
05:27:29 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:27:29 INFO - 1499689649994 Marionette DEBUG Closed connection 370
05:27:29 INFO - 1499689649995 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49787
05:27:29 INFO - 1499689649996 Marionette DEBUG Closed connection 371
05:27:29 INFO - 1499689649997 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49788
05:27:30 INFO - 1499689649997 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689649998 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650000 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650002 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c7b5ee52-5c80-4797-a0c2-6ed039600f63","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650004 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:27:30 INFO - 1499689650005 Marionette TRACE 372 <- [1,2,null,{}]
05:27:30 INFO - 1499689650006 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:30 INFO - 1499689650006 Marionette TRACE 372 <- [1,3,null,{}]
05:27:30 INFO - 1499689650007 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:30 INFO - 1499689650007 Marionette TRACE 372 <- [1,4,null,{}]
05:27:30 INFO - 1499689650009 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}]
05:27:30 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499689650011 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650012 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650014 Marionette TRACE 372 <- [1,6,null,{}]
05:27:30 INFO - 1499689650016 Marionette DEBUG Closed connection 372
05:27:30 INFO - 1499689650016 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49789
05:27:30 INFO - 1499689650017 Marionette DEBUG Closed connection 373
05:27:30 INFO - 1499689650018 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49790
05:27:30 INFO - 1499689650019 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650019 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650021 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650024 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d9429793-8d9c-472f-a19f-aba304585c06","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650025 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:27:30 INFO - 1499689650026 Marionette TRACE 374 <- [1,2,null,{}]
05:27:30 INFO - 1499689650028 Marionette DEBUG Closed connection 374
05:27:30 INFO - 1499689650029 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49791
05:27:30 INFO - 1499689650029 Marionette DEBUG Closed connection 375
05:27:30 INFO - 1499689650030 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49792
05:27:30 INFO - 1499689650031 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:30 INFO - 1499689650038 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650040 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650042 Marionette TRACE 376 <- [1,1,null,{"sessionId":"837f3efc-c63a-4a65-9fc1-f6fde3c14c60","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650044 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"}]
05:27:30 INFO - 1499689650046 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"
05:27:30 INFO - 1499689650049 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"
05:27:30 INFO - 1499689650049 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"
05:27:30 INFO - 1499689650080 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"
05:27:30 INFO - 1499689650085 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"
05:27:30 INFO - 1499689650089 Marionette TRACE 376 <- [1,2,null,{}]
05:27:30 INFO - 1499689650097 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:27:30 INFO - 1499689650100 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb3bae25-c572-46ac-abad-51f354afbdd3","ELEMENT":"eb3bae25-c572-46ac-abad-51f354afbdd3"}}]
05:27:30 INFO - 1499689650100 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"eb3bae25-c572-46ac-abad-51f354afbdd3"}]
05:27:30 INFO - 1499689650335 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:27:30 INFO - 1499689650335 Marionette TRACE 376 <- [1,4,null,{}]
05:27:30 INFO - 1499689650337 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}]
05:27:30 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499689650340 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650341 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650342 Marionette TRACE 376 <- [1,6,null,{}]
05:27:30 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
05:27:30 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:27:30 INFO - 1499689650344 Marionette DEBUG Closed connection 376
05:27:30 INFO - 1499689650345 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49793
05:27:30 INFO - 1499689650345 Marionette DEBUG Closed connection 377
05:27:30 INFO - 1499689650346 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49794
05:27:30 INFO - 1499689650347 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650347 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650349 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650352 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6720b403-d141-496e-8e88-0a1fd32e1ddc","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650354 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:27:30 INFO - 1499689650354 Marionette TRACE 378 <- [1,2,null,{}]
05:27:30 INFO - 1499689650355 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:30 INFO - 1499689650356 Marionette TRACE 378 <- [1,3,null,{}]
05:27:30 INFO - 1499689650357 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:30 INFO - 1499689650357 Marionette TRACE 378 <- [1,4,null,{}]
05:27:30 INFO - 1499689650359 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}]
05:27:30 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499689650361 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650362 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650363 Marionette TRACE 378 <- [1,6,null,{}]
05:27:30 INFO - 1499689650364 Marionette DEBUG Closed connection 378
05:27:30 INFO - 1499689650365 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49795
05:27:30 INFO - 1499689650366 Marionette DEBUG Closed connection 379
05:27:30 INFO - 1499689650367 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49796
05:27:30 INFO - 1499689650368 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650368 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650370 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650372 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c6d842b5-1a59-4a1e-ae3b-74f285aa0770","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650374 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:27:30 INFO - 1499689650375 Marionette TRACE 380 <- [1,2,null,{}]
05:27:30 INFO - 1499689650377 Marionette DEBUG Closed connection 380
05:27:30 INFO - 1499689650377 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49797
05:27:30 INFO - 1499689650378 Marionette DEBUG Closed connection 381
05:27:30 INFO - 1499689650379 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49798
05:27:30 INFO - 1499689650380 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:30 INFO - 1499689650380 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650382 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650385 Marionette TRACE 382 <- [1,1,null,{"sessionId":"aa785e0d-4265-4fc4-94d6-6bb7e02aaf89","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650387 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"}]
05:27:30 INFO - 1499689650388 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"
05:27:30 INFO - 1499689650390 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"
05:27:30 INFO - 1499689650390 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"
05:27:30 INFO - 1499689650400 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"
05:27:30 INFO - 1499689650404 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"
05:27:30 INFO - 1499689650414 Marionette TRACE 382 <- [1,2,null,{}]
05:27:30 INFO - 1499689650415 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:27:30 INFO - 1499689650418 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d8e7b41-5121-4b40-9296-0175528ed207","ELEMENT":"8d8e7b41-5121-4b40-9296-0175528ed207"}}]
05:27:30 INFO - 1499689650419 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8d8e7b41-5121-4b40-9296-0175528ed207"}]
05:27:30 INFO - 1499689650657 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:27:30 INFO - 1499689650657 Marionette TRACE 382 <- [1,4,null,{}]
05:27:30 INFO - 1499689650659 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}]
05:27:30 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499689650661 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650662 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650663 Marionette TRACE 382 <- [1,6,null,{}]
05:27:30 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 321ms
05:27:30 INFO - TEST-START | test_click.py TestClick.test_input_file
05:27:30 INFO - 1499689650665 Marionette DEBUG Closed connection 382
05:27:30 INFO - 1499689650666 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49799
05:27:30 INFO - 1499689650667 Marionette DEBUG Closed connection 383
05:27:30 INFO - 1499689650668 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49800
05:27:30 INFO - 1499689650669 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650669 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650671 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650674 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4e44bbca-8c09-4e55-b439-ed98ebc2ff9b","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650675 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:27:30 INFO - 1499689650676 Marionette TRACE 384 <- [1,2,null,{}]
05:27:30 INFO - 1499689650677 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:30 INFO - 1499689650677 Marionette TRACE 384 <- [1,3,null,{}]
05:27:30 INFO - 1499689650678 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:30 INFO - 1499689650678 Marionette TRACE 384 <- [1,4,null,{}]
05:27:30 INFO - 1499689650680 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}]
05:27:30 INFO - TEST-START: test_click.py TestClick.test_input_file1499689650682 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650683 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650684 Marionette TRACE 384 <- [1,6,null,{}]
05:27:30 INFO - 1499689650685 Marionette DEBUG Closed connection 384
05:27:30 INFO - 1499689650686 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49801
05:27:30 INFO - 1499689650687 Marionette DEBUG Closed connection 385
05:27:30 INFO - 1499689650688 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49802
05:27:30 INFO - 1499689650689 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650689 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650691 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650693 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c971cfcc-fbcb-4d06-b1f3-f413f162e2ed","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650695 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:27:30 INFO - 1499689650696 Marionette TRACE 386 <- [1,2,null,{}]
05:27:30 INFO - 1499689650698 Marionette DEBUG Closed connection 386
05:27:30 INFO - 1499689650698 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49803
05:27:30 INFO - 1499689650699 Marionette DEBUG Closed connection 387
05:27:30 INFO - 1499689650700 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49804
05:27:30 INFO - 1499689650701 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:30 INFO - 1499689650701 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650703 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650712 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7a3aac23-03e6-41a0-92d9-b7003222a80b","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650714 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:27:30 INFO - 1499689650716 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"
05:27:30 INFO - 1499689650718 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"
05:27:30 INFO - 1499689650719 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"
05:27:30 INFO - 1499689650735 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:27:30 INFO - 1499689650739 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:27:30 INFO - 1499689650747 Marionette TRACE 388 <- [1,2,null,{}]
05:27:30 INFO - 1499689650749 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:27:30 INFO - 1499689650751 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"927bed0a-3ef7-4040-963f-da5005ebf8a1","ELEMENT":"927bed0a-3ef7-4040-963f-da5005ebf8a1"}}]
05:27:30 INFO - 1499689650752 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"927bed0a-3ef7-4040-963f-da5005ebf8a1"}]
05:27:30 INFO - 1499689650777 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:27:30 INFO - 1499689650779 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}]
05:27:30 INFO - TEST-END: test_click.py TestClick.test_input_file1499689650781 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650782 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650783 Marionette TRACE 388 <- [1,6,null,{}]
05:27:30 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 119ms
05:27:30 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:27:30 INFO - 1499689650785 Marionette DEBUG Closed connection 388
05:27:30 INFO - 1499689650786 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49805
05:27:30 INFO - 1499689650786 Marionette DEBUG Closed connection 389
05:27:30 INFO - 1499689650787 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49806
05:27:30 INFO - 1499689650788 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650789 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650791 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650793 Marionette TRACE 390 <- [1,1,null,{"sessionId":"db8fdc0d-718e-4daf-ab86-a1a0640be5dc","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650795 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:27:30 INFO - 1499689650796 Marionette TRACE 390 <- [1,2,null,{}]
05:27:30 INFO - 1499689650796 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:30 INFO - 1499689650797 Marionette TRACE 390 <- [1,3,null,{}]
05:27:30 INFO - 1499689650798 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:30 INFO - 1499689650798 Marionette TRACE 390 <- [1,4,null,{}]
05:27:30 INFO - 1499689650800 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}]
05:27:30 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499689650802 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:27:30 INFO - 1499689650803 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:27:30 INFO - 1499689650804 Marionette TRACE 390 <- [1,6,null,{}]
05:27:30 INFO - 1499689650805 Marionette DEBUG Closed connection 390
05:27:30 INFO - 1499689650806 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49807
05:27:30 INFO - 1499689650807 Marionette DEBUG Closed connection 391
05:27:30 INFO - 1499689650807 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49808
05:27:30 INFO - 1499689650808 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:30 INFO - 1499689650809 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650811 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650813 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0ebf04b6-1b4b-4a58-b3dc-201489fab939","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650815 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:27:30 INFO - 1499689650816 Marionette TRACE 392 <- [1,2,null,{}]
05:27:30 INFO - 1499689650817 Marionette DEBUG Closed connection 392
05:27:30 INFO - 1499689650818 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49809
05:27:30 INFO - 1499689650819 Marionette DEBUG Closed connection 393
05:27:30 INFO - 1499689650820 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49810
05:27:30 INFO - 1499689650821 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:30 INFO - 1499689650821 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:30 INFO - 1499689650823 Marionette DEBUG Register listener.js for window 12
05:27:30 INFO - 1499689650825 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a2987bb8-b253-4875-8048-feb74ae76b43","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:30 INFO - 1499689650827 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"}]
05:27:30 INFO - 1499689650828 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:27:30 INFO - 1499689650830 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:27:30 INFO - 1499689650831 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:27:30 INFO - 1499689650849 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"
05:27:30 INFO - 1499689650851 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"
05:27:30 INFO - 1499689650853 Marionette TRACE 394 <- [1,2,null,{}]
05:27:30 INFO - 1499689650860 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:27:30 INFO - 1499689650862 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9c0439b-3d44-462e-ae37-041245f9d545","ELEMENT":"d9c0439b-3d44-462e-ae37-041245f9d545"}}]
05:27:30 INFO - 1499689650863 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:27:30 INFO - 1499689650865 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c58a3613-1fd0-430a-92cf-b1974e834dc7","ELEMENT":"c58a3613-1fd0-430a-92cf-b1974e834dc7"}}]
05:27:30 INFO - 1499689650866 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"d9c0439b-3d44-462e-ae37-041245f9d545"}]
05:27:31 INFO - 1499689651099 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:27:31 INFO - 1499689651099 Marionette TRACE 394 <- [1,5,null,{}]
05:27:31 INFO - 1499689651100 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c58a3613-1fd0-430a-92cf-b1974e834dc7"}]
05:27:31 INFO - 1499689651114 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:27:31 INFO - 1499689651115 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:27:31 INFO - 1499689651118 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:27:31 INFO - 1499689651119 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}]
05:27:31 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499689651122 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:27:31 INFO - 1499689651122 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:27:31 INFO - 1499689651123 Marionette TRACE 394 <- [1,9,null,{}]
05:27:31 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 340ms
05:27:31 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:27:31 INFO - 1499689651125 Marionette DEBUG Closed connection 394
05:27:31 INFO - 1499689651126 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49811
05:27:31 INFO - 1499689651127 Marionette DEBUG Closed connection 395
05:27:31 INFO - 1499689651128 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49812
05:27:31 INFO - 1499689651129 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:31 INFO - 1499689651129 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:31 INFO - 1499689651131 Marionette DEBUG Register listener.js for window 12
05:27:31 INFO - 1499689651133 Marionette TRACE 396 <- [1,1,null,{"sessionId":"98a11ea5-709c-40da-b817-4794c1d788bc","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:31 INFO - 1499689651135 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:27:31 INFO - 1499689651136 Marionette TRACE 396 <- [1,2,null,{}]
05:27:31 INFO - 1499689651137 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:27:31 INFO - 1499689651137 Marionette TRACE 396 <- [1,3,null,{}]
05:27:31 INFO - 1499689651138 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:27:31 INFO - 1499689651138 Marionette TRACE 396 <- [1,4,null,{}]
05:27:31 INFO - 1499689651140 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}]
05:27:31 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499689651142 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:27:31 INFO - 1499689651143 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:27:31 INFO - 1499689651144 Marionette TRACE 396 <- [1,6,null,{}]
05:27:31 INFO - 1499689651146 Marionette DEBUG Closed connection 396
05:27:31 INFO - 1499689651146 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49813
05:27:31 INFO - 1499689651147 Marionette DEBUG Closed connection 397
05:27:31 INFO - 1499689651148 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49814
05:27:31 INFO - 1499689651149 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:27:31 INFO - 1499689651149 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:31 INFO - 1499689651151 Marionette DEBUG Register listener.js for window 12
05:27:31 INFO - 1499689651153 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5da2c240-8372-4c11-be21-3dbeea6b1f55","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:31 INFO - 1499689651155 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:27:31 INFO - 1499689651156 Marionette TRACE 398 <- [1,2,null,{}]
05:27:31 INFO - 1499689651158 Marionette DEBUG Closed connection 398
05:27:31 INFO - 1499689651158 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49815
05:27:31 INFO - 1499689651159 Marionette DEBUG Closed connection 399
05:27:31 INFO - 1499689651160 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49816
05:27:31 INFO - 1499689651161 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:27:31 INFO - 1499689651161 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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}
05:27:31 INFO - 1499689651163 Marionette DEBUG Register listener.js for window 12
05:27:31 INFO - 1499689651165 Marionette TRACE 400 <- [1,1,null,{"sessionId":"52845a95-9b60-4144-9dfa-befc68fe6491","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":1972,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptc_09h.mozrunner","moz:accessibilityChecks":false}}]
05:27:31 INFO - 1499689651167 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"}]
05:27:31 INFO - 1499689651169 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"
05:27:31 INFO - 1499689651171 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"
05:27:31 INFO - 1499689651171 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"
05:27:31 INFO - 1499689651195 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"
05:27:31 INFO - 1499689651197 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"
05:27:31 INFO - 1499689651200 Marionette TRACE 400 <- [1,2,null,{}]
05:27:31 INFO - 1499689651201 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:27:31 INFO - 1499689651204 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cda9a4cd-5812-417a-9cb6-69ff92cb31bb","ELEMENT":"cda9a4cd-5812-417a-9cb6-69ff92cb31bb"}}]
05:27:31 INFO - 1499689651210 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cda9a4cd-5812-417a-9cb6-69ff92cb31bb"}]
05:27:31 INFO - 1499689651232 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:27:31 INFO - 1499689651233 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"cda9a4cd-5812-417a-9cb6-69ff92cb31bb"}]
05:27:31 INFO - 1499689651247 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element