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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:38:18 INFO - 1501076298927 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}]
06:38:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501076298929 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:38:18 INFO - 1501076298930 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:38:18 INFO - 1501076298932 Marionette TRACE 353 <- [1,6,null,{}]
06:38:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 124ms
06:38:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:38:18 INFO - 1501076298933 Marionette DEBUG Closed connection 353
06:38:18 INFO - 1501076298934 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49750
06:38:18 INFO - 1501076298935 Marionette DEBUG Closed connection 354
06:38:18 INFO - 1501076298935 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49751
06:38:18 INFO - 1501076298936 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:18 INFO - 1501076298937 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:18 INFO - 1501076298939 Marionette DEBUG Register listener.js for window 12
06:38:18 INFO - 1501076298941 Marionette TRACE 355 <- [1,1,null,{"sessionId":"d9c066af-523a-4bf3-8237-8ee8b68f9cc2","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:18 INFO - 1501076298943 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:38:18 INFO - 1501076298944 Marionette TRACE 355 <- [1,2,null,{}]
06:38:18 INFO - 1501076298945 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:18 INFO - 1501076298945 Marionette TRACE 355 <- [1,3,null,{}]
06:38:18 INFO - 1501076298946 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:18 INFO - 1501076298946 Marionette TRACE 355 <- [1,4,null,{}]
06:38:18 INFO - 1501076298947 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}]
06:38:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501076298950 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:38:18 INFO - 1501076298951 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:38:18 INFO - 1501076298952 Marionette TRACE 355 <- [1,6,null,{}]
06:38:18 INFO - 1501076298954 Marionette DEBUG Closed connection 355
06:38:18 INFO - 1501076298954 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49752
06:38:18 INFO - 1501076298955 Marionette DEBUG Closed connection 356
06:38:18 INFO - 1501076298956 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49753
06:38:18 INFO - 1501076298957 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:18 INFO - 1501076298957 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:18 INFO - 1501076298959 Marionette DEBUG Register listener.js for window 12
06:38:18 INFO - 1501076298961 Marionette TRACE 357 <- [1,1,null,{"sessionId":"d7e92fdf-8470-454f-8d53-8d9ac555742a","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:18 INFO - 1501076298963 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:38:18 INFO - 1501076298964 Marionette TRACE 357 <- [1,2,null,{}]
06:38:18 INFO - 1501076298966 Marionette DEBUG Closed connection 357
06:38:18 INFO - 1501076298966 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49754
06:38:18 INFO - 1501076298967 Marionette DEBUG Closed connection 358
06:38:18 INFO - 1501076298967 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49755
06:38:18 INFO - 1501076298968 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:18 INFO - 1501076298969 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:18 INFO - 1501076298970 Marionette DEBUG Register listener.js for window 12
06:38:18 INFO - 1501076298973 Marionette TRACE 359 <- [1,1,null,{"sessionId":"25dbaf1f-47a7-4e43-9eb7-710a7fa9fb3b","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:18 INFO - 1501076298975 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
06:38:18 INFO - 1501076298976 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
06:38:18 INFO - 1501076298983 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
06:38:18 INFO - 1501076298983 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
06:38:18 INFO - 1501076298993 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
06:38:18 INFO - 1501076298997 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
06:38:19 INFO - 1501076299006 Marionette TRACE 359 <- [1,2,null,{}]
06:38:19 INFO - 1501076299007 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:38:19 INFO - 1501076299009 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ddb47093-b036-42f3-87ca-bb448093b48d","ELEMENT":"ddb47093-b036-42f3-87ca-bb448093b48d"}}]
06:38:19 INFO - 1501076299010 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"ddb47093-b036-42f3-87ca-bb448093b48d"}]
06:38:19 INFO - 1501076299057 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
06:38:19 INFO - 1501076299061 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
06:38:19 INFO - 1501076299061 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
06:38:19 INFO - 1501076299077 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
06:38:19 INFO - 1501076299080 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
06:38:19 INFO - 1501076299087 Marionette TRACE 359 <- [1,4,null,{}]
06:38:19 INFO - 1501076299088 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:38:19 INFO - 1501076299090 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"78a31c34-f7ad-4fa2-8f9e-3795dc92e3e2","ELEMENT":"78a31c34-f7ad-4fa2-8f9e-3795dc92e3e2"}}]
06:38:19 INFO - 1501076299091 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:38:19 INFO - 1501076299092 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:38:19 INFO - 1501076299093 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}]
06:38:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501076299095 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:38:19 INFO - 1501076299097 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:38:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
06:38:19 INFO - TEST-START | test_click.py TestClick.test_container_element
06:38:19 INFO - 1501076299098 Marionette TRACE 359 <- [1,8,null,{}]
06:38:19 INFO - 1501076299100 Marionette DEBUG Closed connection 359
06:38:19 INFO - 1501076299101 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49758
06:38:19 INFO - 1501076299102 Marionette DEBUG Closed connection 360
06:38:19 INFO - 1501076299102 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49759
06:38:19 INFO - 1501076299103 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299104 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299105 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299109 Marionette TRACE 361 <- [1,1,null,{"sessionId":"c69fe11d-b85b-4976-988f-0f81054e0239","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299111 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:38:19 INFO - 1501076299111 Marionette TRACE 361 <- [1,2,null,{}]
06:38:19 INFO - 1501076299113 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:19 INFO - 1501076299114 Marionette TRACE 361 <- [1,3,null,{}]
06:38:19 INFO - 1501076299115 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:19 INFO - 1501076299115 Marionette TRACE 361 <- [1,4,null,{}]
06:38:19 INFO - 1501076299116 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}]
06:38:19 INFO - TEST-START: test_click.py TestClick.test_container_element1501076299118 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:38:19 INFO - 1501076299119 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:38:19 INFO - 1501076299120 Marionette TRACE 361 <- [1,6,null,{}]
06:38:19 INFO - 1501076299122 Marionette DEBUG Closed connection 361
06:38:19 INFO - 1501076299123 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49760
06:38:19 INFO - 1501076299124 Marionette DEBUG Closed connection 362
06:38:19 INFO - 1501076299124 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49761
06:38:19 INFO - 1501076299125 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299126 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299127 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299131 Marionette TRACE 363 <- [1,1,null,{"sessionId":"d6c1659f-ff39-46eb-b0b5-b48c8e64bf50","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299133 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:38:19 INFO - 1501076299135 Marionette TRACE 363 <- [1,2,null,{}]
06:38:19 INFO - 1501076299137 Marionette DEBUG Closed connection 363
06:38:19 INFO - 1501076299138 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49762
06:38:19 INFO - 1501076299138 Marionette DEBUG Closed connection 364
06:38:19 INFO - 1501076299139 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49763
06:38:19 INFO - 1501076299140 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:19 INFO - 1501076299141 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299142 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299145 Marionette TRACE 365 <- [1,1,null,{"sessionId":"a535ccdf-2c18-4204-9acb-e10786e650fd","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299148 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"}]
06:38:19 INFO - 1501076299149 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
06:38:19 INFO - 1501076299151 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
06:38:19 INFO - 1501076299151 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
06:38:19 INFO - 1501076299173 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"
06:38:19 INFO - 1501076299174 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"
06:38:19 INFO - 1501076299177 Marionette TRACE 365 <- [1,2,null,{}]
06:38:19 INFO - 1501076299178 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:38:19 INFO - 1501076299186 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"96533bbd-c9e5-4b47-acbf-1341a480249f","ELEMENT":"96533bbd-c9e5-4b47-acbf-1341a480249f"}}]
06:38:19 INFO - 1501076299188 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"96533bbd-c9e5-4b47-acbf-1341a480249f"}]
06:38:19 INFO - 1501076299426 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:38:19 INFO - 1501076299427 Marionette TRACE 365 <- [1,4,null,{}]
06:38:19 INFO - 1501076299428 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"96533bbd-c9e5-4b47-acbf-1341a480249f","name":"selected"}]
06:38:19 INFO - 1501076299432 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:38:19 INFO - 1501076299434 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}]
06:38:19 INFO - TEST-END: test_click.py TestClick.test_container_element1501076299435 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:38:19 INFO - 1501076299436 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:38:19 INFO - 1501076299437 Marionette TRACE 365 <- [1,7,null,{}]
06:38:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 339ms
06:38:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:38:19 INFO - 1501076299439 Marionette DEBUG Closed connection 365
06:38:19 INFO - 1501076299440 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49764
06:38:19 INFO - 1501076299441 Marionette DEBUG Closed connection 366
06:38:19 INFO - 1501076299441 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49765
06:38:19 INFO - 1501076299442 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299443 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299445 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299447 Marionette TRACE 367 <- [1,1,null,{"sessionId":"c85db68a-9bed-4105-8e2e-c13ea6f05f50","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299455 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:38:19 INFO - 1501076299456 Marionette TRACE 367 <- [1,2,null,{}]
06:38:19 INFO - 1501076299457 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:19 INFO - 1501076299457 Marionette TRACE 367 <- [1,3,null,{}]
06:38:19 INFO - 1501076299458 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:19 INFO - 1501076299459 Marionette TRACE 367 <- [1,4,null,{}]
06:38:19 INFO - 1501076299461 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}]
06:38:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501076299467 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:38:19 INFO - 1501076299469 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:38:19 INFO - 1501076299470 Marionette TRACE 367 <- [1,6,null,{}]
06:38:19 INFO - 1501076299474 Marionette DEBUG Closed connection 367
06:38:19 INFO - 1501076299474 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49766
06:38:19 INFO - 1501076299475 Marionette DEBUG Closed connection 368
06:38:19 INFO - 1501076299476 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49767
06:38:19 INFO - 1501076299476 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299477 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299479 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299482 Marionette TRACE 369 <- [1,1,null,{"sessionId":"4b96294a-071a-4077-ac7b-41e74c7365df","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299484 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:38:19 INFO - 1501076299485 Marionette TRACE 369 <- [1,2,null,{}]
06:38:19 INFO - 1501076299486 Marionette DEBUG Closed connection 369
06:38:19 INFO - 1501076299487 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49768
06:38:19 INFO - 1501076299487 Marionette DEBUG Closed connection 370
06:38:19 INFO - 1501076299488 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49769
06:38:19 INFO - 1501076299489 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:19 INFO - 1501076299490 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299491 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299494 Marionette TRACE 371 <- [1,1,null,{"sessionId":"4d255578-83e3-4b7c-b1f7-36dcb6cf373f","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299495 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"}]
06:38:19 INFO - 1501076299496 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"
06:38:19 INFO - 1501076299498 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"
06:38:19 INFO - 1501076299498 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"
06:38:19 INFO - 1501076299507 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"
06:38:19 INFO - 1501076299510 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"
06:38:19 INFO - 1501076299513 Marionette TRACE 371 <- [1,2,null,{}]
06:38:19 INFO - 1501076299521 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:38:19 INFO - 1501076299523 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b751630d-b629-4981-89db-f4bd2c7831e7","ELEMENT":"b751630d-b629-4981-89db-f4bd2c7831e7"}}]
06:38:19 INFO - 1501076299524 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"b751630d-b629-4981-89db-f4bd2c7831e7"}]
06:38:19 INFO - 1501076299762 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:38:19 INFO - 1501076299763 Marionette TRACE 371 <- [1,4,null,{}]
06:38:19 INFO - 1501076299764 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"b751630d-b629-4981-89db-f4bd2c7831e7","name":"selected"}]
06:38:19 INFO - 1501076299768 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:38:19 INFO - 1501076299770 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}]
06:38:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501076299772 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:38:19 INFO - 1501076299773 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:38:19 INFO - 1501076299774 Marionette TRACE 371 <- [1,7,null,{}]
06:38:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 336ms
06:38:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:38:19 INFO - 1501076299776 Marionette DEBUG Closed connection 371
06:38:19 INFO - 1501076299777 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49770
06:38:19 INFO - 1501076299777 Marionette DEBUG Closed connection 372
06:38:19 INFO - 1501076299778 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49771
06:38:19 INFO - 1501076299780 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299781 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299782 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299785 Marionette TRACE 373 <- [1,1,null,{"sessionId":"61cac211-aaa5-4021-88a1-b9982fef5b37","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299787 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:38:19 INFO - 1501076299787 Marionette TRACE 373 <- [1,2,null,{}]
06:38:19 INFO - 1501076299788 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:19 INFO - 1501076299788 Marionette TRACE 373 <- [1,3,null,{}]
06:38:19 INFO - 1501076299789 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:19 INFO - 1501076299789 Marionette TRACE 373 <- [1,4,null,{}]
06:38:19 INFO - 1501076299791 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}]
06:38:19 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501076299793 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:38:19 INFO - 1501076299794 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:38:19 INFO - 1501076299795 Marionette TRACE 373 <- [1,6,null,{}]
06:38:19 INFO - 1501076299797 Marionette DEBUG Closed connection 373
06:38:19 INFO - 1501076299798 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49772
06:38:19 INFO - 1501076299799 Marionette DEBUG Closed connection 374
06:38:19 INFO - 1501076299799 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49773
06:38:19 INFO - 1501076299800 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:19 INFO - 1501076299800 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299802 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299804 Marionette TRACE 375 <- [1,1,null,{"sessionId":"a09cc6ac-fefb-47dd-aabf-e373fdc21351","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299806 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:38:19 INFO - 1501076299807 Marionette TRACE 375 <- [1,2,null,{}]
06:38:19 INFO - 1501076299809 Marionette DEBUG Closed connection 375
06:38:19 INFO - 1501076299810 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49774
06:38:19 INFO - 1501076299810 Marionette DEBUG Closed connection 376
06:38:19 INFO - 1501076299811 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49775
06:38:19 INFO - 1501076299812 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:19 INFO - 1501076299812 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:19 INFO - 1501076299814 Marionette DEBUG Register listener.js for window 12
06:38:19 INFO - 1501076299817 Marionette TRACE 377 <- [1,1,null,{"sessionId":"bd982058-2266-417a-b19f-8f98c20c699e","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:19 INFO - 1501076299818 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"}]
06:38:19 INFO - 1501076299820 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"
06:38:19 INFO - 1501076299821 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"
06:38:19 INFO - 1501076299822 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"
06:38:19 INFO - 1501076299838 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"
06:38:19 INFO - 1501076299840 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"
06:38:19 INFO - 1501076299843 Marionette TRACE 377 <- [1,2,null,{}]
06:38:19 INFO - 1501076299849 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:38:19 INFO - 1501076299852 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aedb952f-5ee1-4013-acd5-e3192bedbe5f","ELEMENT":"aedb952f-5ee1-4013-acd5-e3192bedbe5f"}}]
06:38:19 INFO - 1501076299854 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"aedb952f-5ee1-4013-acd5-e3192bedbe5f"}]
06:38:20 INFO - 1501076300096 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:38:20 INFO - 1501076300097 Marionette TRACE 377 <- [1,4,null,{}]
06:38:20 INFO - 1501076300099 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}]
06:38:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501076300101 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300102 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:38:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 328ms
06:38:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:38:20 INFO - 1501076300103 Marionette TRACE 377 <- [1,6,null,{}]
06:38:20 INFO - 1501076300105 Marionette DEBUG Closed connection 377
06:38:20 INFO - 1501076300106 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49776
06:38:20 INFO - 1501076300107 Marionette DEBUG Closed connection 378
06:38:20 INFO - 1501076300107 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49777
06:38:20 INFO - 1501076300108 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300108 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300110 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300113 Marionette TRACE 379 <- [1,1,null,{"sessionId":"09274ce8-a124-4041-b8f4-a13b7e0139f6","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300114 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:38:20 INFO - 1501076300115 Marionette TRACE 379 <- [1,2,null,{}]
06:38:20 INFO - 1501076300115 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:20 INFO - 1501076300115 Marionette TRACE 379 <- [1,3,null,{}]
06:38:20 INFO - 1501076300116 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:20 INFO - 1501076300116 Marionette TRACE 379 <- [1,4,null,{}]
06:38:20 INFO - 1501076300118 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}]
06:38:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501076300120 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300121 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300122 Marionette TRACE 379 <- [1,6,null,{}]
06:38:20 INFO - 1501076300124 Marionette DEBUG Closed connection 379
06:38:20 INFO - 1501076300124 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49778
06:38:20 INFO - 1501076300125 Marionette DEBUG Closed connection 380
06:38:20 INFO - 1501076300125 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49779
06:38:20 INFO - 1501076300126 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300127 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300129 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300131 Marionette TRACE 381 <- [1,1,null,{"sessionId":"3b1be67c-4e9a-4b25-a603-f4455948b6db","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300133 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:38:20 INFO - 1501076300134 Marionette TRACE 381 <- [1,2,null,{}]
06:38:20 INFO - 1501076300135 Marionette DEBUG Closed connection 381
06:38:20 INFO - 1501076300136 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49780
06:38:20 INFO - 1501076300137 Marionette DEBUG Closed connection 382
06:38:20 INFO - 1501076300137 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49781
06:38:20 INFO - 1501076300138 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:20 INFO - 1501076300139 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300140 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300143 Marionette TRACE 383 <- [1,1,null,{"sessionId":"423fb420-46d5-4538-97a8-219d927ba857","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300144 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"}]
06:38:20 INFO - 1501076300145 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"
06:38:20 INFO - 1501076300147 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"
06:38:20 INFO - 1501076300147 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"
06:38:20 INFO - 1501076300156 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"
06:38:20 INFO - 1501076300159 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"
06:38:20 INFO - 1501076300161 Marionette TRACE 383 <- [1,2,null,{}]
06:38:20 INFO - 1501076300170 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:38:20 INFO - 1501076300172 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4734d3c9-236e-4454-bfed-f40c1a0525f4","ELEMENT":"4734d3c9-236e-4454-bfed-f40c1a0525f4"}}]
06:38:20 INFO - 1501076300173 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"4734d3c9-236e-4454-bfed-f40c1a0525f4"}]
06:38:20 INFO - 1501076300410 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:38:20 INFO - 1501076300411 Marionette TRACE 383 <- [1,4,null,{}]
06:38:20 INFO - 1501076300413 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}]
06:38:20 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501076300415 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300416 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300417 Marionette TRACE 383 <- [1,6,null,{}]
06:38:20 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 314ms
06:38:20 INFO - TEST-START | test_click.py TestClick.test_input_file
06:38:20 INFO - 1501076300419 Marionette DEBUG Closed connection 383
06:38:20 INFO - 1501076300420 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49782
06:38:20 INFO - 1501076300420 Marionette DEBUG Closed connection 384
06:38:20 INFO - 1501076300421 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49783
06:38:20 INFO - 1501076300422 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300422 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300424 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300426 Marionette TRACE 385 <- [1,1,null,{"sessionId":"b781e6bb-3c63-43a3-91e0-6082e32ea5d6","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300429 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:38:20 INFO - 1501076300429 Marionette TRACE 385 <- [1,2,null,{}]
06:38:20 INFO - 1501076300430 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:20 INFO - 1501076300430 Marionette TRACE 385 <- [1,3,null,{}]
06:38:20 INFO - 1501076300431 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:20 INFO - 1501076300432 Marionette TRACE 385 <- [1,4,null,{}]
06:38:20 INFO - 1501076300433 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}]
06:38:20 INFO - TEST-START: test_click.py TestClick.test_input_file1501076300435 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300436 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300437 Marionette TRACE 385 <- [1,6,null,{}]
06:38:20 INFO - 1501076300439 Marionette DEBUG Closed connection 385
06:38:20 INFO - 1501076300439 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49784
06:38:20 INFO - 1501076300440 Marionette DEBUG Closed connection 386
06:38:20 INFO - 1501076300440 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49785
06:38:20 INFO - 1501076300441 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300442 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300443 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300453 Marionette TRACE 387 <- [1,1,null,{"sessionId":"46e7efa8-4dea-4408-bbdf-aab3b58e7874","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300455 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:38:20 INFO - 1501076300456 Marionette TRACE 387 <- [1,2,null,{}]
06:38:20 INFO - 1501076300458 Marionette DEBUG Closed connection 387
06:38:20 INFO - 1501076300459 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49786
06:38:20 INFO - 1501076300459 Marionette DEBUG Closed connection 388
06:38:20 INFO - 1501076300460 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49787
06:38:20 INFO - 1501076300461 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:20 INFO - 1501076300461 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300463 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300465 Marionette TRACE 389 <- [1,1,null,{"sessionId":"38d9321b-4a40-40e4-9105-4cfa3631ccca","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300467 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:38:20 INFO - 1501076300468 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"
06:38:20 INFO - 1501076300470 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"
06:38:20 INFO - 1501076300470 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"
06:38:20 INFO - 1501076300486 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:38:20 INFO - 1501076300487 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:38:20 INFO - 1501076300491 Marionette TRACE 389 <- [1,2,null,{}]
06:38:20 INFO - 1501076300492 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:38:20 INFO - 1501076300494 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9fbc2b59-2d16-4200-97f9-575f451c2deb","ELEMENT":"9fbc2b59-2d16-4200-97f9-575f451c2deb"}}]
06:38:20 INFO - 1501076300495 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"9fbc2b59-2d16-4200-97f9-575f451c2deb"}]
06:38:20 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
06:38:20 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:38:20 INFO - 1501076300525 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:38:20 INFO - 1501076300527 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}]
06:38:20 INFO - TEST-END: test_click.py TestClick.test_input_file1501076300530 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300530 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300532 Marionette TRACE 389 <- [1,6,null,{}]
06:38:20 INFO - 1501076300533 Marionette DEBUG Closed connection 389
06:38:20 INFO - 1501076300534 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49788
06:38:20 INFO - 1501076300535 Marionette DEBUG Closed connection 390
06:38:20 INFO - 1501076300536 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49789
06:38:20 INFO - 1501076300537 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300537 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300539 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300541 Marionette TRACE 391 <- [1,1,null,{"sessionId":"6d30158e-3309-43f7-84dc-2ee2a3977b83","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300543 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:38:20 INFO - 1501076300543 Marionette TRACE 391 <- [1,2,null,{}]
06:38:20 INFO - 1501076300544 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:20 INFO - 1501076300544 Marionette TRACE 391 <- [1,3,null,{}]
06:38:20 INFO - 1501076300545 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:20 INFO - 1501076300546 Marionette TRACE 391 <- [1,4,null,{}]
06:38:20 INFO - 1501076300547 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}]
06:38:20 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501076300549 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300550 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300552 Marionette TRACE 391 <- [1,6,null,{}]
06:38:20 INFO - 1501076300553 Marionette DEBUG Closed connection 391
06:38:20 INFO - 1501076300554 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49790
06:38:20 INFO - 1501076300554 Marionette DEBUG Closed connection 392
06:38:20 INFO - 1501076300555 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49791
06:38:20 INFO - 1501076300556 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300556 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300558 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300560 Marionette TRACE 393 <- [1,1,null,{"sessionId":"2736c890-3b4e-4396-a0ac-c6145deec9a7","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300563 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:38:20 INFO - 1501076300564 Marionette TRACE 393 <- [1,2,null,{}]
06:38:20 INFO - 1501076300566 Marionette DEBUG Closed connection 393
06:38:20 INFO - 1501076300566 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49792
06:38:20 INFO - 1501076300567 Marionette DEBUG Closed connection 394
06:38:20 INFO - 1501076300567 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49793
06:38:20 INFO - 1501076300568 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:20 INFO - 1501076300569 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300571 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300573 Marionette TRACE 395 <- [1,1,null,{"sessionId":"efe05054-2cf0-450a-827c-c1aa0f75b6bf","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300575 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"}]
06:38:20 INFO - 1501076300576 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:38:20 INFO - 1501076300578 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:38:20 INFO - 1501076300578 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:38:20 INFO - 1501076300594 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"
06:38:20 INFO - 1501076300596 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"
06:38:20 INFO - 1501076300603 Marionette TRACE 395 <- [1,2,null,{}]
06:38:20 INFO - 1501076300604 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:38:20 INFO - 1501076300606 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b841e8d-a6ab-4615-92db-1dcf9d05baea","ELEMENT":"0b841e8d-a6ab-4615-92db-1dcf9d05baea"}}]
06:38:20 INFO - 1501076300607 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:38:20 INFO - 1501076300608 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80a5b8ee-a48e-4fbc-99d3-520af265f946","ELEMENT":"80a5b8ee-a48e-4fbc-99d3-520af265f946"}}]
06:38:20 INFO - 1501076300609 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"0b841e8d-a6ab-4615-92db-1dcf9d05baea"}]
06:38:20 INFO - 1501076300850 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:38:20 INFO - 1501076300851 Marionette TRACE 395 <- [1,5,null,{}]
06:38:20 INFO - 1501076300852 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"80a5b8ee-a48e-4fbc-99d3-520af265f946"}]
06:38:20 INFO - 1501076300866 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:38:20 INFO - 1501076300867 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:38:20 INFO - 1501076300870 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:38:20 INFO - 1501076300871 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}]
06:38:20 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501076300873 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:38:20 INFO - 1501076300874 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:38:20 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 343ms
06:38:20 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:38:20 INFO - 1501076300875 Marionette TRACE 395 <- [1,9,null,{}]
06:38:20 INFO - 1501076300877 Marionette DEBUG Closed connection 395
06:38:20 INFO - 1501076300877 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49794
06:38:20 INFO - 1501076300878 Marionette DEBUG Closed connection 396
06:38:20 INFO - 1501076300879 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49795
06:38:20 INFO - 1501076300879 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300880 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300882 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300884 Marionette TRACE 397 <- [1,1,null,{"sessionId":"d1873126-8fab-4cdb-98ca-2d359bd7e2e2","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300886 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:38:20 INFO - 1501076300886 Marionette TRACE 397 <- [1,2,null,{}]
06:38:20 INFO - 1501076300887 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:38:20 INFO - 1501076300887 Marionette TRACE 397 <- [1,3,null,{}]
06:38:20 INFO - 1501076300888 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:38:20 INFO - 1501076300888 Marionette TRACE 397 <- [1,4,null,{}]
06:38:20 INFO - 1501076300890 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}]
06:38:20 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501076300892 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:38:20 INFO - 1501076300893 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:38:20 INFO - 1501076300894 Marionette TRACE 397 <- [1,6,null,{}]
06:38:20 INFO - 1501076300895 Marionette DEBUG Closed connection 397
06:38:20 INFO - 1501076300896 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49796
06:38:20 INFO - 1501076300896 Marionette DEBUG Closed connection 398
06:38:20 INFO - 1501076300897 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49797
06:38:20 INFO - 1501076300898 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:38:20 INFO - 1501076300899 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300900 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300903 Marionette TRACE 399 <- [1,1,null,{"sessionId":"becafd63-9daa-4c17-906b-1835e4072e69","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300905 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:38:20 INFO - 1501076300906 Marionette TRACE 399 <- [1,2,null,{}]
06:38:20 INFO - 1501076300908 Marionette DEBUG Closed connection 399
06:38:20 INFO - 1501076300909 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49798
06:38:20 INFO - 1501076300909 Marionette DEBUG Closed connection 400
06:38:20 INFO - 1501076300910 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49799
06:38:20 INFO - 1501076300911 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:38:20 INFO - 1501076300911 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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}
06:38:20 INFO - 1501076300913 Marionette DEBUG Register listener.js for window 12
06:38:20 INFO - 1501076300915 Marionette TRACE 401 <- [1,1,null,{"sessionId":"8005ac65-f53d-4d25-9337-e2f865f39710","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":4044,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-175\\AppData\\Local\\Temp\\tmpqqw3gh.mozrunner","moz:accessibilityChecks":false}}]
06:38:20 INFO - 1501076300917 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"}]
06:38:20 INFO - 1501076300918 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"
06:38:20 INFO - 1501076300920 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"
06:38:20 INFO - 1501076300920 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"
06:38:20 INFO - 1501076300939 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"
06:38:20 INFO - 1501076300940 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"
06:38:20 INFO - 1501076300943 Marionette TRACE 401 <- [1,2,null,{}]
06:38:20 INFO - 1501076300944 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:38:20 INFO - 1501076300951 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a7c038a-082d-41e3-b081-81e4a7b7d3d9","ELEMENT":"7a7c038a-082d-41e3-b081-81e4a7b7d3d9"}}]
06:38:20 INFO - 1501076300952 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"7a7c038a-082d-41e3-b081-81e4a7b7d3d9"}]
06:38:20 INFO - 1501076300974 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:38:20 INFO - 1501076300975 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"7a7c038a-082d-41e3-b081-81e4a7b7d3d9"}]
06:38:20 INFO - 1501076300989 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element