could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:17 INFO - 1498748057113 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}]
07:54:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498748057119 Marionette TRACE 358 <- [1,5,null,{"value":null}]
07:54:17 INFO - 1498748057120 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
07:54:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 229ms
07:54:17 INFO - 1498748057122 Marionette TRACE 358 <- [1,6,null,{}]
07:54:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:54:17 INFO - 1498748057124 Marionette DEBUG Closed connection 358
07:54:17 INFO - 1498748057125 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49922
07:54:17 INFO - 1498748057126 Marionette DEBUG Closed connection 359
07:54:17 INFO - 1498748057127 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49923
07:54:17 INFO - 1498748057128 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:17 INFO - 1498748057129 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057132 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057138 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5a06c142-d640-4d55-bc69-341a8c4ea1e0","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057139 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:54:17 INFO - 1498748057140 Marionette TRACE 360 <- [1,2,null,{}]
07:54:17 INFO - 1498748057141 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:17 INFO - 1498748057142 Marionette TRACE 360 <- [1,3,null,{}]
07:54:17 INFO - 1498748057143 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:17 INFO - 1498748057143 Marionette TRACE 360 <- [1,4,null,{}]
07:54:17 INFO - 1498748057145 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}]
07:54:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498748057149 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:54:17 INFO - 1498748057150 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:54:17 INFO - 1498748057151 Marionette TRACE 360 <- [1,6,null,{}]
07:54:17 INFO - 1498748057155 Marionette DEBUG Closed connection 360
07:54:17 INFO - 1498748057157 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49924
07:54:17 INFO - 1498748057162 Marionette DEBUG Closed connection 361
07:54:17 INFO - 1498748057163 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49925
07:54:17 INFO - 1498748057174 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:17 INFO - 1498748057176 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057179 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057184 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a2b46aad-ea1c-428f-b2c0-900bb7dd1893","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057187 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:54:17 INFO - 1498748057189 Marionette TRACE 362 <- [1,2,null,{}]
07:54:17 INFO - 1498748057192 Marionette DEBUG Closed connection 362
07:54:17 INFO - 1498748057193 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49926
07:54:17 INFO - 1498748057194 Marionette DEBUG Closed connection 363
07:54:17 INFO - 1498748057194 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49927
07:54:17 INFO - 1498748057196 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:17 INFO - 1498748057197 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057199 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057204 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1dcbd38e-ff33-4759-a57f-8cf3fb9f7374","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057205 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49523/clicks.html"}]
07:54:17 INFO - 1498748057207 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49523/hidden.html"
07:54:17 INFO - 1498748057214 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49523/hidden.html"
07:54:17 INFO - 1498748057214 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49523/hidden.html"
07:54:17 INFO - 1498748057239 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49523/clicks.html"
07:54:17 INFO - 1498748057255 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49523/clicks.html"
07:54:17 INFO - 1498748057269 Marionette TRACE 364 <- [1,2,null,{}]
07:54:17 INFO - 1498748057272 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:54:17 INFO - 1498748057276 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb66aed0-8284-4383-89bd-2b5d60fa8823","ELEMENT":"bb66aed0-8284-4383-89bd-2b5d60fa8823"}}]
07:54:17 INFO - 1498748057277 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"bb66aed0-8284-4383-89bd-2b5d60fa8823"}]
07:54:17 INFO - 1498748057320 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49523/clicks.html"
07:54:17 INFO - 1498748057330 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49523/clicks.html"
07:54:17 INFO - 1498748057331 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49523/clicks.html"
07:54:17 INFO - 1498748057379 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49523/test.html"
07:54:17 INFO - 1498748057381 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49523/test.html"
07:54:17 INFO - 1498748057391 Marionette TRACE 364 <- [1,4,null,{}]
07:54:17 INFO - 1498748057394 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:54:17 INFO - 1498748057397 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b8e91d5f-b2fb-4e96-a0c3-3c09387faad2","ELEMENT":"b8e91d5f-b2fb-4e96-a0c3-3c09387faad2"}}]
07:54:17 INFO - 1498748057401 Marionette TRACE 364 -> [0,6,"getTitle",{}]
07:54:17 INFO - 1498748057403 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
07:54:17 INFO - 1498748057405 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}]
07:54:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498748057408 Marionette TRACE 364 <- [1,7,null,{"value":null}]
07:54:17 INFO - 1498748057410 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
07:54:17 INFO - 1498748057411 Marionette TRACE 364 <- [1,8,null,{}]
07:54:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 289ms
07:54:17 INFO - TEST-START | test_click.py TestClick.test_container_element
07:54:17 INFO - 1498748057415 Marionette DEBUG Closed connection 364
07:54:17 INFO - 1498748057415 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49930
07:54:17 INFO - 1498748057447 Marionette DEBUG Closed connection 365
07:54:17 INFO - 1498748057447 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49931
07:54:17 INFO - 1498748057449 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:17 INFO - 1498748057450 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057453 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057457 Marionette TRACE 366 <- [1,1,null,{"sessionId":"661e1816-c09d-4239-b8a9-4e265245f3b4","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057459 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:54:17 INFO - 1498748057460 Marionette TRACE 366 <- [1,2,null,{}]
07:54:17 INFO - 1498748057461 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:17 INFO - 1498748057462 Marionette TRACE 366 <- [1,3,null,{}]
07:54:17 INFO - 1498748057468 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:17 INFO - 1498748057468 Marionette TRACE 366 <- [1,4,null,{}]
07:54:17 INFO - 1498748057470 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}]
07:54:17 INFO - TEST-START: test_click.py TestClick.test_container_element1498748057475 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:54:17 INFO - 1498748057476 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:54:17 INFO - 1498748057478 Marionette TRACE 366 <- [1,6,null,{}]
07:54:17 INFO - 1498748057480 Marionette DEBUG Closed connection 366
07:54:17 INFO - 1498748057481 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49932
07:54:17 INFO - 1498748057482 Marionette DEBUG Closed connection 367
07:54:17 INFO - 1498748057483 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49933
07:54:17 INFO - 1498748057485 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:17 INFO - 1498748057485 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057488 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057493 Marionette TRACE 368 <- [1,1,null,{"sessionId":"45f22de1-c609-463b-8dd5-1faa3bb32b55","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057496 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:54:17 INFO - 1498748057498 Marionette TRACE 368 <- [1,2,null,{}]
07:54:17 INFO - 1498748057550 Marionette DEBUG Closed connection 368
07:54:17 INFO - 1498748057551 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49934
07:54:17 INFO - 1498748057602 Marionette DEBUG Closed connection 369
07:54:17 INFO - 1498748057603 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49935
07:54:17 INFO - 1498748057615 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:17 INFO - 1498748057616 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057619 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057623 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9d00809f-387c-4b8f-8b53-89f5d92a3511","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057637 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"}]
07:54:17 INFO - 1498748057640 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49523/test.html"
07:54:17 INFO - 1498748057644 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49523/test.html"
07:54:17 INFO - 1498748057644 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49523/test.html"
07:54:17 INFO - 1498748057680 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"
07:54:17 INFO - 1498748057683 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"
07:54:17 INFO - 1498748057689 Marionette TRACE 370 <- [1,2,null,{}]
07:54:17 INFO - 1498748057699 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:54:17 INFO - 1498748057702 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd9e60d4-c4dc-4a6a-ac8f-41079ef0ec74","ELEMENT":"bd9e60d4-c4dc-4a6a-ac8f-41079ef0ec74"}}]
07:54:17 INFO - 1498748057703 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"bd9e60d4-c4dc-4a6a-ac8f-41079ef0ec74"}]
07:54:17 INFO - 1498748057952 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:17 INFO - 1498748057953 Marionette TRACE 370 <- [1,4,null,{}]
07:54:17 INFO - 1498748057954 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"bd9e60d4-c4dc-4a6a-ac8f-41079ef0ec74","name":"selected"}]
07:54:17 INFO - 1498748057959 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:54:17 INFO - 1498748057961 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}]
07:54:17 INFO - TEST-END: test_click.py TestClick.test_container_element1498748057965 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:54:17 INFO - 1498748057966 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:54:17 INFO - 1498748057968 Marionette TRACE 370 <- [1,7,null,{}]
07:54:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 556ms
07:54:17 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:54:17 INFO - 1498748057970 Marionette DEBUG Closed connection 370
07:54:17 INFO - 1498748057972 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49936
07:54:17 INFO - 1498748057972 Marionette DEBUG Closed connection 371
07:54:17 INFO - 1498748057973 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49937
07:54:17 INFO - 1498748057975 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:17 INFO - 1498748057976 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:17 INFO - 1498748057980 Marionette DEBUG Register listener.js for window 7
07:54:17 INFO - 1498748057985 Marionette TRACE 372 <- [1,1,null,{"sessionId":"18a9f1f1-1ed3-43f3-89fd-a3f139817403","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:17 INFO - 1498748057987 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:54:17 INFO - 1498748057987 Marionette TRACE 372 <- [1,2,null,{}]
07:54:17 INFO - 1498748057988 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:17 INFO - 1498748057989 Marionette TRACE 372 <- [1,3,null,{}]
07:54:17 INFO - 1498748057990 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:17 INFO - 1498748057991 Marionette TRACE 372 <- [1,4,null,{}]
07:54:17 INFO - 1498748057993 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}]
07:54:17 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498748057996 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:54:17 INFO - 1498748057997 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:54:18 INFO - 1498748057999 Marionette TRACE 372 <- [1,6,null,{}]
07:54:18 INFO - 1498748058001 Marionette DEBUG Closed connection 372
07:54:18 INFO - 1498748058002 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49938
07:54:18 INFO - 1498748058003 Marionette DEBUG Closed connection 373
07:54:18 INFO - 1498748058004 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49939
07:54:18 INFO - 1498748058005 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:18 INFO - 1498748058006 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058009 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058013 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a38ec3a1-e5c1-40d5-9dc9-e1ebf8e39bbe","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058022 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:54:18 INFO - 1498748058024 Marionette TRACE 374 <- [1,2,null,{}]
07:54:18 INFO - 1498748058026 Marionette DEBUG Closed connection 374
07:54:18 INFO - 1498748058027 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49940
07:54:18 INFO - 1498748058028 Marionette DEBUG Closed connection 375
07:54:18 INFO - 1498748058029 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49941
07:54:18 INFO - 1498748058030 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:18 INFO - 1498748058031 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058034 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058038 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8886ea67-cb58-4e9b-9ecc-633ffd48c9a5","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058039 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"}]
07:54:18 INFO - 1498748058041 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"
07:54:18 INFO - 1498748058044 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"
07:54:18 INFO - 1498748058044 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"
07:54:18 INFO - 1498748058067 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"
07:54:18 INFO - 1498748058069 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"
07:54:18 INFO - 1498748058075 Marionette TRACE 376 <- [1,2,null,{}]
07:54:18 INFO - 1498748058084 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:54:18 INFO - 1498748058088 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22d055af-db3e-42d6-aa8d-43b0ce669213","ELEMENT":"22d055af-db3e-42d6-aa8d-43b0ce669213"}}]
07:54:18 INFO - 1498748058089 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"22d055af-db3e-42d6-aa8d-43b0ce669213"}]
07:54:18 INFO - 1498748058337 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:18 INFO - 1498748058338 Marionette TRACE 376 <- [1,4,null,{}]
07:54:18 INFO - 1498748058339 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"22d055af-db3e-42d6-aa8d-43b0ce669213","name":"selected"}]
07:54:18 INFO - 1498748058344 Marionette TRACE 376 <- [1,5,null,{"value":true}]
07:54:18 INFO - 1498748058346 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}]
07:54:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498748058350 Marionette TRACE 376 <- [1,6,null,{"value":null}]
07:54:18 INFO - 1498748058351 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
07:54:18 INFO - 1498748058352 Marionette TRACE 376 <- [1,7,null,{}]
07:54:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 384ms
07:54:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:54:18 INFO - 1498748058355 Marionette DEBUG Closed connection 376
07:54:18 INFO - 1498748058356 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49942
07:54:18 INFO - 1498748058357 Marionette DEBUG Closed connection 377
07:54:18 INFO - 1498748058358 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49943
07:54:18 INFO - 1498748058359 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:18 INFO - 1498748058360 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058363 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058367 Marionette TRACE 378 <- [1,1,null,{"sessionId":"11f0b7b9-ca3d-480f-a388-c091aee1c2c1","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058368 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:54:18 INFO - 1498748058369 Marionette TRACE 378 <- [1,2,null,{}]
07:54:18 INFO - 1498748058370 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:18 INFO - 1498748058371 Marionette TRACE 378 <- [1,3,null,{}]
07:54:18 INFO - 1498748058371 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:18 INFO - 1498748058372 Marionette TRACE 378 <- [1,4,null,{}]
07:54:18 INFO - 1498748058374 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}]
07:54:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498748058377 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:54:18 INFO - 1498748058378 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:54:18 INFO - 1498748058380 Marionette TRACE 378 <- [1,6,null,{}]
07:54:18 INFO - 1498748058382 Marionette DEBUG Closed connection 378
07:54:18 INFO - 1498748058383 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49944
07:54:18 INFO - 1498748058384 Marionette DEBUG Closed connection 379
07:54:18 INFO - 1498748058385 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49945
07:54:18 INFO - 1498748058386 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:18 INFO - 1498748058387 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058389 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058393 Marionette TRACE 380 <- [1,1,null,{"sessionId":"984dd422-f430-4d8d-97a3-90cf14c386b3","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058395 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:54:18 INFO - 1498748058396 Marionette TRACE 380 <- [1,2,null,{}]
07:54:18 INFO - 1498748058399 Marionette DEBUG Closed connection 380
07:54:18 INFO - 1498748058399 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49946
07:54:18 INFO - 1498748058400 Marionette DEBUG Closed connection 381
07:54:18 INFO - 1498748058401 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49947
07:54:18 INFO - 1498748058402 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:18 INFO - 1498748058403 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058406 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058410 Marionette TRACE 382 <- [1,1,null,{"sessionId":"deffc902-0d4a-4cb4-81bd-1fc62953f1f5","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058412 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"}]
07:54:18 INFO - 1498748058414 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"
07:54:18 INFO - 1498748058417 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"
07:54:18 INFO - 1498748058417 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"
07:54:18 INFO - 1498748058441 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"
07:54:18 INFO - 1498748058445 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"
07:54:18 INFO - 1498748058458 Marionette TRACE 382 <- [1,2,null,{}]
07:54:18 INFO - 1498748058460 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:54:18 INFO - 1498748058464 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"833fa15d-4c99-4d49-84d0-14b077459dee","ELEMENT":"833fa15d-4c99-4d49-84d0-14b077459dee"}}]
07:54:18 INFO - 1498748058465 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"833fa15d-4c99-4d49-84d0-14b077459dee"}]
07:54:18 INFO - 1498748058707 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:18 INFO - 1498748058708 Marionette TRACE 382 <- [1,4,null,{}]
07:54:18 INFO - 1498748058711 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}]
07:54:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498748058715 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:54:18 INFO - 1498748058716 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:54:18 INFO - 1498748058718 Marionette TRACE 382 <- [1,6,null,{}]
07:54:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 365ms
07:54:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:54:18 INFO - 1498748058721 Marionette DEBUG Closed connection 382
07:54:18 INFO - 1498748058722 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49948
07:54:18 INFO - 1498748058723 Marionette DEBUG Closed connection 383
07:54:18 INFO - 1498748058724 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49949
07:54:18 INFO - 1498748058725 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:18 INFO - 1498748058726 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058728 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058732 Marionette TRACE 384 <- [1,1,null,{"sessionId":"91446193-3cf3-4819-8375-306004d57770","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058734 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:54:18 INFO - 1498748058734 Marionette TRACE 384 <- [1,2,null,{}]
07:54:18 INFO - 1498748058735 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:18 INFO - 1498748058736 Marionette TRACE 384 <- [1,3,null,{}]
07:54:18 INFO - 1498748058737 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:18 INFO - 1498748058737 Marionette TRACE 384 <- [1,4,null,{}]
07:54:18 INFO - 1498748058739 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}]
07:54:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498748058742 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:54:18 INFO - 1498748058743 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:54:18 INFO - 1498748058745 Marionette TRACE 384 <- [1,6,null,{}]
07:54:18 INFO - 1498748058748 Marionette DEBUG Closed connection 384
07:54:18 INFO - 1498748058749 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49950
07:54:18 INFO - 1498748058750 Marionette DEBUG Closed connection 385
07:54:18 INFO - 1498748058751 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49951
07:54:18 INFO - 1498748058753 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:18 INFO - 1498748058753 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058756 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058760 Marionette TRACE 386 <- [1,1,null,{"sessionId":"264ce263-aa90-4d32-ab74-93ba7a6ed0d6","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058761 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:54:18 INFO - 1498748058763 Marionette TRACE 386 <- [1,2,null,{}]
07:54:18 INFO - 1498748058765 Marionette DEBUG Closed connection 386
07:54:18 INFO - 1498748058766 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49952
07:54:18 INFO - 1498748058767 Marionette DEBUG Closed connection 387
07:54:18 INFO - 1498748058768 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49953
07:54:18 INFO - 1498748058769 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:18 INFO - 1498748058770 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:18 INFO - 1498748058772 Marionette DEBUG Register listener.js for window 7
07:54:18 INFO - 1498748058776 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1bff29cd-94aa-411c-b605-75119ef4724d","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:18 INFO - 1498748058778 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"}]
07:54:18 INFO - 1498748058780 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"
07:54:18 INFO - 1498748058783 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"
07:54:18 INFO - 1498748058784 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"
07:54:18 INFO - 1498748058804 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"
07:54:18 INFO - 1498748058809 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"
07:54:18 INFO - 1498748058821 Marionette TRACE 388 <- [1,2,null,{}]
07:54:18 INFO - 1498748058823 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:54:18 INFO - 1498748058834 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40c2596a-f5dc-4a03-8525-4e1ae42584e6","ELEMENT":"40c2596a-f5dc-4a03-8525-4e1ae42584e6"}}]
07:54:18 INFO - 1498748058836 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"40c2596a-f5dc-4a03-8525-4e1ae42584e6"}]
07:54:19 INFO - 1498748059081 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:19 INFO - 1498748059082 Marionette TRACE 388 <- [1,4,null,{}]
07:54:19 INFO - 1498748059085 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}]
07:54:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498748059088 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:54:19 INFO - 1498748059089 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:54:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 371ms
07:54:19 INFO - 1498748059091 Marionette TRACE 388 <- [1,6,null,{}]
07:54:19 INFO - TEST-START | test_click.py TestClick.test_input_file
07:54:19 INFO - 1498748059093 Marionette DEBUG Closed connection 388
07:54:19 INFO - 1498748059095 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49954
07:54:19 INFO - 1498748059095 Marionette DEBUG Closed connection 389
07:54:19 INFO - 1498748059096 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49955
07:54:19 INFO - 1498748059097 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059098 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059101 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059105 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f65497ca-4241-4eb4-8f5a-efa7f1f2fdcc","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059106 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:54:19 INFO - 1498748059107 Marionette TRACE 390 <- [1,2,null,{}]
07:54:19 INFO - 1498748059108 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:19 INFO - 1498748059108 Marionette TRACE 390 <- [1,3,null,{}]
07:54:19 INFO - 1498748059109 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:19 INFO - 1498748059110 Marionette TRACE 390 <- [1,4,null,{}]
07:54:19 INFO - 1498748059112 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}]
07:54:19 INFO - TEST-START: test_click.py TestClick.test_input_file1498748059115 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:54:19 INFO - 1498748059116 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:54:19 INFO - 1498748059117 Marionette TRACE 390 <- [1,6,null,{}]
07:54:19 INFO - 1498748059120 Marionette DEBUG Closed connection 390
07:54:19 INFO - 1498748059121 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49956
07:54:19 INFO - 1498748059121 Marionette DEBUG Closed connection 391
07:54:19 INFO - 1498748059122 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49957
07:54:19 INFO - 1498748059125 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059126 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059128 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059132 Marionette TRACE 392 <- [1,1,null,{"sessionId":"29332b7f-4664-4589-8a09-9aed48658b6d","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059134 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:54:19 INFO - 1498748059136 Marionette TRACE 392 <- [1,2,null,{}]
07:54:19 INFO - 1498748059138 Marionette DEBUG Closed connection 392
07:54:19 INFO - 1498748059139 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49958
07:54:19 INFO - 1498748059140 Marionette DEBUG Closed connection 393
07:54:19 INFO - 1498748059141 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49959
07:54:19 INFO - 1498748059142 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:19 INFO - 1498748059143 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059146 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059150 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e94b24e5-eacd-4d73-ab98-b461d0b6c377","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059151 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:54:19 INFO - 1498748059153 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"
07:54:19 INFO - 1498748059156 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"
07:54:19 INFO - 1498748059157 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"
07:54:19 INFO - 1498748059179 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:19 INFO - 1498748059184 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:19 INFO - 1498748059189 Marionette TRACE 394 <- [1,2,null,{}]
07:54:19 INFO - 1498748059198 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:54:19 INFO - 1498748059202 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1dc0f6c9-0928-4017-82a1-9809d0fd7c19","ELEMENT":"1dc0f6c9-0928-4017-82a1-9809d0fd7c19"}}]
07:54:19 INFO - 1498748059204 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"1dc0f6c9-0928-4017-82a1-9809d0fd7c19"}]
07:54:19 INFO - 1498748059234 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:19 INFO - 1498748059236 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}]
07:54:19 INFO - TEST-END: test_click.py TestClick.test_input_file1498748059240 Marionette TRACE 394 <- [1,5,null,{"value":null}]
07:54:19 INFO - 1498748059241 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
07:54:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 151ms
07:54:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:54:19 INFO - 1498748059242 Marionette TRACE 394 <- [1,6,null,{}]
07:54:19 INFO - 1498748059245 Marionette DEBUG Closed connection 394
07:54:19 INFO - 1498748059246 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49960
07:54:19 INFO - 1498748059247 Marionette DEBUG Closed connection 395
07:54:19 INFO - 1498748059248 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49961
07:54:19 INFO - 1498748059249 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059250 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059253 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059257 Marionette TRACE 396 <- [1,1,null,{"sessionId":"26eef11a-8415-49f8-a29f-26761144b02f","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059258 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:54:19 INFO - 1498748059259 Marionette TRACE 396 <- [1,2,null,{}]
07:54:19 INFO - 1498748059259 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:19 INFO - 1498748059260 Marionette TRACE 396 <- [1,3,null,{}]
07:54:19 INFO - 1498748059261 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:19 INFO - 1498748059262 Marionette TRACE 396 <- [1,4,null,{}]
07:54:19 INFO - 1498748059264 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}]
07:54:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498748059267 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:54:19 INFO - 1498748059268 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:54:19 INFO - 1498748059270 Marionette TRACE 396 <- [1,6,null,{}]
07:54:19 INFO - 1498748059272 Marionette DEBUG Closed connection 396
07:54:19 INFO - 1498748059273 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49962
07:54:19 INFO - 1498748059274 Marionette DEBUG Closed connection 397
07:54:19 INFO - 1498748059275 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49963
07:54:19 INFO - 1498748059276 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059277 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059279 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059283 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2576980f-0fdc-423d-9417-f14f0f8c48b9","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059285 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:54:19 INFO - 1498748059286 Marionette TRACE 398 <- [1,2,null,{}]
07:54:19 INFO - 1498748059288 Marionette DEBUG Closed connection 398
07:54:19 INFO - 1498748059289 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49964
07:54:19 INFO - 1498748059290 Marionette DEBUG Closed connection 399
07:54:19 INFO - 1498748059291 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49965
07:54:19 INFO - 1498748059292 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:19 INFO - 1498748059293 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059295 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059299 Marionette TRACE 400 <- [1,1,null,{"sessionId":"bd704307-f103-4eb6-93f7-13d07abc4d57","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059301 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"}]
07:54:19 INFO - 1498748059302 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:19 INFO - 1498748059307 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:19 INFO - 1498748059307 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:54:19 INFO - 1498748059348 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"
07:54:19 INFO - 1498748059353 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"
07:54:19 INFO - 1498748059358 Marionette TRACE 400 <- [1,2,null,{}]
07:54:19 INFO - 1498748059370 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:54:19 INFO - 1498748059377 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1ca7a43-a29f-4cd2-ab03-202e829d0aa4","ELEMENT":"f1ca7a43-a29f-4cd2-ab03-202e829d0aa4"}}]
07:54:19 INFO - 1498748059378 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:54:19 INFO - 1498748059381 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8aab1516-7857-4b5d-9c61-df9975b6989f","ELEMENT":"8aab1516-7857-4b5d-9c61-df9975b6989f"}}]
07:54:19 INFO - 1498748059382 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f1ca7a43-a29f-4cd2-ab03-202e829d0aa4"}]
07:54:19 INFO - 1498748059630 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:54:19 INFO - 1498748059631 Marionette TRACE 400 <- [1,5,null,{}]
07:54:19 INFO - 1498748059633 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"8aab1516-7857-4b5d-9c61-df9975b6989f"}]
07:54:19 INFO - 1498748059652 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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
07:54:19 INFO - 1498748059654 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
07:54:19 INFO - 1498748059658 Marionette TRACE 400 <- [1,7,null,{"value":false}]
07:54:19 INFO - 1498748059660 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}]
07:54:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498748059664 Marionette TRACE 400 <- [1,8,null,{"value":null}]
07:54:19 INFO - 1498748059665 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
07:54:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 424ms
07:54:19 INFO - 1498748059667 Marionette TRACE 400 <- [1,9,null,{}]
07:54:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:54:19 INFO - 1498748059669 Marionette DEBUG Closed connection 400
07:54:19 INFO - 1498748059670 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49966
07:54:19 INFO - 1498748059671 Marionette DEBUG Closed connection 401
07:54:19 INFO - 1498748059672 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49967
07:54:19 INFO - 1498748059674 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059675 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059678 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059682 Marionette TRACE 402 <- [1,1,null,{"sessionId":"317e0201-f398-4c52-9418-822f20523000","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059683 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
07:54:19 INFO - 1498748059684 Marionette TRACE 402 <- [1,2,null,{}]
07:54:19 INFO - 1498748059685 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:54:19 INFO - 1498748059686 Marionette TRACE 402 <- [1,3,null,{}]
07:54:19 INFO - 1498748059687 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
07:54:19 INFO - 1498748059687 Marionette TRACE 402 <- [1,4,null,{}]
07:54:19 INFO - 1498748059689 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}]
07:54:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498748059692 Marionette TRACE 402 <- [1,5,null,{"value":null}]
07:54:19 INFO - 1498748059693 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
07:54:19 INFO - 1498748059695 Marionette TRACE 402 <- [1,6,null,{}]
07:54:19 INFO - 1498748059697 Marionette DEBUG Closed connection 402
07:54:19 INFO - 1498748059698 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49968
07:54:19 INFO - 1498748059699 Marionette DEBUG Closed connection 403
07:54:19 INFO - 1498748059700 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49969
07:54:19 INFO - 1498748059701 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:54:19 INFO - 1498748059702 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059705 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059709 Marionette TRACE 404 <- [1,1,null,{"sessionId":"82099cb0-9b20-4582-b0fd-1e3c034cf1dd","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059710 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
07:54:19 INFO - 1498748059711 Marionette TRACE 404 <- [1,2,null,{}]
07:54:19 INFO - 1498748059714 Marionette DEBUG Closed connection 404
07:54:19 INFO - 1498748059715 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49970
07:54:19 INFO - 1498748059715 Marionette DEBUG Closed connection 405
07:54:19 INFO - 1498748059716 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49971
07:54:19 INFO - 1498748059717 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:54:19 INFO - 1498748059718 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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}
07:54:19 INFO - 1498748059721 Marionette DEBUG Register listener.js for window 7
07:54:19 INFO - 1498748059724 Marionette TRACE 406 <- [1,1,null,{"sessionId":"ce66313d-25d9-4b37-be9c-f924fcc9b25b","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":3748,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpypminv.mozrunner","moz:accessibilityChecks":false}}]
07:54:19 INFO - 1498748059726 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"}]
07:54:19 INFO - 1498748059728 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"
07:54:19 INFO - 1498748059731 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"
07:54:19 INFO - 1498748059731 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"
07:54:19 INFO - 1498748059755 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"
07:54:19 INFO - 1498748059757 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"
07:54:19 INFO - 1498748059769 Marionette TRACE 406 <- [1,2,null,{}]
07:54:19 INFO - 1498748059770 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:54:19 INFO - 1498748059773 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2766cfba-459b-4960-8f61-7e8797d0a91a","ELEMENT":"2766cfba-459b-4960-8f61-7e8797d0a91a"}}]
07:54:19 INFO - 1498748059775 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2766cfba-459b-4960-8f61-7e8797d0a91a"}]
07:54:19 INFO - 1498748059805 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
07:54:19 INFO - 1498748059807 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"2766cfba-459b-4960-8f61-7e8797d0a91a"}]
07:54:19 INFO - 1498748059832 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element