could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:56:03 INFO - 1498859763149 Marionette TRACE 358 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
14:56:03 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498859763152 Marionette TRACE 358 <- [1,5,null,{"value":null}]
14:56:03 INFO - 1498859763153 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
14:56:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 154ms
14:56:03 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:56:03 INFO - 1498859763155 Marionette TRACE 358 <- [1,6,null,{}]
14:56:03 INFO - 1498859763157 Marionette DEBUG Closed connection 358
14:56:03 INFO - 1498859763158 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49938
14:56:03 INFO - 1498859763159 Marionette DEBUG Closed connection 359
14:56:03 INFO - 1498859763159 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49939
14:56:03 INFO - 1498859763161 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763162 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763165 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763169 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b7e38e8f-1cc4-4253-953f-d4537a6f1298","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763171 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:56:03 INFO - 1498859763171 Marionette TRACE 360 <- [1,2,null,{}]
14:56:03 INFO - 1498859763172 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:03 INFO - 1498859763173 Marionette TRACE 360 <- [1,3,null,{}]
14:56:03 INFO - 1498859763174 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:03 INFO - 1498859763175 Marionette TRACE 360 <- [1,4,null,{}]
14:56:03 INFO - 1498859763178 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
14:56:03 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498859763182 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:56:03 INFO - 1498859763183 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:56:03 INFO - 1498859763185 Marionette TRACE 360 <- [1,6,null,{}]
14:56:03 INFO - 1498859763187 Marionette DEBUG Closed connection 360
14:56:03 INFO - 1498859763188 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49940
14:56:03 INFO - 1498859763189 Marionette DEBUG Closed connection 361
14:56:03 INFO - 1498859763189 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49941
14:56:03 INFO - 1498859763191 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763191 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763195 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763198 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f9155947-2dcd-4b86-ad47-dc64cab5229f","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763200 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:56:03 INFO - 1498859763202 Marionette TRACE 362 <- [1,2,null,{}]
14:56:03 INFO - 1498859763203 Marionette DEBUG Closed connection 362
14:56:03 INFO - 1498859763204 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49942
14:56:03 INFO - 1498859763205 Marionette DEBUG Closed connection 363
14:56:03 INFO - 1498859763206 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49943
14:56:03 INFO - 1498859763207 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:03 INFO - 1498859763208 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763211 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763225 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8c1b08c4-a0d9-45cd-b63a-189c1afe2914","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763226 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49533/clicks.html"}]
14:56:03 INFO - 1498859763229 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49533/hidden.html"
14:56:03 INFO - 1498859763238 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49533/hidden.html"
14:56:03 INFO - 1498859763238 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49533/hidden.html"
14:56:03 INFO - 1498859763252 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49533/clicks.html"
14:56:03 INFO - 1498859763254 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49533/clicks.html"
14:56:03 INFO - 1498859763259 Marionette TRACE 364 <- [1,2,null,{}]
14:56:03 INFO - 1498859763269 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:56:03 INFO - 1498859763278 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"495f1665-079f-4f7d-9396-3f6879c8540f","ELEMENT":"495f1665-079f-4f7d-9396-3f6879c8540f"}}]
14:56:03 INFO - 1498859763280 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"495f1665-079f-4f7d-9396-3f6879c8540f"}]
14:56:03 INFO - 1498859763327 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49533/clicks.html"
14:56:03 INFO - 1498859763335 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49533/clicks.html"
14:56:03 INFO - 1498859763336 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49533/clicks.html"
14:56:03 INFO - 1498859763344 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49533/test.html"
14:56:03 INFO - 1498859763348 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49533/test.html"
14:56:03 INFO - 1498859763355 Marionette TRACE 364 <- [1,4,null,{}]
14:56:03 INFO - 1498859763367 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:56:03 INFO - 1498859763371 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a054b5d1-b9ce-4a55-b2bf-99b658a473cb","ELEMENT":"a054b5d1-b9ce-4a55-b2bf-99b658a473cb"}}]
14:56:03 INFO - 1498859763373 Marionette TRACE 364 -> [0,6,"getTitle",{}]
14:56:03 INFO - 1498859763375 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
14:56:03 INFO - 1498859763377 Marionette TRACE 364 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
14:56:03 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498859763380 Marionette TRACE 364 <- [1,7,null,{"value":null}]
14:56:03 INFO - 1498859763381 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
14:56:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 228ms
14:56:03 INFO - TEST-START | test_click.py TestClick.test_container_element
14:56:03 INFO - 1498859763383 Marionette TRACE 364 <- [1,8,null,{}]
14:56:03 INFO - 1498859763385 Marionette DEBUG Closed connection 364
14:56:03 INFO - 1498859763386 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49946
14:56:03 INFO - 1498859763387 Marionette DEBUG Closed connection 365
14:56:03 INFO - 1498859763388 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49947
14:56:03 INFO - 1498859763389 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763390 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763393 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763397 Marionette TRACE 366 <- [1,1,null,{"sessionId":"f5168309-4960-4b68-bb23-bef4ac3180f5","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763398 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:56:03 INFO - 1498859763399 Marionette TRACE 366 <- [1,2,null,{}]
14:56:03 INFO - 1498859763400 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:03 INFO - 1498859763400 Marionette TRACE 366 <- [1,3,null,{}]
14:56:03 INFO - 1498859763401 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:03 INFO - 1498859763402 Marionette TRACE 366 <- [1,4,null,{}]
14:56:03 INFO - 1498859763404 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
14:56:03 INFO - TEST-START: test_click.py TestClick.test_container_element1498859763408 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:56:03 INFO - 1498859763409 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:56:03 INFO - 1498859763410 Marionette TRACE 366 <- [1,6,null,{}]
14:56:03 INFO - 1498859763412 Marionette DEBUG Closed connection 366
14:56:03 INFO - 1498859763413 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49948
14:56:03 INFO - 1498859763414 Marionette DEBUG Closed connection 367
14:56:03 INFO - 1498859763415 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49949
14:56:03 INFO - 1498859763416 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763417 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763420 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763424 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4b9847b6-41c8-4596-b386-98cbbd423f16","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763425 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:56:03 INFO - 1498859763427 Marionette TRACE 368 <- [1,2,null,{}]
14:56:03 INFO - 1498859763429 Marionette DEBUG Closed connection 368
14:56:03 INFO - 1498859763430 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49950
14:56:03 INFO - 1498859763431 Marionette DEBUG Closed connection 369
14:56:03 INFO - 1498859763432 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49951
14:56:03 INFO - 1498859763434 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:03 INFO - 1498859763434 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763439 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763442 Marionette TRACE 370 <- [1,1,null,{"sessionId":"493bb076-745f-48c6-b9a1-842e0a242dca","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763443 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:56:03 INFO - 1498859763446 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49533/test.html"
14:56:03 INFO - 1498859763448 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49533/test.html"
14:56:03 INFO - 1498859763449 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49533/test.html"
14:56:03 INFO - 1498859763456 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"
14:56:03 INFO - 1498859763461 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"
14:56:03 INFO - 1498859763484 Marionette TRACE 370 <- [1,2,null,{}]
14:56:03 INFO - 1498859763501 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:56:03 INFO - 1498859763506 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"91125e9e-cdb9-4a69-9637-5074585d77a6","ELEMENT":"91125e9e-cdb9-4a69-9637-5074585d77a6"}}]
14:56:03 INFO - 1498859763507 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"91125e9e-cdb9-4a69-9637-5074585d77a6"}]
14:56:03 INFO - 1498859763755 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:56:03 INFO - 1498859763756 Marionette TRACE 370 <- [1,4,null,{}]
14:56:03 INFO - 1498859763757 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"91125e9e-cdb9-4a69-9637-5074585d77a6","name":"selected"}]
14:56:03 INFO - 1498859763763 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:56:03 INFO - 1498859763765 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
14:56:03 INFO - TEST-END: test_click.py TestClick.test_container_element1498859763768 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:56:03 INFO - 1498859763769 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:56:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 387ms
14:56:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:56:03 INFO - 1498859763771 Marionette TRACE 370 <- [1,7,null,{}]
14:56:03 INFO - 1498859763772 Marionette DEBUG Closed connection 370
14:56:03 INFO - 1498859763773 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49952
14:56:03 INFO - 1498859763774 Marionette DEBUG Closed connection 371
14:56:03 INFO - 1498859763775 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49953
14:56:03 INFO - 1498859763776 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763777 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763780 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763783 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4e594c52-b616-42ac-9fe2-adc82ce6882e","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763785 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:56:03 INFO - 1498859763785 Marionette TRACE 372 <- [1,2,null,{}]
14:56:03 INFO - 1498859763786 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:03 INFO - 1498859763787 Marionette TRACE 372 <- [1,3,null,{}]
14:56:03 INFO - 1498859763788 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:03 INFO - 1498859763788 Marionette TRACE 372 <- [1,4,null,{}]
14:56:03 INFO - 1498859763790 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
14:56:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498859763794 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:56:03 INFO - 1498859763795 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:56:03 INFO - 1498859763796 Marionette TRACE 372 <- [1,6,null,{}]
14:56:03 INFO - 1498859763798 Marionette DEBUG Closed connection 372
14:56:03 INFO - 1498859763799 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49954
14:56:03 INFO - 1498859763800 Marionette DEBUG Closed connection 373
14:56:03 INFO - 1498859763801 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49955
14:56:03 INFO - 1498859763802 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:03 INFO - 1498859763803 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763806 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763809 Marionette TRACE 374 <- [1,1,null,{"sessionId":"7e54a621-f04d-453f-9653-ed7f5195db2a","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763810 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:56:03 INFO - 1498859763812 Marionette TRACE 374 <- [1,2,null,{}]
14:56:03 INFO - 1498859763815 Marionette DEBUG Closed connection 374
14:56:03 INFO - 1498859763816 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49956
14:56:03 INFO - 1498859763817 Marionette DEBUG Closed connection 375
14:56:03 INFO - 1498859763818 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49957
14:56:03 INFO - 1498859763819 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:03 INFO - 1498859763820 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:03 INFO - 1498859763823 Marionette DEBUG Register listener.js for window 2147483649
14:56:03 INFO - 1498859763827 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6e3f8931-2075-4a2b-a2c3-756e28e2defd","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:03 INFO - 1498859763828 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:56:03 INFO - 1498859763831 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"
14:56:03 INFO - 1498859763835 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"
14:56:03 INFO - 1498859763835 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"
14:56:03 INFO - 1498859763845 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"
14:56:03 INFO - 1498859763852 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"
14:56:03 INFO - 1498859763858 Marionette TRACE 376 <- [1,2,null,{}]
14:56:03 INFO - 1498859763867 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:56:03 INFO - 1498859763872 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8b8e164d-31d0-4000-bac3-3b13aa1064a1","ELEMENT":"8b8e164d-31d0-4000-bac3-3b13aa1064a1"}}]
14:56:03 INFO - 1498859763873 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8b8e164d-31d0-4000-bac3-3b13aa1064a1"}]
14:56:04 INFO - 1498859764122 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:56:04 INFO - 1498859764123 Marionette TRACE 376 <- [1,4,null,{}]
14:56:04 INFO - 1498859764124 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"8b8e164d-31d0-4000-bac3-3b13aa1064a1","name":"selected"}]
14:56:04 INFO - 1498859764130 Marionette TRACE 376 <- [1,5,null,{"value":true}]
14:56:04 INFO - 1498859764132 Marionette TRACE 376 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
14:56:04 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498859764136 Marionette TRACE 376 <- [1,6,null,{"value":null}]
14:56:04 INFO - 1498859764137 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
14:56:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 367ms
14:56:04 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:56:04 INFO - 1498859764139 Marionette TRACE 376 <- [1,7,null,{}]
14:56:04 INFO - 1498859764140 Marionette DEBUG Closed connection 376
14:56:04 INFO - 1498859764141 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49958
14:56:04 INFO - 1498859764142 Marionette DEBUG Closed connection 377
14:56:04 INFO - 1498859764143 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49959
14:56:04 INFO - 1498859764144 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764145 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764148 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764152 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f17fe915-e913-489b-b1e7-a12fd6d4c631","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764154 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:56:04 INFO - 1498859764154 Marionette TRACE 378 <- [1,2,null,{}]
14:56:04 INFO - 1498859764156 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:04 INFO - 1498859764157 Marionette TRACE 378 <- [1,3,null,{}]
14:56:04 INFO - 1498859764158 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:04 INFO - 1498859764159 Marionette TRACE 378 <- [1,4,null,{}]
14:56:04 INFO - 1498859764160 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
14:56:04 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498859764164 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764165 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:56:04 INFO - 1498859764167 Marionette TRACE 378 <- [1,6,null,{}]
14:56:04 INFO - 1498859764169 Marionette DEBUG Closed connection 378
14:56:04 INFO - 1498859764170 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49960
14:56:04 INFO - 1498859764171 Marionette DEBUG Closed connection 379
14:56:04 INFO - 1498859764172 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49961
14:56:04 INFO - 1498859764173 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764173 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764177 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764180 Marionette TRACE 380 <- [1,1,null,{"sessionId":"e80e364a-fa7a-4137-92d2-fc6ddd99ecc7","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764182 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:56:04 INFO - 1498859764183 Marionette TRACE 380 <- [1,2,null,{}]
14:56:04 INFO - 1498859764185 Marionette DEBUG Closed connection 380
14:56:04 INFO - 1498859764186 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49962
14:56:04 INFO - 1498859764187 Marionette DEBUG Closed connection 381
14:56:04 INFO - 1498859764187 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49963
14:56:04 INFO - 1498859764189 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:04 INFO - 1498859764189 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764193 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764196 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b4de1919-9fc7-4a43-a680-9b5a0147b29a","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764198 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
14:56:04 INFO - 1498859764201 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"
14:56:04 INFO - 1498859764203 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"
14:56:04 INFO - 1498859764203 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"
14:56:04 INFO - 1498859764211 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"
14:56:04 INFO - 1498859764214 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"
14:56:04 INFO - 1498859764225 Marionette TRACE 382 <- [1,2,null,{}]
14:56:04 INFO - 1498859764236 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:56:04 INFO - 1498859764239 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d585e5fd-4f90-4e6c-8eca-0b713dffdf1f","ELEMENT":"d585e5fd-4f90-4e6c-8eca-0b713dffdf1f"}}]
14:56:04 INFO - 1498859764240 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d585e5fd-4f90-4e6c-8eca-0b713dffdf1f"}]
14:56:04 INFO - 1498859764490 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:56:04 INFO - 1498859764491 Marionette TRACE 382 <- [1,4,null,{}]
14:56:04 INFO - 1498859764493 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
14:56:04 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498859764496 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764497 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:56:04 INFO - 1498859764499 Marionette TRACE 382 <- [1,6,null,{}]
14:56:04 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 360ms
14:56:04 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:56:04 INFO - 1498859764501 Marionette DEBUG Closed connection 382
14:56:04 INFO - 1498859764502 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49964
14:56:04 INFO - 1498859764503 Marionette DEBUG Closed connection 383
14:56:04 INFO - 1498859764504 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49965
14:56:04 INFO - 1498859764505 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764506 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764509 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764513 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0da7e2c7-3fa4-4853-8100-2098622cf885","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764514 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:56:04 INFO - 1498859764515 Marionette TRACE 384 <- [1,2,null,{}]
14:56:04 INFO - 1498859764516 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:04 INFO - 1498859764516 Marionette TRACE 384 <- [1,3,null,{}]
14:56:04 INFO - 1498859764517 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:04 INFO - 1498859764518 Marionette TRACE 384 <- [1,4,null,{}]
14:56:04 INFO - 1498859764520 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
14:56:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498859764523 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764524 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:56:04 INFO - 1498859764526 Marionette TRACE 384 <- [1,6,null,{}]
14:56:04 INFO - 1498859764528 Marionette DEBUG Closed connection 384
14:56:04 INFO - 1498859764529 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49966
14:56:04 INFO - 1498859764530 Marionette DEBUG Closed connection 385
14:56:04 INFO - 1498859764531 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49967
14:56:04 INFO - 1498859764532 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764532 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764536 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764540 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1695b338-4985-4f60-b23d-d0e9fa06b4ca","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764541 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:56:04 INFO - 1498859764543 Marionette TRACE 386 <- [1,2,null,{}]
14:56:04 INFO - 1498859764545 Marionette DEBUG Closed connection 386
14:56:04 INFO - 1498859764546 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49968
14:56:04 INFO - 1498859764547 Marionette DEBUG Closed connection 387
14:56:04 INFO - 1498859764548 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49969
14:56:04 INFO - 1498859764549 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:04 INFO - 1498859764550 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764553 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764557 Marionette TRACE 388 <- [1,1,null,{"sessionId":"5f13f0e5-3f92-4356-b6cb-aaa7d7c943eb","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764558 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:56:04 INFO - 1498859764561 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"
14:56:04 INFO - 1498859764563 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"
14:56:04 INFO - 1498859764564 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"
14:56:04 INFO - 1498859764571 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"
14:56:04 INFO - 1498859764575 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"
14:56:04 INFO - 1498859764577 Marionette TRACE 388 <- [1,2,null,{}]
14:56:04 INFO - 1498859764589 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:56:04 INFO - 1498859764593 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0aae7a86-4c8f-44c5-b2bc-a0524ad376e8","ELEMENT":"0aae7a86-4c8f-44c5-b2bc-a0524ad376e8"}}]
14:56:04 INFO - 1498859764594 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"0aae7a86-4c8f-44c5-b2bc-a0524ad376e8"}]
14:56:04 INFO - 1498859764840 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:56:04 INFO - 1498859764841 Marionette TRACE 388 <- [1,4,null,{}]
14:56:04 INFO - 1498859764843 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
14:56:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498859764847 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764848 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:56:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 350ms
14:56:04 INFO - TEST-START | test_click.py TestClick.test_input_file
14:56:04 INFO - 1498859764849 Marionette TRACE 388 <- [1,6,null,{}]
14:56:04 INFO - 1498859764851 Marionette DEBUG Closed connection 388
14:56:04 INFO - 1498859764852 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49970
14:56:04 INFO - 1498859764853 Marionette DEBUG Closed connection 389
14:56:04 INFO - 1498859764854 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49971
14:56:04 INFO - 1498859764855 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764856 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764859 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764863 Marionette TRACE 390 <- [1,1,null,{"sessionId":"69ac8855-aac9-4632-9088-ef91d93430d3","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764864 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:56:04 INFO - 1498859764865 Marionette TRACE 390 <- [1,2,null,{}]
14:56:04 INFO - 1498859764865 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:04 INFO - 1498859764866 Marionette TRACE 390 <- [1,3,null,{}]
14:56:04 INFO - 1498859764867 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:04 INFO - 1498859764867 Marionette TRACE 390 <- [1,4,null,{}]
14:56:04 INFO - 1498859764869 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
14:56:04 INFO - TEST-START: test_click.py TestClick.test_input_file1498859764873 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764874 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:56:04 INFO - 1498859764876 Marionette TRACE 390 <- [1,6,null,{}]
14:56:04 INFO - 1498859764878 Marionette DEBUG Closed connection 390
14:56:04 INFO - 1498859764879 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49972
14:56:04 INFO - 1498859764880 Marionette DEBUG Closed connection 391
14:56:04 INFO - 1498859764881 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49973
14:56:04 INFO - 1498859764882 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:04 INFO - 1498859764882 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764886 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764890 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d0a3669f-4e3d-407c-ac9f-f0def5230a83","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764891 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:56:04 INFO - 1498859764893 Marionette TRACE 392 <- [1,2,null,{}]
14:56:04 INFO - 1498859764895 Marionette DEBUG Closed connection 392
14:56:04 INFO - 1498859764896 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49974
14:56:04 INFO - 1498859764896 Marionette DEBUG Closed connection 393
14:56:04 INFO - 1498859764897 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49975
14:56:04 INFO - 1498859764898 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:04 INFO - 1498859764899 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:04 INFO - 1498859764902 Marionette DEBUG Register listener.js for window 2147483649
14:56:04 INFO - 1498859764906 Marionette TRACE 394 <- [1,1,null,{"sessionId":"40960580-80c9-4ef1-957d-7a4856657b6c","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:04 INFO - 1498859764908 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:56:04 INFO - 1498859764910 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"
14:56:04 INFO - 1498859764912 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"
14:56:04 INFO - 1498859764913 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"
14:56:04 INFO - 1498859764921 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:56:04 INFO - 1498859764926 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:56:04 INFO - 1498859764928 Marionette TRACE 394 <- [1,2,null,{}]
14:56:04 INFO - 1498859764944 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:56:04 INFO - 1498859764953 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"479e49e7-545c-429e-a36c-3bd288577194","ELEMENT":"479e49e7-545c-429e-a36c-3bd288577194"}}]
14:56:04 INFO - 1498859764954 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"479e49e7-545c-429e-a36c-3bd288577194"}]
14:56:04 INFO - 1498859764983 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:56:04 INFO - 1498859764985 Marionette TRACE 394 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
14:56:04 INFO - TEST-END: test_click.py TestClick.test_input_file1498859764989 Marionette TRACE 394 <- [1,5,null,{"value":null}]
14:56:04 INFO - 1498859764990 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
14:56:04 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 141ms
14:56:04 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:56:04 INFO - 1498859764992 Marionette TRACE 394 <- [1,6,null,{}]
14:56:04 INFO - 1498859764993 Marionette DEBUG Closed connection 394
14:56:04 INFO - 1498859764994 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49976
14:56:04 INFO - 1498859764995 Marionette DEBUG Closed connection 395
14:56:04 INFO - 1498859764996 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49977
14:56:05 INFO - 1498859764997 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:05 INFO - 1498859764998 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765001 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765005 Marionette TRACE 396 <- [1,1,null,{"sessionId":"036a1da1-1fca-48cb-a114-b69729d02f98","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765006 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:56:05 INFO - 1498859765007 Marionette TRACE 396 <- [1,2,null,{}]
14:56:05 INFO - 1498859765008 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:05 INFO - 1498859765008 Marionette TRACE 396 <- [1,3,null,{}]
14:56:05 INFO - 1498859765009 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:05 INFO - 1498859765010 Marionette TRACE 396 <- [1,4,null,{}]
14:56:05 INFO - 1498859765012 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
14:56:05 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498859765015 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:56:05 INFO - 1498859765016 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:56:05 INFO - 1498859765018 Marionette TRACE 396 <- [1,6,null,{}]
14:56:05 INFO - 1498859765020 Marionette DEBUG Closed connection 396
14:56:05 INFO - 1498859765021 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49978
14:56:05 INFO - 1498859765021 Marionette DEBUG Closed connection 397
14:56:05 INFO - 1498859765022 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49979
14:56:05 INFO - 1498859765024 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:05 INFO - 1498859765024 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765028 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765031 Marionette TRACE 398 <- [1,1,null,{"sessionId":"985557a1-e856-4695-9a43-a0a2eb4c63b5","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765033 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:56:05 INFO - 1498859765034 Marionette TRACE 398 <- [1,2,null,{}]
14:56:05 INFO - 1498859765036 Marionette DEBUG Closed connection 398
14:56:05 INFO - 1498859765037 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49980
14:56:05 INFO - 1498859765038 Marionette DEBUG Closed connection 399
14:56:05 INFO - 1498859765039 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49981
14:56:05 INFO - 1498859765040 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:05 INFO - 1498859765041 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765044 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765047 Marionette TRACE 400 <- [1,1,null,{"sessionId":"da538727-8ae8-4dd1-9dfe-128c6bcd3dec","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765049 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
14:56:05 INFO - 1498859765051 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:56:05 INFO - 1498859765053 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:56:05 INFO - 1498859765054 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:56:05 INFO - 1498859765063 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"
14:56:05 INFO - 1498859765065 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"
14:56:05 INFO - 1498859765074 Marionette TRACE 400 <- [1,2,null,{}]
14:56:05 INFO - 1498859765084 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:56:05 INFO - 1498859765088 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"36eca82d-0601-4aa7-9933-1e9b625ca514","ELEMENT":"36eca82d-0601-4aa7-9933-1e9b625ca514"}}]
14:56:05 INFO - 1498859765089 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:56:05 INFO - 1498859765092 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89fa45f3-ddb7-4ffa-bfba-481ff0baf1c7","ELEMENT":"89fa45f3-ddb7-4ffa-bfba-481ff0baf1c7"}}]
14:56:05 INFO - 1498859765093 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"36eca82d-0601-4aa7-9933-1e9b625ca514"}]
14:56:05 INFO - 1498859765342 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:56:05 INFO - 1498859765343 Marionette TRACE 400 <- [1,5,null,{}]
14:56:05 INFO - 1498859765344 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"89fa45f3-ddb7-4ffa-bfba-481ff0baf1c7"}]
14:56:05 INFO - 1498859765364 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
14:56:05 INFO - 1498859765366 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:56:05 INFO - 1498859765369 Marionette TRACE 400 <- [1,7,null,{"value":false}]
14:56:05 INFO - 1498859765371 Marionette TRACE 400 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
14:56:05 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 385ms
14:56:05 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498859765375 Marionette TRACE 400 <- [1,8,null,{"value":null}]
14:56:05 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:56:05 INFO - 1498859765376 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
14:56:05 INFO - 1498859765378 Marionette TRACE 400 <- [1,9,null,{}]
14:56:05 INFO - 1498859765379 Marionette DEBUG Closed connection 400
14:56:05 INFO - 1498859765381 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49982
14:56:05 INFO - 1498859765382 Marionette DEBUG Closed connection 401
14:56:05 INFO - 1498859765382 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49983
14:56:05 INFO - 1498859765384 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:05 INFO - 1498859765384 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765388 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765391 Marionette TRACE 402 <- [1,1,null,{"sessionId":"d0d43827-a330-4300-b04b-aa59e6fe94cc","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765392 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
14:56:05 INFO - 1498859765393 Marionette TRACE 402 <- [1,2,null,{}]
14:56:05 INFO - 1498859765394 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:56:05 INFO - 1498859765395 Marionette TRACE 402 <- [1,3,null,{}]
14:56:05 INFO - 1498859765397 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
14:56:05 INFO - 1498859765397 Marionette TRACE 402 <- [1,4,null,{}]
14:56:05 INFO - 1498859765399 Marionette TRACE 402 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
14:56:05 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498859765403 Marionette TRACE 402 <- [1,5,null,{"value":null}]
14:56:05 INFO - 1498859765404 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
14:56:05 INFO - 1498859765406 Marionette TRACE 402 <- [1,6,null,{}]
14:56:05 INFO - 1498859765407 Marionette DEBUG Closed connection 402
14:56:05 INFO - 1498859765408 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49984
14:56:05 INFO - 1498859765409 Marionette DEBUG Closed connection 403
14:56:05 INFO - 1498859765410 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49985
14:56:05 INFO - 1498859765411 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:56:05 INFO - 1498859765412 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765415 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765418 Marionette TRACE 404 <- [1,1,null,{"sessionId":"03b74787-0f8d-4184-b916-35325353a112","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765420 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
14:56:05 INFO - 1498859765421 Marionette TRACE 404 <- [1,2,null,{}]
14:56:05 INFO - 1498859765423 Marionette DEBUG Closed connection 404
14:56:05 INFO - 1498859765424 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49986
14:56:05 INFO - 1498859765425 Marionette DEBUG Closed connection 405
14:56:05 INFO - 1498859765426 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49987
14:56:05 INFO - 1498859765427 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:56:05 INFO - 1498859765428 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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}
14:56:05 INFO - 1498859765431 Marionette DEBUG Register listener.js for window 2147483649
14:56:05 INFO - 1498859765435 Marionette TRACE 406 <- [1,1,null,{"sessionId":"c6739911-58c4-4dab-a18a-8cd28c2fa52e","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":5696,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpe79i6b.mozrunner","moz:accessibilityChecks":false}}]
14:56:05 INFO - 1498859765436 Marionette TRACE 406 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
14:56:05 INFO - 1498859765438 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"
14:56:05 INFO - 1498859765441 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"
14:56:05 INFO - 1498859765442 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"
14:56:05 INFO - 1498859765451 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"
14:56:05 INFO - 1498859765453 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"
14:56:05 INFO - 1498859765460 Marionette TRACE 406 <- [1,2,null,{}]
14:56:05 INFO - 1498859765473 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:56:05 INFO - 1498859765477 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e2b1f827-17fe-4401-9de5-a7560e699535","ELEMENT":"e2b1f827-17fe-4401-9de5-a7560e699535"}}]
14:56:05 INFO - 1498859765478 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e2b1f827-17fe-4401-9de5-a7560e699535"}]
14:56:05 INFO - 1498859765503 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
14:56:05 INFO - 1498859765504 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"e2b1f827-17fe-4401-9de5-a7560e699535"}]
14:56:05 INFO - 1498859765524 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element