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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:41 INFO - 1500559601207 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
07:06:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500559601209 Marionette TRACE 352 <- [1,5,null,{"value":null}]
07:06:41 INFO - 1500559601217 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
07:06:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 132ms
07:06:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:06:41 INFO - 1500559601219 Marionette TRACE 352 <- [1,6,null,{}]
07:06:41 INFO - 1500559601221 Marionette DEBUG Closed connection 352
07:06:41 INFO - 1500559601222 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49749
07:06:41 INFO - 1500559601222 Marionette DEBUG Closed connection 353
07:06:41 INFO - 1500559601223 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49750
07:06:41 INFO - 1500559601224 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601224 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601226 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601229 Marionette TRACE 354 <- [1,1,null,{"sessionId":"04e8b6e4-a725-466c-9df2-15aaae8c82c3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601231 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
07:06:41 INFO - 1500559601231 Marionette TRACE 354 <- [1,2,null,{}]
07:06:41 INFO - 1500559601232 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:41 INFO - 1500559601233 Marionette TRACE 354 <- [1,3,null,{}]
07:06:41 INFO - 1500559601233 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:41 INFO - 1500559601234 Marionette TRACE 354 <- [1,4,null,{}]
07:06:41 INFO - 1500559601235 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
07:06:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500559601237 Marionette TRACE 354 <- [1,5,null,{"value":null}]
07:06:41 INFO - 1500559601238 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
07:06:41 INFO - 1500559601239 Marionette TRACE 354 <- [1,6,null,{}]
07:06:41 INFO - 1500559601241 Marionette DEBUG Closed connection 354
07:06:41 INFO - 1500559601242 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49751
07:06:41 INFO - 1500559601242 Marionette DEBUG Closed connection 355
07:06:41 INFO - 1500559601243 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49752
07:06:41 INFO - 1500559601244 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601244 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601247 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601250 Marionette TRACE 356 <- [1,1,null,{"sessionId":"26f4c7bd-5885-4f2a-8023-03eeca6f8c16","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601251 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
07:06:41 INFO - 1500559601252 Marionette TRACE 356 <- [1,2,null,{}]
07:06:41 INFO - 1500559601254 Marionette DEBUG Closed connection 356
07:06:41 INFO - 1500559601255 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49753
07:06:41 INFO - 1500559601255 Marionette DEBUG Closed connection 357
07:06:41 INFO - 1500559601256 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49754
07:06:41 INFO - 1500559601257 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:41 INFO - 1500559601257 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601259 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601262 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e7dc51ae-bdf6-44fe-b840-762f1cb7c353","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601264 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49322/clicks.html"}]
07:06:41 INFO - 1500559601265 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49322/hidden.html"
07:06:41 INFO - 1500559601283 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49322/hidden.html"
07:06:41 INFO - 1500559601283 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49322/hidden.html"
07:06:41 INFO - 1500559601297 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49322/clicks.html"
07:06:41 INFO - 1500559601301 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49322/clicks.html"
07:06:41 INFO - 1500559601304 Marionette TRACE 358 <- [1,2,null,{}]
07:06:41 INFO - 1500559601311 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:06:41 INFO - 1500559601313 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"763181ee-cfc3-4c67-b533-50eac6e89de5","ELEMENT":"763181ee-cfc3-4c67-b533-50eac6e89de5"}}]
07:06:41 INFO - 1500559601314 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"763181ee-cfc3-4c67-b533-50eac6e89de5"}]
07:06:41 INFO - 1500559601361 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49322/clicks.html"
07:06:41 INFO - 1500559601365 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49322/clicks.html"
07:06:41 INFO - 1500559601365 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49322/clicks.html"
07:06:41 INFO - 1500559601382 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49322/test.html"
07:06:41 INFO - 1500559601384 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49322/test.html"
07:06:41 INFO - 1500559601387 Marionette TRACE 358 <- [1,4,null,{}]
07:06:41 INFO - 1500559601393 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:06:41 INFO - 1500559601395 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"df038ff8-edce-40eb-91da-9e7706c2ee57","ELEMENT":"df038ff8-edce-40eb-91da-9e7706c2ee57"}}]
07:06:41 INFO - 1500559601396 Marionette TRACE 358 -> [0,6,"getTitle",{}]
07:06:41 INFO - 1500559601396 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
07:06:41 INFO - 1500559601397 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
07:06:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500559601399 Marionette TRACE 358 <- [1,7,null,{"value":null}]
07:06:41 INFO - 1500559601400 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
07:06:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 182ms
07:06:41 INFO - 1500559601401 Marionette TRACE 358 <- [1,8,null,{}]
07:06:41 INFO - TEST-START | test_click.py TestClick.test_container_element
07:06:41 INFO - 1500559601403 Marionette DEBUG Closed connection 358
07:06:41 INFO - 1500559601404 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49757
07:06:41 INFO - 1500559601405 Marionette DEBUG Closed connection 359
07:06:41 INFO - 1500559601406 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49758
07:06:41 INFO - 1500559601407 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601407 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601409 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601412 Marionette TRACE 360 <- [1,1,null,{"sessionId":"59684fa7-525c-4044-9499-6aaa192dba59","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601414 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:06:41 INFO - 1500559601414 Marionette TRACE 360 <- [1,2,null,{}]
07:06:41 INFO - 1500559601416 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:41 INFO - 1500559601416 Marionette TRACE 360 <- [1,3,null,{}]
07:06:41 INFO - 1500559601417 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:41 INFO - 1500559601417 Marionette TRACE 360 <- [1,4,null,{}]
07:06:41 INFO - 1500559601421 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
07:06:41 INFO - TEST-START: test_click.py TestClick.test_container_element1500559601424 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:06:41 INFO - 1500559601425 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:06:41 INFO - 1500559601426 Marionette TRACE 360 <- [1,6,null,{}]
07:06:41 INFO - 1500559601428 Marionette DEBUG Closed connection 360
07:06:41 INFO - 1500559601428 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49759
07:06:41 INFO - 1500559601429 Marionette DEBUG Closed connection 361
07:06:41 INFO - 1500559601430 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49760
07:06:41 INFO - 1500559601431 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601431 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601433 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601436 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a9b6ec29-988c-45db-908f-d13a9f1570a4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601439 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:06:41 INFO - 1500559601440 Marionette TRACE 362 <- [1,2,null,{}]
07:06:41 INFO - 1500559601441 Marionette DEBUG Closed connection 362
07:06:41 INFO - 1500559601442 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49761
07:06:41 INFO - 1500559601443 Marionette DEBUG Closed connection 363
07:06:41 INFO - 1500559601443 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49762
07:06:41 INFO - 1500559601444 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:41 INFO - 1500559601445 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601447 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601449 Marionette TRACE 364 <- [1,1,null,{"sessionId":"b4450d3f-bb3b-43c8-9006-b842493b5e7b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601451 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
07:06:41 INFO - 1500559601453 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49322/test.html"
07:06:41 INFO - 1500559601455 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49322/test.html"
07:06:41 INFO - 1500559601455 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49322/test.html"
07:06:41 INFO - 1500559601468 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:06:41 INFO - 1500559601479 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:06:41 INFO - 1500559601481 Marionette TRACE 364 <- [1,2,null,{}]
07:06:41 INFO - 1500559601482 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:06:41 INFO - 1500559601484 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c3e1b05-1c06-4d3c-8629-5a65ada55526","ELEMENT":"6c3e1b05-1c06-4d3c-8629-5a65ada55526"}}]
07:06:41 INFO - 1500559601491 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6c3e1b05-1c06-4d3c-8629-5a65ada55526"}]
07:06:41 INFO - 1500559601730 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:41 INFO - 1500559601730 Marionette TRACE 364 <- [1,4,null,{}]
07:06:41 INFO - 1500559601732 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6c3e1b05-1c06-4d3c-8629-5a65ada55526","name":"selected"}]
07:06:41 INFO - 1500559601735 Marionette TRACE 364 <- [1,5,null,{"value":true}]
07:06:41 INFO - 1500559601737 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
07:06:41 INFO - TEST-END: test_click.py TestClick.test_container_element1500559601739 Marionette TRACE 364 <- [1,6,null,{"value":null}]
07:06:41 INFO - 1500559601740 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
07:06:41 INFO - 1500559601741 Marionette TRACE 364 <- [1,7,null,{}]
07:06:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 339ms
07:06:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:06:41 INFO - 1500559601743 Marionette DEBUG Closed connection 364
07:06:41 INFO - 1500559601744 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49763
07:06:41 INFO - 1500559601745 Marionette DEBUG Closed connection 365
07:06:41 INFO - 1500559601746 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49764
07:06:41 INFO - 1500559601747 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601747 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601749 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601752 Marionette TRACE 366 <- [1,1,null,{"sessionId":"50e16e53-01a0-4d7e-a497-c681619da51d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601754 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:06:41 INFO - 1500559601754 Marionette TRACE 366 <- [1,2,null,{}]
07:06:41 INFO - 1500559601755 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:41 INFO - 1500559601755 Marionette TRACE 366 <- [1,3,null,{}]
07:06:41 INFO - 1500559601756 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:41 INFO - 1500559601757 Marionette TRACE 366 <- [1,4,null,{}]
07:06:41 INFO - 1500559601759 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
07:06:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500559601761 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:06:41 INFO - 1500559601762 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:06:41 INFO - 1500559601763 Marionette TRACE 366 <- [1,6,null,{}]
07:06:41 INFO - 1500559601764 Marionette DEBUG Closed connection 366
07:06:41 INFO - 1500559601765 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49765
07:06:41 INFO - 1500559601766 Marionette DEBUG Closed connection 367
07:06:41 INFO - 1500559601766 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49766
07:06:41 INFO - 1500559601767 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:41 INFO - 1500559601768 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601770 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601780 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c0648820-accc-438d-ba5d-0871aca25d0a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601782 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:06:41 INFO - 1500559601783 Marionette TRACE 368 <- [1,2,null,{}]
07:06:41 INFO - 1500559601785 Marionette DEBUG Closed connection 368
07:06:41 INFO - 1500559601786 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49767
07:06:41 INFO - 1500559601786 Marionette DEBUG Closed connection 369
07:06:41 INFO - 1500559601787 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49768
07:06:41 INFO - 1500559601788 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:41 INFO - 1500559601788 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:41 INFO - 1500559601790 Marionette DEBUG Register listener.js for window 15
07:06:41 INFO - 1500559601793 Marionette TRACE 370 <- [1,1,null,{"sessionId":"f170d9cd-77e9-43b7-9b5a-b8542e2a504d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:41 INFO - 1500559601795 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
07:06:41 INFO - 1500559601796 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:06:41 INFO - 1500559601797 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:06:41 INFO - 1500559601798 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:06:41 INFO - 1500559601813 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:06:41 INFO - 1500559601816 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:06:41 INFO - 1500559601818 Marionette TRACE 370 <- [1,2,null,{}]
07:06:41 INFO - 1500559601825 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:06:41 INFO - 1500559601827 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"13ae55ee-f33e-49fc-b9db-a360ff9ffe6e","ELEMENT":"13ae55ee-f33e-49fc-b9db-a360ff9ffe6e"}}]
07:06:41 INFO - 1500559601828 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"13ae55ee-f33e-49fc-b9db-a360ff9ffe6e"}]
07:06:42 INFO - 1500559602066 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:42 INFO - 1500559602066 Marionette TRACE 370 <- [1,4,null,{}]
07:06:42 INFO - 1500559602068 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"13ae55ee-f33e-49fc-b9db-a360ff9ffe6e","name":"selected"}]
07:06:42 INFO - 1500559602072 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:06:42 INFO - 1500559602074 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
07:06:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500559602076 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:06:42 INFO - 1500559602076 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:06:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 336ms
07:06:42 INFO - 1500559602078 Marionette TRACE 370 <- [1,7,null,{}]
07:06:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:06:42 INFO - 1500559602079 Marionette DEBUG Closed connection 370
07:06:42 INFO - 1500559602081 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49769
07:06:42 INFO - 1500559602082 Marionette DEBUG Closed connection 371
07:06:42 INFO - 1500559602083 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49770
07:06:42 INFO - 1500559602084 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602084 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602086 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602089 Marionette TRACE 372 <- [1,1,null,{"sessionId":"34f6c793-184c-45ff-aca7-dd52a1763c75","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602090 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:06:42 INFO - 1500559602091 Marionette TRACE 372 <- [1,2,null,{}]
07:06:42 INFO - 1500559602092 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:42 INFO - 1500559602093 Marionette TRACE 372 <- [1,3,null,{}]
07:06:42 INFO - 1500559602100 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:42 INFO - 1500559602101 Marionette TRACE 372 <- [1,4,null,{}]
07:06:42 INFO - 1500559602102 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
07:06:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500559602104 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602105 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602106 Marionette TRACE 372 <- [1,6,null,{}]
07:06:42 INFO - 1500559602108 Marionette DEBUG Closed connection 372
07:06:42 INFO - 1500559602109 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49771
07:06:42 INFO - 1500559602109 Marionette DEBUG Closed connection 373
07:06:42 INFO - 1500559602110 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49772
07:06:42 INFO - 1500559602111 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602111 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602113 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602116 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2658e0ec-131e-4082-a2d3-9355df4dddbf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602118 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:06:42 INFO - 1500559602119 Marionette TRACE 374 <- [1,2,null,{}]
07:06:42 INFO - 1500559602120 Marionette DEBUG Closed connection 374
07:06:42 INFO - 1500559602121 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49773
07:06:42 INFO - 1500559602121 Marionette DEBUG Closed connection 375
07:06:42 INFO - 1500559602122 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49774
07:06:42 INFO - 1500559602123 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:42 INFO - 1500559602123 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602125 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602128 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e21fc935-9c46-468e-bede-adbbf93e7169","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602129 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
07:06:42 INFO - 1500559602131 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:06:42 INFO - 1500559602132 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:06:42 INFO - 1500559602132 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:06:42 INFO - 1500559602148 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:06:42 INFO - 1500559602150 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:06:42 INFO - 1500559602153 Marionette TRACE 376 <- [1,2,null,{}]
07:06:42 INFO - 1500559602159 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:06:42 INFO - 1500559602161 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"341f16ad-d1e0-42ee-9e1c-f21976a2afae","ELEMENT":"341f16ad-d1e0-42ee-9e1c-f21976a2afae"}}]
07:06:42 INFO - 1500559602162 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"341f16ad-d1e0-42ee-9e1c-f21976a2afae"}]
07:06:42 INFO - 1500559602402 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:42 INFO - 1500559602402 Marionette TRACE 376 <- [1,4,null,{}]
07:06:42 INFO - 1500559602404 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
07:06:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500559602406 Marionette TRACE 376 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602407 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
07:06:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 329ms
07:06:42 INFO - 1500559602408 Marionette TRACE 376 <- [1,6,null,{}]
07:06:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:06:42 INFO - 1500559602410 Marionette DEBUG Closed connection 376
07:06:42 INFO - 1500559602411 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49775
07:06:42 INFO - 1500559602412 Marionette DEBUG Closed connection 377
07:06:42 INFO - 1500559602412 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49776
07:06:42 INFO - 1500559602413 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602414 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602416 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602418 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7095dc9f-5903-4abd-95a9-75f20a9425ad","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602420 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:06:42 INFO - 1500559602420 Marionette TRACE 378 <- [1,2,null,{}]
07:06:42 INFO - 1500559602421 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:42 INFO - 1500559602422 Marionette TRACE 378 <- [1,3,null,{}]
07:06:42 INFO - 1500559602423 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:42 INFO - 1500559602423 Marionette TRACE 378 <- [1,4,null,{}]
07:06:42 INFO - 1500559602425 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
07:06:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500559602427 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602428 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602429 Marionette TRACE 378 <- [1,6,null,{}]
07:06:42 INFO - 1500559602431 Marionette DEBUG Closed connection 378
07:06:42 INFO - 1500559602431 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49777
07:06:42 INFO - 1500559602432 Marionette DEBUG Closed connection 379
07:06:42 INFO - 1500559602433 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49778
07:06:42 INFO - 1500559602434 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602434 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602436 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602438 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ccb5a00a-f488-4210-9cc9-0af2dbdd4b35","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602440 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:06:42 INFO - 1500559602441 Marionette TRACE 380 <- [1,2,null,{}]
07:06:42 INFO - 1500559602443 Marionette DEBUG Closed connection 380
07:06:42 INFO - 1500559602443 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49779
07:06:42 INFO - 1500559602444 Marionette DEBUG Closed connection 381
07:06:42 INFO - 1500559602445 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49780
07:06:42 INFO - 1500559602446 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:42 INFO - 1500559602446 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602448 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602451 Marionette TRACE 382 <- [1,1,null,{"sessionId":"780c7eda-ac51-44b0-bde2-085437be3768","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602452 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
07:06:42 INFO - 1500559602454 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:06:42 INFO - 1500559602455 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:06:42 INFO - 1500559602455 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:06:42 INFO - 1500559602464 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:06:42 INFO - 1500559602467 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:06:42 INFO - 1500559602477 Marionette TRACE 382 <- [1,2,null,{}]
07:06:42 INFO - 1500559602479 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:06:42 INFO - 1500559602481 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"139a3e5f-d610-486d-9dcc-d62f0a3f2600","ELEMENT":"139a3e5f-d610-486d-9dcc-d62f0a3f2600"}}]
07:06:42 INFO - 1500559602482 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"139a3e5f-d610-486d-9dcc-d62f0a3f2600"}]
07:06:42 INFO - 1500559602719 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:42 INFO - 1500559602719 Marionette TRACE 382 <- [1,4,null,{}]
07:06:42 INFO - 1500559602721 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
07:06:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500559602723 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602724 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602725 Marionette TRACE 382 <- [1,6,null,{}]
07:06:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
07:06:42 INFO - TEST-START | test_click.py TestClick.test_input_file
07:06:42 INFO - 1500559602726 Marionette DEBUG Closed connection 382
07:06:42 INFO - 1500559602727 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49781
07:06:42 INFO - 1500559602728 Marionette DEBUG Closed connection 383
07:06:42 INFO - 1500559602729 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49782
07:06:42 INFO - 1500559602730 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602730 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602732 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602735 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ee3d5804-4cda-436b-907a-76e2b6fde446","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602736 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:06:42 INFO - 1500559602737 Marionette TRACE 384 <- [1,2,null,{}]
07:06:42 INFO - 1500559602738 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:42 INFO - 1500559602738 Marionette TRACE 384 <- [1,3,null,{}]
07:06:42 INFO - 1500559602739 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:42 INFO - 1500559602739 Marionette TRACE 384 <- [1,4,null,{}]
07:06:42 INFO - 1500559602741 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
07:06:42 INFO - TEST-START: test_click.py TestClick.test_input_file1500559602743 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602743 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602745 Marionette TRACE 384 <- [1,6,null,{}]
07:06:42 INFO - 1500559602746 Marionette DEBUG Closed connection 384
07:06:42 INFO - 1500559602747 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49783
07:06:42 INFO - 1500559602747 Marionette DEBUG Closed connection 385
07:06:42 INFO - 1500559602748 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49784
07:06:42 INFO - 1500559602749 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602750 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602752 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602754 Marionette TRACE 386 <- [1,1,null,{"sessionId":"25868b2b-8f55-45d6-acb1-6147695eff44","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602756 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:06:42 INFO - 1500559602757 Marionette TRACE 386 <- [1,2,null,{}]
07:06:42 INFO - 1500559602758 Marionette DEBUG Closed connection 386
07:06:42 INFO - 1500559602759 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49785
07:06:42 INFO - 1500559602760 Marionette DEBUG Closed connection 387
07:06:42 INFO - 1500559602760 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49786
07:06:42 INFO - 1500559602761 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:42 INFO - 1500559602762 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602770 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602773 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b59bfcd8-b9ff-40b6-9ff8-4fbd8699e2b9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602775 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:06:42 INFO - 1500559602776 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:06:42 INFO - 1500559602777 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:06:42 INFO - 1500559602778 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:06:42 INFO - 1500559602795 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:42 INFO - 1500559602797 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:42 INFO - 1500559602799 Marionette TRACE 388 <- [1,2,null,{}]
07:06:42 INFO - 1500559602800 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:06:42 INFO - 1500559602808 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d069f7f9-c5fb-4cc2-8bb9-94da10a82ae3","ELEMENT":"d069f7f9-c5fb-4cc2-8bb9-94da10a82ae3"}}]
07:06:42 INFO - 1500559602809 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d069f7f9-c5fb-4cc2-8bb9-94da10a82ae3"}]
07:06:42 INFO - 1500559602834 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:42 INFO - 1500559602836 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
07:06:42 INFO - TEST-END: test_click.py TestClick.test_input_file1500559602838 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602839 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602840 Marionette TRACE 388 <- [1,6,null,{}]
07:06:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
07:06:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:06:42 INFO - 1500559602842 Marionette DEBUG Closed connection 388
07:06:42 INFO - 1500559602843 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49787
07:06:42 INFO - 1500559602844 Marionette DEBUG Closed connection 389
07:06:42 INFO - 1500559602845 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49788
07:06:42 INFO - 1500559602845 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602846 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602848 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602850 Marionette TRACE 390 <- [1,1,null,{"sessionId":"43437899-77ea-4ee5-893d-3b1b98e818e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602852 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:06:42 INFO - 1500559602853 Marionette TRACE 390 <- [1,2,null,{}]
07:06:42 INFO - 1500559602853 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:42 INFO - 1500559602854 Marionette TRACE 390 <- [1,3,null,{}]
07:06:42 INFO - 1500559602855 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:42 INFO - 1500559602855 Marionette TRACE 390 <- [1,4,null,{}]
07:06:42 INFO - 1500559602857 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
07:06:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500559602859 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:06:42 INFO - 1500559602859 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:06:42 INFO - 1500559602861 Marionette TRACE 390 <- [1,6,null,{}]
07:06:42 INFO - 1500559602862 Marionette DEBUG Closed connection 390
07:06:42 INFO - 1500559602863 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49789
07:06:42 INFO - 1500559602863 Marionette DEBUG Closed connection 391
07:06:42 INFO - 1500559602864 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49790
07:06:42 INFO - 1500559602865 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:42 INFO - 1500559602865 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602867 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602870 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d531964d-343b-4077-ae5d-58823c3711bc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602872 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:06:42 INFO - 1500559602873 Marionette TRACE 392 <- [1,2,null,{}]
07:06:42 INFO - 1500559602874 Marionette DEBUG Closed connection 392
07:06:42 INFO - 1500559602875 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49791
07:06:42 INFO - 1500559602875 Marionette DEBUG Closed connection 393
07:06:42 INFO - 1500559602876 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49792
07:06:42 INFO - 1500559602877 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:42 INFO - 1500559602878 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:42 INFO - 1500559602880 Marionette DEBUG Register listener.js for window 15
07:06:42 INFO - 1500559602882 Marionette TRACE 394 <- [1,1,null,{"sessionId":"14d402f4-a453-4919-b622-e8847f333eb2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:42 INFO - 1500559602884 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
07:06:42 INFO - 1500559602885 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:42 INFO - 1500559602887 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:42 INFO - 1500559602887 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:42 INFO - 1500559602896 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:06:42 INFO - 1500559602899 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:06:42 INFO - 1500559602902 Marionette TRACE 394 <- [1,2,null,{}]
07:06:42 INFO - 1500559602909 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:06:42 INFO - 1500559602911 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db106393-96c1-434c-8f67-37f8db2c6bf5","ELEMENT":"db106393-96c1-434c-8f67-37f8db2c6bf5"}}]
07:06:42 INFO - 1500559602912 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:06:42 INFO - 1500559602914 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"78758ec1-eada-472e-8163-65b53008a515","ELEMENT":"78758ec1-eada-472e-8163-65b53008a515"}}]
07:06:42 INFO - 1500559602915 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"db106393-96c1-434c-8f67-37f8db2c6bf5"}]
07:06:43 INFO - 1500559603155 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:43 INFO - 1500559603155 Marionette TRACE 394 <- [1,5,null,{}]
07:06:43 INFO - 1500559603156 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"78758ec1-eada-472e-8163-65b53008a515"}]
07:06:43 INFO - 1500559603169 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:43 INFO - 1500559603171 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
07:06:43 INFO - 1500559603173 Marionette TRACE 394 <- [1,7,null,{"value":false}]
07:06:43 INFO - 1500559603174 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
07:06:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500559603176 Marionette TRACE 394 <- [1,8,null,{"value":null}]
07:06:43 INFO - 1500559603177 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
07:06:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
07:06:43 INFO - 1500559603178 Marionette TRACE 394 <- [1,9,null,{}]
07:06:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:06:43 INFO - 1500559603180 Marionette DEBUG Closed connection 394
07:06:43 INFO - 1500559603181 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49793
07:06:43 INFO - 1500559603181 Marionette DEBUG Closed connection 395
07:06:43 INFO - 1500559603182 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49794
07:06:43 INFO - 1500559603183 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:43 INFO - 1500559603184 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:43 INFO - 1500559603186 Marionette DEBUG Register listener.js for window 15
07:06:43 INFO - 1500559603188 Marionette TRACE 396 <- [1,1,null,{"sessionId":"44e9a0d6-2847-4cbc-a8be-8509c821b50f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:43 INFO - 1500559603190 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:06:43 INFO - 1500559603190 Marionette TRACE 396 <- [1,2,null,{}]
07:06:43 INFO - 1500559603191 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:43 INFO - 1500559603192 Marionette TRACE 396 <- [1,3,null,{}]
07:06:43 INFO - 1500559603192 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:43 INFO - 1500559603193 Marionette TRACE 396 <- [1,4,null,{}]
07:06:43 INFO - 1500559603194 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
07:06:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500559603196 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:06:43 INFO - 1500559603197 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:06:43 INFO - 1500559603198 Marionette TRACE 396 <- [1,6,null,{}]
07:06:43 INFO - 1500559603200 Marionette DEBUG Closed connection 396
07:06:43 INFO - 1500559603200 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49795
07:06:43 INFO - 1500559603201 Marionette DEBUG Closed connection 397
07:06:43 INFO - 1500559603201 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49796
07:06:43 INFO - 1500559603202 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:43 INFO - 1500559603203 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:43 INFO - 1500559603205 Marionette DEBUG Register listener.js for window 15
07:06:43 INFO - 1500559603208 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4e264f13-ba44-4941-a8ab-6ba76623c6c8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:43 INFO - 1500559603210 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:06:43 INFO - 1500559603211 Marionette TRACE 398 <- [1,2,null,{}]
07:06:43 INFO - 1500559603212 Marionette DEBUG Closed connection 398
07:06:43 INFO - 1500559603213 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49797
07:06:43 INFO - 1500559603214 Marionette DEBUG Closed connection 399
07:06:43 INFO - 1500559603214 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49798
07:06:43 INFO - 1500559603215 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:43 INFO - 1500559603215 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}
07:06:43 INFO - 1500559603218 Marionette DEBUG Register listener.js for window 15
07:06:43 INFO - 1500559603220 Marionette TRACE 400 <- [1,1,null,{"sessionId":"dea8e9de-24e2-4e9e-bf7a-d0090d326887","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-063.001\\AppData\\Local\\Temp\\tmp660vb4.mozrunner","moz:accessibilityChecks":false}}]
07:06:43 INFO - 1500559603222 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
07:06:43 INFO - 1500559603223 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:06:43 INFO - 1500559603224 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:06:43 INFO - 1500559603225 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:06:43 INFO - 1500559603242 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:06:43 INFO - 1500559603243 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:06:43 INFO - 1500559603246 Marionette TRACE 400 <- [1,2,null,{}]
07:06:43 INFO - 1500559603248 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:06:43 INFO - 1500559603250 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86fbabeb-c636-40cd-9883-24d346bbb31f","ELEMENT":"86fbabeb-c636-40cd-9883-24d346bbb31f"}}]
07:06:43 INFO - 1500559603251 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"86fbabeb-c636-40cd-9883-24d346bbb31f"}]
07:06:43 INFO - 1500559603277 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
07:06:43 INFO - 1500559603278 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"86fbabeb-c636-40cd-9883-24d346bbb31f"}]
07:06:43 INFO - 1500559603295 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element