could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:09:48 INFO - 1498849788265 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}]
12:09:48 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498849788270 Marionette TRACE 358 <- [1,5,null,{"value":null}]
12:09:48 INFO - 1498849788272 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
12:09:48 INFO - 1498849788275 Marionette TRACE 358 <- [1,6,null,{}]
12:09:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 137ms
12:09:48 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:09:48 INFO - 1498849788277 Marionette DEBUG Closed connection 358
12:09:48 INFO - 1498849788278 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49691
12:09:48 INFO - 1498849788280 Marionette DEBUG Closed connection 359
12:09:48 INFO - 1498849788281 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49692
12:09:48 INFO - 1498849788283 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:48 INFO - 1498849788286 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788290 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788295 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ad766a53-57a8-4a24-8b52-b64e1b300b02","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788298 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:09:48 INFO - 1498849788299 Marionette TRACE 360 <- [1,2,null,{}]
12:09:48 INFO - 1498849788301 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:48 INFO - 1498849788302 Marionette TRACE 360 <- [1,3,null,{}]
12:09:48 INFO - 1498849788341 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:48 INFO - 1498849788343 Marionette TRACE 360 <- [1,4,null,{}]
12:09:48 INFO - 1498849788345 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}]
12:09:48 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498849788349 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:09:48 INFO - 1498849788350 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:09:48 INFO - 1498849788352 Marionette TRACE 360 <- [1,6,null,{}]
12:09:48 INFO - 1498849788355 Marionette DEBUG Closed connection 360
12:09:48 INFO - 1498849788356 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49693
12:09:48 INFO - 1498849788357 Marionette DEBUG Closed connection 361
12:09:48 INFO - 1498849788358 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49694
12:09:48 INFO - 1498849788398 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:48 INFO - 1498849788403 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788407 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788414 Marionette TRACE 362 <- [1,1,null,{"sessionId":"735d255d-c885-4bd4-ba08-433e170066b4","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788420 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:09:48 INFO - 1498849788423 Marionette TRACE 362 <- [1,2,null,{}]
12:09:48 INFO - 1498849788425 Marionette DEBUG Closed connection 362
12:09:48 INFO - 1498849788426 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49695
12:09:48 INFO - 1498849788427 Marionette DEBUG Closed connection 363
12:09:48 INFO - 1498849788428 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49696
12:09:48 INFO - 1498849788430 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:48 INFO - 1498849788431 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788435 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788443 Marionette TRACE 364 <- [1,1,null,{"sessionId":"873845b0-e0bf-49f4-9dbd-b525046e10e6","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788455 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49294/clicks.html"}]
12:09:48 INFO - 1498849788458 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/hidden.html"
12:09:48 INFO - 1498849788466 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/hidden.html"
12:09:48 INFO - 1498849788466 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/hidden.html"
12:09:48 INFO - 1498849788474 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49294/clicks.html"
12:09:48 INFO - 1498849788480 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49294/clicks.html"
12:09:48 INFO - 1498849788490 Marionette TRACE 364 <- [1,2,null,{}]
12:09:48 INFO - 1498849788497 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:09:48 INFO - 1498849788500 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edbb906c-d850-4560-9321-e5d847339e57","ELEMENT":"edbb906c-d850-4560-9321-e5d847339e57"}}]
12:09:48 INFO - 1498849788502 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"edbb906c-d850-4560-9321-e5d847339e57"}]
12:09:48 INFO - 1498849788548 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/clicks.html"
12:09:48 INFO - 1498849788558 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/clicks.html"
12:09:48 INFO - 1498849788558 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/clicks.html"
12:09:48 INFO - 1498849788565 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49294/test.html"
12:09:48 INFO - 1498849788569 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49294/test.html"
12:09:48 INFO - 1498849788578 Marionette TRACE 364 <- [1,4,null,{}]
12:09:48 INFO - 1498849788583 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:09:48 INFO - 1498849788590 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38b12bbf-a84c-4d80-9c8a-7328b8d7a118","ELEMENT":"38b12bbf-a84c-4d80-9c8a-7328b8d7a118"}}]
12:09:48 INFO - 1498849788591 Marionette TRACE 364 -> [0,6,"getTitle",{}]
12:09:48 INFO - 1498849788594 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
12:09:48 INFO - 1498849788596 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}]
12:09:48 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498849788599 Marionette TRACE 364 <- [1,7,null,{"value":null}]
12:09:48 INFO - 1498849788601 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
12:09:48 INFO - 1498849788603 Marionette TRACE 364 <- [1,8,null,{}]
12:09:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 329ms
12:09:48 INFO - TEST-START | test_click.py TestClick.test_container_element
12:09:48 INFO - 1498849788606 Marionette DEBUG Closed connection 364
12:09:48 INFO - 1498849788607 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49699
12:09:48 INFO - 1498849788608 Marionette DEBUG Closed connection 365
12:09:48 INFO - 1498849788609 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49700
12:09:48 INFO - 1498849788612 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:48 INFO - 1498849788614 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788617 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788622 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8b565db4-d12d-4a66-8364-24b3a67b8213","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788623 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:09:48 INFO - 1498849788624 Marionette TRACE 366 <- [1,2,null,{}]
12:09:48 INFO - 1498849788625 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:48 INFO - 1498849788626 Marionette TRACE 366 <- [1,3,null,{}]
12:09:48 INFO - 1498849788627 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:48 INFO - 1498849788628 Marionette TRACE 366 <- [1,4,null,{}]
12:09:48 INFO - 1498849788630 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}]
12:09:48 INFO - TEST-START: test_click.py TestClick.test_container_element1498849788634 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:09:48 INFO - 1498849788634 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:09:48 INFO - 1498849788636 Marionette TRACE 366 <- [1,6,null,{}]
12:09:48 INFO - 1498849788638 Marionette DEBUG Closed connection 366
12:09:48 INFO - 1498849788639 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49701
12:09:48 INFO - 1498849788640 Marionette DEBUG Closed connection 367
12:09:48 INFO - 1498849788641 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49702
12:09:48 INFO - 1498849788642 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:48 INFO - 1498849788643 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788647 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788651 Marionette TRACE 368 <- [1,1,null,{"sessionId":"51c9df04-fea3-424c-a8e6-dbe4701546d0","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788653 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:09:48 INFO - 1498849788654 Marionette TRACE 368 <- [1,2,null,{}]
12:09:48 INFO - 1498849788656 Marionette DEBUG Closed connection 368
12:09:48 INFO - 1498849788657 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49703
12:09:48 INFO - 1498849788658 Marionette DEBUG Closed connection 369
12:09:48 INFO - 1498849788659 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49704
12:09:48 INFO - 1498849788660 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:48 INFO - 1498849788661 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788665 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788670 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3dc13fc5-0d91-4908-ac6a-715db9dbecee","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788671 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"}]
12:09:48 INFO - 1498849788673 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49294/test.html"
12:09:48 INFO - 1498849788676 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49294/test.html"
12:09:48 INFO - 1498849788676 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49294/test.html"
12:09:48 INFO - 1498849788683 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"
12:09:48 INFO - 1498849788688 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"
12:09:48 INFO - 1498849788696 Marionette TRACE 370 <- [1,2,null,{}]
12:09:48 INFO - 1498849788700 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:09:48 INFO - 1498849788707 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab34f683-ffef-4b43-9743-86b05bd7fbe4","ELEMENT":"ab34f683-ffef-4b43-9743-86b05bd7fbe4"}}]
12:09:48 INFO - 1498849788708 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ab34f683-ffef-4b43-9743-86b05bd7fbe4"}]
12:09:48 INFO - 1498849788956 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:09:48 INFO - 1498849788957 Marionette TRACE 370 <- [1,4,null,{}]
12:09:48 INFO - 1498849788958 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ab34f683-ffef-4b43-9743-86b05bd7fbe4","name":"selected"}]
12:09:48 INFO - 1498849788965 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:09:48 INFO - 1498849788967 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}]
12:09:48 INFO - TEST-END: test_click.py TestClick.test_container_element1498849788970 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:09:48 INFO - 1498849788971 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:09:48 INFO - 1498849788972 Marionette TRACE 370 <- [1,7,null,{}]
12:09:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 367ms
12:09:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:09:48 INFO - 1498849788974 Marionette DEBUG Closed connection 370
12:09:48 INFO - 1498849788975 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49705
12:09:48 INFO - 1498849788976 Marionette DEBUG Closed connection 371
12:09:48 INFO - 1498849788976 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49706
12:09:48 INFO - 1498849788978 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:48 INFO - 1498849788979 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:48 INFO - 1498849788982 Marionette DEBUG Register listener.js for window 2147483649
12:09:48 INFO - 1498849788987 Marionette TRACE 372 <- [1,1,null,{"sessionId":"1e4207de-fec3-4d17-a1b0-21415272497e","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:48 INFO - 1498849788988 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:09:48 INFO - 1498849788989 Marionette TRACE 372 <- [1,2,null,{}]
12:09:48 INFO - 1498849788990 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:48 INFO - 1498849788990 Marionette TRACE 372 <- [1,3,null,{}]
12:09:48 INFO - 1498849788991 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:48 INFO - 1498849788992 Marionette TRACE 372 <- [1,4,null,{}]
12:09:48 INFO - 1498849788994 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}]
12:09:49 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498849788998 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:09:49 INFO - 1498849788999 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:09:49 INFO - 1498849789001 Marionette TRACE 372 <- [1,6,null,{}]
12:09:49 INFO - 1498849789004 Marionette DEBUG Closed connection 372
12:09:49 INFO - 1498849789006 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49707
12:09:49 INFO - 1498849789007 Marionette DEBUG Closed connection 373
12:09:49 INFO - 1498849789008 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49708
12:09:49 INFO - 1498849789009 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:49 INFO - 1498849789010 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789012 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789016 Marionette TRACE 374 <- [1,1,null,{"sessionId":"76c1111c-7b8b-491b-8443-f6c11f80a1a4","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789017 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:09:49 INFO - 1498849789019 Marionette TRACE 374 <- [1,2,null,{}]
12:09:49 INFO - 1498849789020 Marionette DEBUG Closed connection 374
12:09:49 INFO - 1498849789021 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49709
12:09:49 INFO - 1498849789022 Marionette DEBUG Closed connection 375
12:09:49 INFO - 1498849789023 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49710
12:09:49 INFO - 1498849789024 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:49 INFO - 1498849789024 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789027 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789031 Marionette TRACE 376 <- [1,1,null,{"sessionId":"5dfd00bd-bf1a-4477-a16b-f0a0f328016e","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789032 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"}]
12:09:49 INFO - 1498849789034 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"
12:09:49 INFO - 1498849789036 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"
12:09:49 INFO - 1498849789036 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"
12:09:49 INFO - 1498849789042 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"
12:09:49 INFO - 1498849789046 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"
12:09:49 INFO - 1498849789050 Marionette TRACE 376 <- [1,2,null,{}]
12:09:49 INFO - 1498849789059 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:09:49 INFO - 1498849789062 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"261f8794-7485-4ba2-a55c-18eb1e6cb1d2","ELEMENT":"261f8794-7485-4ba2-a55c-18eb1e6cb1d2"}}]
12:09:49 INFO - 1498849789063 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"261f8794-7485-4ba2-a55c-18eb1e6cb1d2"}]
12:09:49 INFO - 1498849789307 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:09:49 INFO - 1498849789308 Marionette TRACE 376 <- [1,4,null,{}]
12:09:49 INFO - 1498849789309 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"261f8794-7485-4ba2-a55c-18eb1e6cb1d2","name":"selected"}]
12:09:49 INFO - 1498849789314 Marionette TRACE 376 <- [1,5,null,{"value":true}]
12:09:49 INFO - 1498849789316 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}]
12:09:49 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498849789320 Marionette TRACE 376 <- [1,6,null,{"value":null}]
12:09:49 INFO - 1498849789321 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
12:09:49 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 350ms
12:09:49 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:09:49 INFO - 1498849789323 Marionette TRACE 376 <- [1,7,null,{}]
12:09:49 INFO - 1498849789325 Marionette DEBUG Closed connection 376
12:09:49 INFO - 1498849789325 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49711
12:09:49 INFO - 1498849789326 Marionette DEBUG Closed connection 377
12:09:49 INFO - 1498849789327 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49712
12:09:49 INFO - 1498849789328 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:49 INFO - 1498849789329 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789331 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789335 Marionette TRACE 378 <- [1,1,null,{"sessionId":"1f1a15c3-6311-45a4-8147-8d045bc7a713","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789336 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:09:49 INFO - 1498849789336 Marionette TRACE 378 <- [1,2,null,{}]
12:09:49 INFO - 1498849789337 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:49 INFO - 1498849789338 Marionette TRACE 378 <- [1,3,null,{}]
12:09:49 INFO - 1498849789339 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:49 INFO - 1498849789339 Marionette TRACE 378 <- [1,4,null,{}]
12:09:49 INFO - 1498849789341 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}]
12:09:49 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498849789344 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:09:49 INFO - 1498849789345 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:09:49 INFO - 1498849789347 Marionette TRACE 378 <- [1,6,null,{}]
12:09:49 INFO - 1498849789348 Marionette DEBUG Closed connection 378
12:09:49 INFO - 1498849789349 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49713
12:09:49 INFO - 1498849789350 Marionette DEBUG Closed connection 379
12:09:49 INFO - 1498849789350 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49714
12:09:49 INFO - 1498849789352 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:49 INFO - 1498849789352 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789355 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789358 Marionette TRACE 380 <- [1,1,null,{"sessionId":"00d8c03d-046a-47f5-8765-a17bd016e811","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789360 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:09:49 INFO - 1498849789361 Marionette TRACE 380 <- [1,2,null,{}]
12:09:49 INFO - 1498849789363 Marionette DEBUG Closed connection 380
12:09:49 INFO - 1498849789363 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49715
12:09:49 INFO - 1498849789364 Marionette DEBUG Closed connection 381
12:09:49 INFO - 1498849789365 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49716
12:09:49 INFO - 1498849789366 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:49 INFO - 1498849789367 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789372 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789376 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c01d3f4c-5f87-40ec-90b9-3a57996b10d6","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789377 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"}]
12:09:49 INFO - 1498849789380 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"
12:09:49 INFO - 1498849789382 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"
12:09:49 INFO - 1498849789382 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"
12:09:49 INFO - 1498849789392 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"
12:09:49 INFO - 1498849789394 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"
12:09:49 INFO - 1498849789400 Marionette TRACE 382 <- [1,2,null,{}]
12:09:49 INFO - 1498849789409 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:09:49 INFO - 1498849789412 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"038765d3-2666-4260-8561-f2da5111abae","ELEMENT":"038765d3-2666-4260-8561-f2da5111abae"}}]
12:09:49 INFO - 1498849789413 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"038765d3-2666-4260-8561-f2da5111abae"}]
12:09:49 INFO - 1498849789658 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:09:49 INFO - 1498849789659 Marionette TRACE 382 <- [1,4,null,{}]
12:09:49 INFO - 1498849789661 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}]
12:09:49 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498849789664 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:09:49 INFO - 1498849789665 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:09:49 INFO - 1498849789666 Marionette TRACE 382 <- [1,6,null,{}]
12:09:49 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 343ms
12:09:49 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:09:49 INFO - 1498849789668 Marionette DEBUG Closed connection 382
12:09:49 INFO - 1498849789669 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49717
12:09:49 INFO - 1498849789669 Marionette DEBUG Closed connection 383
12:09:49 INFO - 1498849789670 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49718
12:09:49 INFO - 1498849789671 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:49 INFO - 1498849789672 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789675 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789678 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3d50c53d-fbc1-4407-8e26-4a85524bc912","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789680 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:09:49 INFO - 1498849789680 Marionette TRACE 384 <- [1,2,null,{}]
12:09:49 INFO - 1498849789681 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:49 INFO - 1498849789682 Marionette TRACE 384 <- [1,3,null,{}]
12:09:49 INFO - 1498849789683 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:49 INFO - 1498849789684 Marionette TRACE 384 <- [1,4,null,{}]
12:09:49 INFO - 1498849789685 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}]
12:09:49 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498849789688 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:09:49 INFO - 1498849789689 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:09:49 INFO - 1498849789691 Marionette TRACE 384 <- [1,6,null,{}]
12:09:49 INFO - 1498849789693 Marionette DEBUG Closed connection 384
12:09:49 INFO - 1498849789693 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49719
12:09:49 INFO - 1498849789694 Marionette DEBUG Closed connection 385
12:09:49 INFO - 1498849789695 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49720
12:09:49 INFO - 1498849789696 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:49 INFO - 1498849789697 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789699 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789703 Marionette TRACE 386 <- [1,1,null,{"sessionId":"aa2ad684-bdc1-4d49-bf11-0cd8bf9c8337","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789704 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:09:49 INFO - 1498849789705 Marionette TRACE 386 <- [1,2,null,{}]
12:09:49 INFO - 1498849789707 Marionette DEBUG Closed connection 386
12:09:49 INFO - 1498849789707 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49721
12:09:49 INFO - 1498849789708 Marionette DEBUG Closed connection 387
12:09:49 INFO - 1498849789709 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49722
12:09:49 INFO - 1498849789710 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:49 INFO - 1498849789710 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:49 INFO - 1498849789713 Marionette DEBUG Register listener.js for window 2147483649
12:09:49 INFO - 1498849789716 Marionette TRACE 388 <- [1,1,null,{"sessionId":"5a193ffa-b72f-4099-833c-e58c3c580910","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:49 INFO - 1498849789718 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"}]
12:09:49 INFO - 1498849789721 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"
12:09:49 INFO - 1498849789723 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"
12:09:49 INFO - 1498849789723 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"
12:09:49 INFO - 1498849789729 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"
12:09:49 INFO - 1498849789733 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"
12:09:49 INFO - 1498849789743 Marionette TRACE 388 <- [1,2,null,{}]
12:09:49 INFO - 1498849789747 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:09:49 INFO - 1498849789750 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf9165d6-a073-4855-a4ef-4e6bf802b627","ELEMENT":"cf9165d6-a073-4855-a4ef-4e6bf802b627"}}]
12:09:49 INFO - 1498849789751 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"cf9165d6-a073-4855-a4ef-4e6bf802b627"}]
12:09:49 INFO - 1498849789993 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:09:49 INFO - 1498849789994 Marionette TRACE 388 <- [1,4,null,{}]
12:09:49 INFO - 1498849789996 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}]
12:09:50 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498849789999 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:09:50 INFO - 1498849790000 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:09:50 INFO - 1498849790001 Marionette TRACE 388 <- [1,6,null,{}]
12:09:50 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 335ms
12:09:50 INFO - TEST-START | test_click.py TestClick.test_input_file
12:09:50 INFO - 1498849790003 Marionette DEBUG Closed connection 388
12:09:50 INFO - 1498849790004 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49723
12:09:50 INFO - 1498849790005 Marionette DEBUG Closed connection 389
12:09:50 INFO - 1498849790005 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49724
12:09:50 INFO - 1498849790006 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790007 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790010 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790013 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9a85dcb8-020c-473d-9056-869eb8bfcefd","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790015 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:09:50 INFO - 1498849790015 Marionette TRACE 390 <- [1,2,null,{}]
12:09:50 INFO - 1498849790016 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:50 INFO - 1498849790017 Marionette TRACE 390 <- [1,3,null,{}]
12:09:50 INFO - 1498849790018 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:50 INFO - 1498849790018 Marionette TRACE 390 <- [1,4,null,{}]
12:09:50 INFO - 1498849790020 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}]
12:09:50 INFO - TEST-START: test_click.py TestClick.test_input_file1498849790023 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:09:50 INFO - 1498849790024 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:09:50 INFO - 1498849790025 Marionette TRACE 390 <- [1,6,null,{}]
12:09:50 INFO - 1498849790027 Marionette DEBUG Closed connection 390
12:09:50 INFO - 1498849790027 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49725
12:09:50 INFO - 1498849790028 Marionette DEBUG Closed connection 391
12:09:50 INFO - 1498849790029 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49726
12:09:50 INFO - 1498849790030 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790030 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790033 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790037 Marionette TRACE 392 <- [1,1,null,{"sessionId":"68e3cfc4-c297-47b4-bbf5-efb04fc55750","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790039 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:09:50 INFO - 1498849790040 Marionette TRACE 392 <- [1,2,null,{}]
12:09:50 INFO - 1498849790042 Marionette DEBUG Closed connection 392
12:09:50 INFO - 1498849790043 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49727
12:09:50 INFO - 1498849790044 Marionette DEBUG Closed connection 393
12:09:50 INFO - 1498849790044 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49728
12:09:50 INFO - 1498849790045 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:50 INFO - 1498849790046 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790049 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790053 Marionette TRACE 394 <- [1,1,null,{"sessionId":"914c4a39-fa12-4c2b-8d43-4b8d562089aa","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790054 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:09:50 INFO - 1498849790056 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"
12:09:50 INFO - 1498849790058 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"
12:09:50 INFO - 1498849790059 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"
12:09:50 INFO - 1498849790065 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:09:50 INFO - 1498849790070 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:09:50 INFO - 1498849790076 Marionette TRACE 394 <- [1,2,null,{}]
12:09:50 INFO - 1498849790080 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:09:50 INFO - 1498849790083 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"537c08e0-d41d-4686-b564-9ee5a0fcc8c0","ELEMENT":"537c08e0-d41d-4686-b564-9ee5a0fcc8c0"}}]
12:09:50 INFO - 1498849790085 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"537c08e0-d41d-4686-b564-9ee5a0fcc8c0"}]
12:09:50 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
12:09:50 INFO - 1498849790116 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:09:50 INFO - 1498849790118 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}]
12:09:50 INFO - TEST-END: test_click.py TestClick.test_input_file1498849790122 Marionette TRACE 394 <- [1,5,null,{"value":null}]
12:09:50 INFO - 1498849790123 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
12:09:50 INFO - 1498849790125 Marionette TRACE 394 <- [1,6,null,{}]
12:09:50 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:09:50 INFO - 1498849790126 Marionette DEBUG Closed connection 394
12:09:50 INFO - 1498849790128 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49729
12:09:50 INFO - 1498849790128 Marionette DEBUG Closed connection 395
12:09:50 INFO - 1498849790129 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49730
12:09:50 INFO - 1498849790130 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790131 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790138 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790150 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6a52affb-192c-4644-a372-bb1b169383f8","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790152 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:09:50 INFO - 1498849790152 Marionette TRACE 396 <- [1,2,null,{}]
12:09:50 INFO - 1498849790153 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:50 INFO - 1498849790154 Marionette TRACE 396 <- [1,3,null,{}]
12:09:50 INFO - 1498849790155 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:50 INFO - 1498849790155 Marionette TRACE 396 <- [1,4,null,{}]
12:09:50 INFO - 1498849790157 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}]
12:09:50 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498849790160 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:09:50 INFO - 1498849790161 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:09:50 INFO - 1498849790163 Marionette TRACE 396 <- [1,6,null,{}]
12:09:50 INFO - 1498849790164 Marionette DEBUG Closed connection 396
12:09:50 INFO - 1498849790165 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49731
12:09:50 INFO - 1498849790166 Marionette DEBUG Closed connection 397
12:09:50 INFO - 1498849790167 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49732
12:09:50 INFO - 1498849790168 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790168 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790171 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790175 Marionette TRACE 398 <- [1,1,null,{"sessionId":"aaba4a18-9501-4274-ad25-5cf7b22956d7","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790176 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:09:50 INFO - 1498849790177 Marionette TRACE 398 <- [1,2,null,{}]
12:09:50 INFO - 1498849790179 Marionette DEBUG Closed connection 398
12:09:50 INFO - 1498849790179 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49733
12:09:50 INFO - 1498849790180 Marionette DEBUG Closed connection 399
12:09:50 INFO - 1498849790181 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49734
12:09:50 INFO - 1498849790182 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:50 INFO - 1498849790183 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790186 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790189 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c9cf4920-d52f-4e67-966b-7b9c616d32a1","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790191 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"}]
12:09:50 INFO - 1498849790192 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:09:50 INFO - 1498849790196 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:09:50 INFO - 1498849790196 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:09:50 INFO - 1498849790208 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"
12:09:50 INFO - 1498849790212 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"
12:09:50 INFO - 1498849790215 Marionette TRACE 400 <- [1,2,null,{}]
12:09:50 INFO - 1498849790218 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:09:50 INFO - 1498849790224 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99c8fde5-f9c3-4ed7-95f9-07d67e1ecf9e","ELEMENT":"99c8fde5-f9c3-4ed7-95f9-07d67e1ecf9e"}}]
12:09:50 INFO - 1498849790225 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:09:50 INFO - 1498849790228 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e3e9806f-7b92-42a5-92e6-de3db419b3ff","ELEMENT":"e3e9806f-7b92-42a5-92e6-de3db419b3ff"}}]
12:09:50 INFO - 1498849790229 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"99c8fde5-f9c3-4ed7-95f9-07d67e1ecf9e"}]
12:09:50 INFO - 1498849790468 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:09:50 INFO - 1498849790469 Marionette TRACE 400 <- [1,5,null,{}]
12:09:50 INFO - 1498849790470 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"e3e9806f-7b92-42a5-92e6-de3db419b3ff"}]
12:09:50 INFO - 1498849790488 Marionette TRACE 400 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:09:50 INFO - 1498849790489 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:09:50 INFO - 1498849790492 Marionette TRACE 400 <- [1,7,null,{"value":false}]
12:09:50 INFO - 1498849790494 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}]
12:09:50 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498849790496 Marionette TRACE 400 <- [1,8,null,{"value":null}]
12:09:50 INFO - 1498849790497 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
12:09:50 INFO - 1498849790499 Marionette TRACE 400 <- [1,9,null,{}]
12:09:50 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 374ms
12:09:50 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:09:50 INFO - 1498849790500 Marionette DEBUG Closed connection 400
12:09:50 INFO - 1498849790502 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49735
12:09:50 INFO - 1498849790503 Marionette DEBUG Closed connection 401
12:09:50 INFO - 1498849790503 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49736
12:09:50 INFO - 1498849790504 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790505 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790507 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790511 Marionette TRACE 402 <- [1,1,null,{"sessionId":"566830e8-ba0d-41c4-a3a4-622adcd4132a","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790514 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
12:09:50 INFO - 1498849790514 Marionette TRACE 402 <- [1,2,null,{}]
12:09:50 INFO - 1498849790515 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:09:50 INFO - 1498849790516 Marionette TRACE 402 <- [1,3,null,{}]
12:09:50 INFO - 1498849790517 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
12:09:50 INFO - 1498849790517 Marionette TRACE 402 <- [1,4,null,{}]
12:09:50 INFO - 1498849790519 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}]
12:09:50 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498849790522 Marionette TRACE 402 <- [1,5,null,{"value":null}]
12:09:50 INFO - 1498849790523 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
12:09:50 INFO - 1498849790525 Marionette TRACE 402 <- [1,6,null,{}]
12:09:50 INFO - 1498849790526 Marionette DEBUG Closed connection 402
12:09:50 INFO - 1498849790527 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49737
12:09:50 INFO - 1498849790528 Marionette DEBUG Closed connection 403
12:09:50 INFO - 1498849790528 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49738
12:09:50 INFO - 1498849790529 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:09:50 INFO - 1498849790531 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790534 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790537 Marionette TRACE 404 <- [1,1,null,{"sessionId":"47d21b06-ba24-404e-b85c-896d4458cf9e","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790539 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
12:09:50 INFO - 1498849790540 Marionette TRACE 404 <- [1,2,null,{}]
12:09:50 INFO - 1498849790542 Marionette DEBUG Closed connection 404
12:09:50 INFO - 1498849790542 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49739
12:09:50 INFO - 1498849790544 Marionette DEBUG Closed connection 405
12:09:50 INFO - 1498849790545 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49740
12:09:50 INFO - 1498849790546 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:09:50 INFO - 1498849790547 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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}
12:09:50 INFO - 1498849790550 Marionette DEBUG Register listener.js for window 2147483649
12:09:50 INFO - 1498849790554 Marionette TRACE 406 <- [1,1,null,{"sessionId":"44fdd9e5-37d5-4bb3-b9e1-4e02ebefa2e0","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":3008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-145\\AppData\\Local\\Temp\\tmpprs4fn.mozrunner","moz:accessibilityChecks":false}}]
12:09:50 INFO - 1498849790556 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"}]
12:09:50 INFO - 1498849790558 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"
12:09:50 INFO - 1498849790560 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"
12:09:50 INFO - 1498849790561 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"
12:09:50 INFO - 1498849790567 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"
12:09:50 INFO - 1498849790572 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"
12:09:50 INFO - 1498849790579 Marionette TRACE 406 <- [1,2,null,{}]
12:09:50 INFO - 1498849790582 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:09:50 INFO - 1498849790585 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"faec00b5-842b-41d3-9537-f30bdd8c4f96","ELEMENT":"faec00b5-842b-41d3-9537-f30bdd8c4f96"}}]
12:09:50 INFO - 1498849790591 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"faec00b5-842b-41d3-9537-f30bdd8c4f96"}]
12:09:50 INFO - 1498849790615 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
12:09:50 INFO - 1498849790617 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"faec00b5-842b-41d3-9537-f30bdd8c4f96"}]
12:09:50 INFO - 1498849790634 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element