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:1318:5\n"},null]
05:02:39 INFO - 1499169759823 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:02:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499169759825 Marionette TRACE 353 <- [1,5,null,{"value":null}]
05:02:39 INFO - 1499169759826 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
05:02:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
05:02:39 INFO - 1499169759827 Marionette TRACE 353 <- [1,6,null,{}]
05:02:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:02:39 INFO - 1499169759829 Marionette DEBUG Closed connection 353
05:02:39 INFO - 1499169759830 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49747
05:02:39 INFO - 1499169759831 Marionette DEBUG Closed connection 354
05:02:39 INFO - 1499169759831 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49748
05:02:39 INFO - 1499169759832 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:39 INFO - 1499169759832 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:39 INFO - 1499169759834 Marionette DEBUG Register listener.js for window 12
05:02:39 INFO - 1499169759838 Marionette TRACE 355 <- [1,1,null,{"sessionId":"d22cf8f7-75bb-4a29-9a14-9070b340488e","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:39 INFO - 1499169759839 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
05:02:39 INFO - 1499169759840 Marionette TRACE 355 <- [1,2,null,{}]
05:02:39 INFO - 1499169759840 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:39 INFO - 1499169759841 Marionette TRACE 355 <- [1,3,null,{}]
05:02:39 INFO - 1499169759841 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:39 INFO - 1499169759842 Marionette TRACE 355 <- [1,4,null,{}]
05:02:39 INFO - 1499169759843 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:02:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499169759845 Marionette TRACE 355 <- [1,5,null,{"value":null}]
05:02:39 INFO - 1499169759846 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
05:02:39 INFO - 1499169759847 Marionette TRACE 355 <- [1,6,null,{}]
05:02:39 INFO - 1499169759849 Marionette DEBUG Closed connection 355
05:02:39 INFO - 1499169759850 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49749
05:02:39 INFO - 1499169759850 Marionette DEBUG Closed connection 356
05:02:39 INFO - 1499169759851 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49750
05:02:39 INFO - 1499169759852 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:39 INFO - 1499169759852 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:39 INFO - 1499169759854 Marionette DEBUG Register listener.js for window 12
05:02:39 INFO - 1499169759857 Marionette TRACE 357 <- [1,1,null,{"sessionId":"10e233f3-4cbd-4299-ad05-47938823c094","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:39 INFO - 1499169759858 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
05:02:39 INFO - 1499169759859 Marionette TRACE 357 <- [1,2,null,{}]
05:02:39 INFO - 1499169759860 Marionette DEBUG Closed connection 357
05:02:39 INFO - 1499169759861 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49751
05:02:39 INFO - 1499169759861 Marionette DEBUG Closed connection 358
05:02:39 INFO - 1499169759862 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49752
05:02:39 INFO - 1499169759863 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:39 INFO - 1499169759863 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:39 INFO - 1499169759865 Marionette DEBUG Register listener.js for window 12
05:02:39 INFO - 1499169759868 Marionette TRACE 359 <- [1,1,null,{"sessionId":"89c67ebe-5568-48e8-a192-5269ba999033","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:39 INFO - 1499169759869 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49323/clicks.html"}]
05:02:39 INFO - 1499169759870 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/hidden.html"
05:02:39 INFO - 1499169759878 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/hidden.html"
05:02:39 INFO - 1499169759878 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/hidden.html"
05:02:39 INFO - 1499169759888 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49323/clicks.html"
05:02:39 INFO - 1499169759897 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49323/clicks.html"
05:02:39 INFO - 1499169759900 Marionette TRACE 359 <- [1,2,null,{}]
05:02:39 INFO - 1499169759901 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:02:39 INFO - 1499169759903 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a8c1db1d-486c-450a-9b2b-da5475abb6f6","ELEMENT":"a8c1db1d-486c-450a-9b2b-da5475abb6f6"}}]
05:02:39 INFO - 1499169759904 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"a8c1db1d-486c-450a-9b2b-da5475abb6f6"}]
05:02:39 INFO - 1499169759943 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/clicks.html"
05:02:39 INFO - 1499169759960 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/clicks.html"
05:02:39 INFO - 1499169759960 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/clicks.html"
05:02:40 INFO - 1499169759997 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49323/test.html"
05:02:40 INFO - 1499169759998 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49323/test.html"
05:02:40 INFO - 1499169760000 Marionette TRACE 359 <- [1,4,null,{}]
05:02:40 INFO - 1499169760001 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:02:40 INFO - 1499169760003 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1996d499-5ff9-4fe7-a34c-be54e9ad4b98","ELEMENT":"1996d499-5ff9-4fe7-a34c-be54e9ad4b98"}}]
05:02:40 INFO - 1499169760004 Marionette TRACE 359 -> [0,6,"getTitle",{}]
05:02:40 INFO - 1499169760004 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
05:02:40 INFO - 1499169760011 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:02:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499169760013 Marionette TRACE 359 <- [1,7,null,{"value":null}]
05:02:40 INFO - 1499169760014 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
05:02:40 INFO - 1499169760015 Marionette TRACE 359 <- [1,8,null,{}]
05:02:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 187ms
05:02:40 INFO - TEST-START | test_click.py TestClick.test_container_element
05:02:40 INFO - 1499169760017 Marionette DEBUG Closed connection 359
05:02:40 INFO - 1499169760018 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49755
05:02:40 INFO - 1499169760018 Marionette DEBUG Closed connection 360
05:02:40 INFO - 1499169760019 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49756
05:02:40 INFO - 1499169760020 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760020 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760022 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760025 Marionette TRACE 361 <- [1,1,null,{"sessionId":"852bcd62-15ae-4432-b041-fb6436987115","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760026 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
05:02:40 INFO - 1499169760027 Marionette TRACE 361 <- [1,2,null,{}]
05:02:40 INFO - 1499169760027 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:40 INFO - 1499169760028 Marionette TRACE 361 <- [1,3,null,{}]
05:02:40 INFO - 1499169760029 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:40 INFO - 1499169760029 Marionette TRACE 361 <- [1,4,null,{}]
05:02:40 INFO - 1499169760031 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:02:40 INFO - TEST-START: test_click.py TestClick.test_container_element1499169760033 Marionette TRACE 361 <- [1,5,null,{"value":null}]
05:02:40 INFO - 1499169760033 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
05:02:40 INFO - 1499169760034 Marionette TRACE 361 <- [1,6,null,{}]
05:02:40 INFO - 1499169760036 Marionette DEBUG Closed connection 361
05:02:40 INFO - 1499169760037 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49757
05:02:40 INFO - 1499169760038 Marionette DEBUG Closed connection 362
05:02:40 INFO - 1499169760038 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49758
05:02:40 INFO - 1499169760039 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760040 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760042 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760050 Marionette TRACE 363 <- [1,1,null,{"sessionId":"20133e89-2854-452b-9997-93f99be01f4a","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760052 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
05:02:40 INFO - 1499169760053 Marionette TRACE 363 <- [1,2,null,{}]
05:02:40 INFO - 1499169760055 Marionette DEBUG Closed connection 363
05:02:40 INFO - 1499169760055 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49759
05:02:40 INFO - 1499169760056 Marionette DEBUG Closed connection 364
05:02:40 INFO - 1499169760056 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49760
05:02:40 INFO - 1499169760057 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:40 INFO - 1499169760058 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760060 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760062 Marionette TRACE 365 <- [1,1,null,{"sessionId":"78b10dc3-f2d4-4299-b819-ba0aff811aab","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760063 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:02:40 INFO - 1499169760064 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/test.html"
05:02:40 INFO - 1499169760066 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/test.html"
05:02:40 INFO - 1499169760066 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/test.html"
05:02:40 INFO - 1499169760086 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760090 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760098 Marionette TRACE 365 <- [1,2,null,{}]
05:02:40 INFO - 1499169760099 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:02:40 INFO - 1499169760101 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb7a23fa-13c0-4218-ad10-f17497c70e01","ELEMENT":"fb7a23fa-13c0-4218-ad10-f17497c70e01"}}]
05:02:40 INFO - 1499169760102 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"fb7a23fa-13c0-4218-ad10-f17497c70e01"}]
05:02:40 INFO - 1499169760338 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:40 INFO - 1499169760338 Marionette TRACE 365 <- [1,4,null,{}]
05:02:40 INFO - 1499169760339 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"fb7a23fa-13c0-4218-ad10-f17497c70e01","name":"selected"}]
05:02:40 INFO - 1499169760343 Marionette TRACE 365 <- [1,5,null,{"value":true}]
05:02:40 INFO - 1499169760346 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:02:40 INFO - TEST-END: test_click.py TestClick.test_container_element1499169760348 Marionette TRACE 365 <- [1,6,null,{"value":null}]
05:02:40 INFO - 1499169760348 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
05:02:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 334ms
05:02:40 INFO - 1499169760349 Marionette TRACE 365 <- [1,7,null,{}]
05:02:40 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:02:40 INFO - 1499169760351 Marionette DEBUG Closed connection 365
05:02:40 INFO - 1499169760352 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49761
05:02:40 INFO - 1499169760354 Marionette DEBUG Closed connection 366
05:02:40 INFO - 1499169760354 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49762
05:02:40 INFO - 1499169760356 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760356 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760358 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760361 Marionette TRACE 367 <- [1,1,null,{"sessionId":"2c544923-2426-475b-a777-5ca5960ba3c1","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760362 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
05:02:40 INFO - 1499169760362 Marionette TRACE 367 <- [1,2,null,{}]
05:02:40 INFO - 1499169760363 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:40 INFO - 1499169760363 Marionette TRACE 367 <- [1,3,null,{}]
05:02:40 INFO - 1499169760364 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:40 INFO - 1499169760365 Marionette TRACE 367 <- [1,4,null,{}]
05:02:40 INFO - 1499169760366 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:02:40 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499169760369 Marionette TRACE 367 <- [1,5,null,{"value":null}]
05:02:40 INFO - 1499169760370 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
05:02:40 INFO - 1499169760371 Marionette TRACE 367 <- [1,6,null,{}]
05:02:40 INFO - 1499169760373 Marionette DEBUG Closed connection 367
05:02:40 INFO - 1499169760373 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49763
05:02:40 INFO - 1499169760374 Marionette DEBUG Closed connection 368
05:02:40 INFO - 1499169760374 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49764
05:02:40 INFO - 1499169760375 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760376 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760378 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760380 Marionette TRACE 369 <- [1,1,null,{"sessionId":"73c42b84-cf6e-409c-8b3f-7d527bf201b2","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760381 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
05:02:40 INFO - 1499169760382 Marionette TRACE 369 <- [1,2,null,{}]
05:02:40 INFO - 1499169760384 Marionette DEBUG Closed connection 369
05:02:40 INFO - 1499169760384 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49765
05:02:40 INFO - 1499169760385 Marionette DEBUG Closed connection 370
05:02:40 INFO - 1499169760385 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49766
05:02:40 INFO - 1499169760386 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:40 INFO - 1499169760387 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760389 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760391 Marionette TRACE 371 <- [1,1,null,{"sessionId":"86cbefcc-6a52-43f4-8e5c-7fc84e732878","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760392 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:02:40 INFO - 1499169760393 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760395 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760395 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760404 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760413 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760416 Marionette TRACE 371 <- [1,2,null,{}]
05:02:40 INFO - 1499169760417 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:02:40 INFO - 1499169760419 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8dd7caca-6c98-40f4-bf0e-06daa06510af","ELEMENT":"8dd7caca-6c98-40f4-bf0e-06daa06510af"}}]
05:02:40 INFO - 1499169760419 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"8dd7caca-6c98-40f4-bf0e-06daa06510af"}]
05:02:40 INFO - 1499169760673 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:40 INFO - 1499169760673 Marionette TRACE 371 <- [1,4,null,{}]
05:02:40 INFO - 1499169760675 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"8dd7caca-6c98-40f4-bf0e-06daa06510af","name":"selected"}]
05:02:40 INFO - 1499169760678 Marionette TRACE 371 <- [1,5,null,{"value":true}]
05:02:40 INFO - 1499169760680 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:02:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499169760682 Marionette TRACE 371 <- [1,6,null,{"value":null}]
05:02:40 INFO - 1499169760683 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
05:02:40 INFO - 1499169760684 Marionette TRACE 371 <- [1,7,null,{}]
05:02:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
05:02:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:02:40 INFO - 1499169760685 Marionette DEBUG Closed connection 371
05:02:40 INFO - 1499169760687 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49767
05:02:40 INFO - 1499169760687 Marionette DEBUG Closed connection 372
05:02:40 INFO - 1499169760688 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49768
05:02:40 INFO - 1499169760689 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760689 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760691 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760694 Marionette TRACE 373 <- [1,1,null,{"sessionId":"7c70c950-87d7-4e43-acc7-27d48cc887e1","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760695 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
05:02:40 INFO - 1499169760695 Marionette TRACE 373 <- [1,2,null,{}]
05:02:40 INFO - 1499169760696 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:40 INFO - 1499169760697 Marionette TRACE 373 <- [1,3,null,{}]
05:02:40 INFO - 1499169760698 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:40 INFO - 1499169760698 Marionette TRACE 373 <- [1,4,null,{}]
05:02:40 INFO - 1499169760700 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:02:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499169760702 Marionette TRACE 373 <- [1,5,null,{"value":null}]
05:02:40 INFO - 1499169760703 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
05:02:40 INFO - 1499169760704 Marionette TRACE 373 <- [1,6,null,{}]
05:02:40 INFO - 1499169760706 Marionette DEBUG Closed connection 373
05:02:40 INFO - 1499169760706 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49769
05:02:40 INFO - 1499169760707 Marionette DEBUG Closed connection 374
05:02:40 INFO - 1499169760707 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49770
05:02:40 INFO - 1499169760708 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:40 INFO - 1499169760709 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760711 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760713 Marionette TRACE 375 <- [1,1,null,{"sessionId":"0d97c141-5899-4377-954b-1c1c8be2ff87","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760714 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
05:02:40 INFO - 1499169760715 Marionette TRACE 375 <- [1,2,null,{}]
05:02:40 INFO - 1499169760717 Marionette DEBUG Closed connection 375
05:02:40 INFO - 1499169760717 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49771
05:02:40 INFO - 1499169760718 Marionette DEBUG Closed connection 376
05:02:40 INFO - 1499169760719 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49772
05:02:40 INFO - 1499169760720 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:40 INFO - 1499169760720 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:40 INFO - 1499169760722 Marionette DEBUG Register listener.js for window 12
05:02:40 INFO - 1499169760724 Marionette TRACE 377 <- [1,1,null,{"sessionId":"9079717c-d64e-4fab-9e86-e247f60e8734","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:40 INFO - 1499169760725 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:02:40 INFO - 1499169760726 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760728 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760728 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:40 INFO - 1499169760737 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:02:40 INFO - 1499169760740 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:02:40 INFO - 1499169760748 Marionette TRACE 377 <- [1,2,null,{}]
05:02:40 INFO - 1499169760751 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:02:40 INFO - 1499169760753 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bccd7365-4932-4938-b1f8-4ccb18a0b2e7","ELEMENT":"bccd7365-4932-4938-b1f8-4ccb18a0b2e7"}}]
05:02:40 INFO - 1499169760754 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"bccd7365-4932-4938-b1f8-4ccb18a0b2e7"}]
05:02:40 INFO - 1499169760987 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:40 INFO - 1499169760987 Marionette TRACE 377 <- [1,4,null,{}]
05:02:40 INFO - 1499169760989 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:02:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499169760991 Marionette TRACE 377 <- [1,5,null,{"value":null}]
05:02:40 INFO - 1499169760992 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
05:02:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 308ms
05:02:40 INFO - 1499169760993 Marionette TRACE 377 <- [1,6,null,{}]
05:02:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:02:40 INFO - 1499169760995 Marionette DEBUG Closed connection 377
05:02:40 INFO - 1499169760997 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49773
05:02:40 INFO - 1499169760997 Marionette DEBUG Closed connection 378
05:02:40 INFO - 1499169760998 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49774
05:02:41 INFO - 1499169760999 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169760999 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761001 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761004 Marionette TRACE 379 <- [1,1,null,{"sessionId":"b55bb516-0844-4be7-8d85-b7896ed91cb4","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761005 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
05:02:41 INFO - 1499169761005 Marionette TRACE 379 <- [1,2,null,{}]
05:02:41 INFO - 1499169761006 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:41 INFO - 1499169761006 Marionette TRACE 379 <- [1,3,null,{}]
05:02:41 INFO - 1499169761007 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:41 INFO - 1499169761008 Marionette TRACE 379 <- [1,4,null,{}]
05:02:41 INFO - 1499169761009 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:02:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499169761011 Marionette TRACE 379 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761012 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
05:02:41 INFO - 1499169761013 Marionette TRACE 379 <- [1,6,null,{}]
05:02:41 INFO - 1499169761015 Marionette DEBUG Closed connection 379
05:02:41 INFO - 1499169761015 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49775
05:02:41 INFO - 1499169761016 Marionette DEBUG Closed connection 380
05:02:41 INFO - 1499169761017 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49776
05:02:41 INFO - 1499169761018 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761018 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761020 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761023 Marionette TRACE 381 <- [1,1,null,{"sessionId":"c250b59b-0ae7-42f8-98ee-1f2cf9e896ea","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761024 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
05:02:41 INFO - 1499169761025 Marionette TRACE 381 <- [1,2,null,{}]
05:02:41 INFO - 1499169761026 Marionette DEBUG Closed connection 381
05:02:41 INFO - 1499169761027 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49777
05:02:41 INFO - 1499169761028 Marionette DEBUG Closed connection 382
05:02:41 INFO - 1499169761028 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49778
05:02:41 INFO - 1499169761029 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:41 INFO - 1499169761030 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761032 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761034 Marionette TRACE 383 <- [1,1,null,{"sessionId":"caf6a84a-06e2-430a-8d1a-3808efa70415","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761036 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:02:41 INFO - 1499169761037 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:02:41 INFO - 1499169761038 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:02:41 INFO - 1499169761039 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:02:41 INFO - 1499169761048 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:41 INFO - 1499169761051 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:41 INFO - 1499169761053 Marionette TRACE 383 <- [1,2,null,{}]
05:02:41 INFO - 1499169761062 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:02:41 INFO - 1499169761065 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ee6d96f-c681-4973-a0d8-c8afdf52f0cd","ELEMENT":"2ee6d96f-c681-4973-a0d8-c8afdf52f0cd"}}]
05:02:41 INFO - 1499169761066 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"2ee6d96f-c681-4973-a0d8-c8afdf52f0cd"}]
05:02:41 INFO - 1499169761308 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:41 INFO - 1499169761308 Marionette TRACE 383 <- [1,4,null,{}]
05:02:41 INFO - 1499169761310 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:02:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499169761312 Marionette TRACE 383 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761313 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
05:02:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 320ms
05:02:41 INFO - TEST-START | test_click.py TestClick.test_input_file
05:02:41 INFO - 1499169761314 Marionette TRACE 383 <- [1,6,null,{}]
05:02:41 INFO - 1499169761316 Marionette DEBUG Closed connection 383
05:02:41 INFO - 1499169761316 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49779
05:02:41 INFO - 1499169761317 Marionette DEBUG Closed connection 384
05:02:41 INFO - 1499169761318 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49780
05:02:41 INFO - 1499169761319 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761319 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761321 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761324 Marionette TRACE 385 <- [1,1,null,{"sessionId":"31d8ef1e-1e66-4c54-a160-4451beca7899","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761325 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
05:02:41 INFO - 1499169761325 Marionette TRACE 385 <- [1,2,null,{}]
05:02:41 INFO - 1499169761326 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:41 INFO - 1499169761326 Marionette TRACE 385 <- [1,3,null,{}]
05:02:41 INFO - 1499169761327 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:41 INFO - 1499169761328 Marionette TRACE 385 <- [1,4,null,{}]
05:02:41 INFO - 1499169761329 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:02:41 INFO - TEST-START: test_click.py TestClick.test_input_file1499169761331 Marionette TRACE 385 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761332 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
05:02:41 INFO - 1499169761333 Marionette TRACE 385 <- [1,6,null,{}]
05:02:41 INFO - 1499169761334 Marionette DEBUG Closed connection 385
05:02:41 INFO - 1499169761335 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49781
05:02:41 INFO - 1499169761335 Marionette DEBUG Closed connection 386
05:02:41 INFO - 1499169761336 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49782
05:02:41 INFO - 1499169761337 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761337 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761339 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761342 Marionette TRACE 387 <- [1,1,null,{"sessionId":"0751fe5d-cd94-4251-9ba8-fbcc43a5caa7","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761343 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
05:02:41 INFO - 1499169761344 Marionette TRACE 387 <- [1,2,null,{}]
05:02:41 INFO - 1499169761345 Marionette DEBUG Closed connection 387
05:02:41 INFO - 1499169761345 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49783
05:02:41 INFO - 1499169761346 Marionette DEBUG Closed connection 388
05:02:41 INFO - 1499169761347 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49784
05:02:41 INFO - 1499169761348 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:41 INFO - 1499169761348 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761350 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761352 Marionette TRACE 389 <- [1,1,null,{"sessionId":"9da6e245-a78a-4b11-813a-254b6a0885a4","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761353 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:02:41 INFO - 1499169761354 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:41 INFO - 1499169761356 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:41 INFO - 1499169761356 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:02:41 INFO - 1499169761364 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:41 INFO - 1499169761367 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:41 INFO - 1499169761370 Marionette TRACE 389 <- [1,2,null,{}]
05:02:41 INFO - 1499169761371 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:02:41 INFO - 1499169761379 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa3b001d-f036-4d57-9696-62c48c5b6619","ELEMENT":"fa3b001d-f036-4d57-9696-62c48c5b6619"}}]
05:02:41 INFO - 1499169761380 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"fa3b001d-f036-4d57-9696-62c48c5b6619"}]
05:02:41 INFO - 1499169761403 Marionette TRACE 389 <- [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:1318:5\n"},null]
05:02:41 INFO - 1499169761405 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:02:41 INFO - TEST-END: test_click.py TestClick.test_input_file1499169761407 Marionette TRACE 389 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761408 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
05:02:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 95ms
05:02:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:02:41 INFO - 1499169761409 Marionette TRACE 389 <- [1,6,null,{}]
05:02:41 INFO - 1499169761411 Marionette DEBUG Closed connection 389
05:02:41 INFO - 1499169761411 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49785
05:02:41 INFO - 1499169761412 Marionette DEBUG Closed connection 390
05:02:41 INFO - 1499169761413 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49786
05:02:41 INFO - 1499169761414 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761414 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761416 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761419 Marionette TRACE 391 <- [1,1,null,{"sessionId":"5f8ca6a6-302f-4c6f-b941-afef61e075ac","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761420 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
05:02:41 INFO - 1499169761421 Marionette TRACE 391 <- [1,2,null,{}]
05:02:41 INFO - 1499169761421 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:41 INFO - 1499169761422 Marionette TRACE 391 <- [1,3,null,{}]
05:02:41 INFO - 1499169761423 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:41 INFO - 1499169761423 Marionette TRACE 391 <- [1,4,null,{}]
05:02:41 INFO - 1499169761424 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:02:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499169761426 Marionette TRACE 391 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761427 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
05:02:41 INFO - 1499169761428 Marionette TRACE 391 <- [1,6,null,{}]
05:02:41 INFO - 1499169761430 Marionette DEBUG Closed connection 391
05:02:41 INFO - 1499169761431 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49787
05:02:41 INFO - 1499169761431 Marionette DEBUG Closed connection 392
05:02:41 INFO - 1499169761432 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49788
05:02:41 INFO - 1499169761432 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761439 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761441 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761444 Marionette TRACE 393 <- [1,1,null,{"sessionId":"e6661605-d5a4-41e7-b902-1df8ed44de2e","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761445 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
05:02:41 INFO - 1499169761446 Marionette TRACE 393 <- [1,2,null,{}]
05:02:41 INFO - 1499169761448 Marionette DEBUG Closed connection 393
05:02:41 INFO - 1499169761448 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49789
05:02:41 INFO - 1499169761450 Marionette DEBUG Closed connection 394
05:02:41 INFO - 1499169761450 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49790
05:02:41 INFO - 1499169761451 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:41 INFO - 1499169761452 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761453 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761456 Marionette TRACE 395 <- [1,1,null,{"sessionId":"4c5791a8-e4b2-4756-ba70-657f62716161","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761457 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:02:41 INFO - 1499169761458 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:41 INFO - 1499169761460 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:41 INFO - 1499169761460 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:41 INFO - 1499169761469 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:02:41 INFO - 1499169761473 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:02:41 INFO - 1499169761480 Marionette TRACE 395 <- [1,2,null,{}]
05:02:41 INFO - 1499169761482 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:02:41 INFO - 1499169761484 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38b7f620-5f7f-4e74-8b9d-b1fea26e6ed4","ELEMENT":"38b7f620-5f7f-4e74-8b9d-b1fea26e6ed4"}}]
05:02:41 INFO - 1499169761485 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:02:41 INFO - 1499169761486 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"080ae8d9-e651-40eb-b166-205c57202857","ELEMENT":"080ae8d9-e651-40eb-b166-205c57202857"}}]
05:02:41 INFO - 1499169761487 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"38b7f620-5f7f-4e74-8b9d-b1fea26e6ed4"}]
05:02:41 INFO - 1499169761721 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:41 INFO - 1499169761721 Marionette TRACE 395 <- [1,5,null,{}]
05:02:41 INFO - 1499169761722 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"080ae8d9-e651-40eb-b166-205c57202857"}]
05:02:41 INFO - 1499169761738 Marionette TRACE 395 <- [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:1318:5\n"},null]
05:02:41 INFO - 1499169761740 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:02:41 INFO - 1499169761742 Marionette TRACE 395 <- [1,7,null,{"value":false}]
05:02:41 INFO - 1499169761744 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:02:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499169761746 Marionette TRACE 395 <- [1,8,null,{"value":null}]
05:02:41 INFO - 1499169761747 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
05:02:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
05:02:41 INFO - 1499169761748 Marionette TRACE 395 <- [1,9,null,{}]
05:02:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:02:41 INFO - 1499169761750 Marionette DEBUG Closed connection 395
05:02:41 INFO - 1499169761751 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49791
05:02:41 INFO - 1499169761752 Marionette DEBUG Closed connection 396
05:02:41 INFO - 1499169761753 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49792
05:02:41 INFO - 1499169761754 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761754 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761756 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761758 Marionette TRACE 397 <- [1,1,null,{"sessionId":"b88c00e1-c379-4a84-8756-3c11a0225c7c","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761760 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
05:02:41 INFO - 1499169761760 Marionette TRACE 397 <- [1,2,null,{}]
05:02:41 INFO - 1499169761761 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:41 INFO - 1499169761761 Marionette TRACE 397 <- [1,3,null,{}]
05:02:41 INFO - 1499169761762 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:41 INFO - 1499169761762 Marionette TRACE 397 <- [1,4,null,{}]
05:02:41 INFO - 1499169761764 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:02:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499169761765 Marionette TRACE 397 <- [1,5,null,{"value":null}]
05:02:41 INFO - 1499169761767 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
05:02:41 INFO - 1499169761768 Marionette TRACE 397 <- [1,6,null,{}]
05:02:41 INFO - 1499169761769 Marionette DEBUG Closed connection 397
05:02:41 INFO - 1499169761770 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49793
05:02:41 INFO - 1499169761770 Marionette DEBUG Closed connection 398
05:02:41 INFO - 1499169761771 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49794
05:02:41 INFO - 1499169761772 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:41 INFO - 1499169761772 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761775 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761777 Marionette TRACE 399 <- [1,1,null,{"sessionId":"e9a2eda0-0a77-4515-9e81-33dc64762b04","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761778 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
05:02:41 INFO - 1499169761779 Marionette TRACE 399 <- [1,2,null,{}]
05:02:41 INFO - 1499169761780 Marionette DEBUG Closed connection 399
05:02:41 INFO - 1499169761781 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49795
05:02:41 INFO - 1499169761782 Marionette DEBUG Closed connection 400
05:02:41 INFO - 1499169761782 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49796
05:02:41 INFO - 1499169761783 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:41 INFO - 1499169761784 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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}
05:02:41 INFO - 1499169761786 Marionette DEBUG Register listener.js for window 12
05:02:41 INFO - 1499169761788 Marionette TRACE 401 <- [1,1,null,{"sessionId":"9c167be9-1189-46d1-81ef-a253a14b90d0","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":2160,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-066.000\\AppData\\Local\\Temp\\tmpzwsacl.mozrunner","moz:accessibilityChecks":false}}]
05:02:41 INFO - 1499169761789 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:02:41 INFO - 1499169761790 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:02:41 INFO - 1499169761793 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:02:41 INFO - 1499169761793 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:02:41 INFO - 1499169761803 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:02:41 INFO - 1499169761806 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:02:41 INFO - 1499169761814 Marionette TRACE 401 <- [1,2,null,{}]
05:02:41 INFO - 1499169761817 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:02:41 INFO - 1499169761819 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c766f40-0eaa-42de-91ee-8490c02ed5b7","ELEMENT":"3c766f40-0eaa-42de-91ee-8490c02ed5b7"}}]
05:02:41 INFO - 1499169761820 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3c766f40-0eaa-42de-91ee-8490c02ed5b7"}]
05:02:41 INFO - 1499169761846 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
05:02:41 INFO - 1499169761847 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"3c766f40-0eaa-42de-91ee-8490c02ed5b7"}]
05:02:41 INFO - 1499169761863 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element