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]
09:53:01 INFO - 1501001581156 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:53:01 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501001581159 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:53:01 INFO - 1501001581160 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:53:01 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 119ms
09:53:01 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:53:01 INFO - 1501001581161 Marionette TRACE 352 <- [1,6,null,{}]
09:53:01 INFO - 1501001581162 Marionette DEBUG Closed connection 352
09:53:01 INFO - 1501001581163 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49762
09:53:01 INFO - 1501001581163 Marionette DEBUG Closed connection 353
09:53:01 INFO - 1501001581164 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49763
09:53:01 INFO - 1501001581165 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581166 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581167 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581170 Marionette TRACE 354 <- [1,1,null,{"sessionId":"31989735-4f90-4e9a-b1c8-5aaf6a6324b1","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581171 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:53:01 INFO - 1501001581172 Marionette TRACE 354 <- [1,2,null,{}]
09:53:01 INFO - 1501001581173 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:01 INFO - 1501001581173 Marionette TRACE 354 <- [1,3,null,{}]
09:53:01 INFO - 1501001581174 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:01 INFO - 1501001581174 Marionette TRACE 354 <- [1,4,null,{}]
09:53:01 INFO - 1501001581176 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:53:01 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501001581178 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:53:01 INFO - 1501001581179 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:53:01 INFO - 1501001581180 Marionette TRACE 354 <- [1,6,null,{}]
09:53:01 INFO - 1501001581182 Marionette DEBUG Closed connection 354
09:53:01 INFO - 1501001581182 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49764
09:53:01 INFO - 1501001581183 Marionette DEBUG Closed connection 355
09:53:01 INFO - 1501001581183 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49765
09:53:01 INFO - 1501001581184 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581185 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581187 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581189 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5e4c0098-d665-49ad-acd6-f4e5a0ac68da","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581190 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:53:01 INFO - 1501001581191 Marionette TRACE 356 <- [1,2,null,{}]
09:53:01 INFO - 1501001581193 Marionette DEBUG Closed connection 356
09:53:01 INFO - 1501001581193 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49766
09:53:01 INFO - 1501001581194 Marionette DEBUG Closed connection 357
09:53:01 INFO - 1501001581194 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49767
09:53:01 INFO - 1501001581195 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:01 INFO - 1501001581196 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581198 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581208 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1b9f18dd-07d9-4c3a-bb29-6262364e8aac","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581209 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
09:53:01 INFO - 1501001581210 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
09:53:01 INFO - 1501001581217 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
09:53:01 INFO - 1501001581217 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
09:53:01 INFO - 1501001581223 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
09:53:01 INFO - 1501001581227 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
09:53:01 INFO - 1501001581230 Marionette TRACE 358 <- [1,2,null,{}]
09:53:01 INFO - 1501001581235 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:53:01 INFO - 1501001581237 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"185550c9-f1c8-4de1-b53a-80692f8f630c","ELEMENT":"185550c9-f1c8-4de1-b53a-80692f8f630c"}}]
09:53:01 INFO - 1501001581238 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"185550c9-f1c8-4de1-b53a-80692f8f630c"}]
09:53:01 INFO - 1501001581276 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
09:53:01 INFO - 1501001581283 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
09:53:01 INFO - 1501001581284 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
09:53:01 INFO - 1501001581289 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
09:53:01 INFO - 1501001581292 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
09:53:01 INFO - 1501001581304 Marionette TRACE 358 <- [1,4,null,{}]
09:53:01 INFO - 1501001581308 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:53:01 INFO - 1501001581313 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42bee8d0-c778-4b0d-b771-ecfae49046ef","ELEMENT":"42bee8d0-c778-4b0d-b771-ecfae49046ef"}}]
09:53:01 INFO - 1501001581314 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:53:01 INFO - 1501001581314 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:53:01 INFO - 1501001581316 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:53:01 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 159ms
09:53:01 INFO - TEST-START | test_click.py TestClick.test_container_element
09:53:01 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501001581318 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:53:01 INFO - 1501001581319 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:53:01 INFO - 1501001581320 Marionette TRACE 358 <- [1,8,null,{}]
09:53:01 INFO - 1501001581321 Marionette DEBUG Closed connection 358
09:53:01 INFO - 1501001581322 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49770
09:53:01 INFO - 1501001581323 Marionette DEBUG Closed connection 359
09:53:01 INFO - 1501001581323 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49771
09:53:01 INFO - 1501001581324 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581325 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581327 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581330 Marionette TRACE 360 <- [1,1,null,{"sessionId":"7009cb78-e1d0-45c3-b952-bf64eaec563d","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581331 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:53:01 INFO - 1501001581331 Marionette TRACE 360 <- [1,2,null,{}]
09:53:01 INFO - 1501001581332 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:01 INFO - 1501001581333 Marionette TRACE 360 <- [1,3,null,{}]
09:53:01 INFO - 1501001581334 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:01 INFO - 1501001581334 Marionette TRACE 360 <- [1,4,null,{}]
09:53:01 INFO - 1501001581335 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:53:01 INFO - TEST-START: test_click.py TestClick.test_container_element1501001581338 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:53:01 INFO - 1501001581338 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:53:01 INFO - 1501001581339 Marionette TRACE 360 <- [1,6,null,{}]
09:53:01 INFO - 1501001581341 Marionette DEBUG Closed connection 360
09:53:01 INFO - 1501001581341 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49772
09:53:01 INFO - 1501001581342 Marionette DEBUG Closed connection 361
09:53:01 INFO - 1501001581343 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49773
09:53:01 INFO - 1501001581344 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581344 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581346 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581349 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9f7c552d-3a95-414d-b07c-24a7b93ea773","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581350 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:53:01 INFO - 1501001581351 Marionette TRACE 362 <- [1,2,null,{}]
09:53:01 INFO - 1501001581352 Marionette DEBUG Closed connection 362
09:53:01 INFO - 1501001581352 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49774
09:53:01 INFO - 1501001581353 Marionette DEBUG Closed connection 363
09:53:01 INFO - 1501001581354 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49775
09:53:01 INFO - 1501001581355 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:01 INFO - 1501001581355 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581357 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581360 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8c3f6fbb-2b48-4adb-a904-42651be20a5a","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581361 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:53:01 INFO - 1501001581362 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
09:53:01 INFO - 1501001581364 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
09:53:01 INFO - 1501001581364 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
09:53:01 INFO - 1501001581369 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"
09:53:01 INFO - 1501001581372 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"
09:53:01 INFO - 1501001581377 Marionette TRACE 364 <- [1,2,null,{}]
09:53:01 INFO - 1501001581385 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:53:01 INFO - 1501001581387 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77726f52-b125-4c38-87b4-3f249d1488fd","ELEMENT":"77726f52-b125-4c38-87b4-3f249d1488fd"}}]
09:53:01 INFO - 1501001581388 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"77726f52-b125-4c38-87b4-3f249d1488fd"}]
09:53:01 INFO - 1501001581631 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:53:01 INFO - 1501001581632 Marionette TRACE 364 <- [1,4,null,{}]
09:53:01 INFO - 1501001581633 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"77726f52-b125-4c38-87b4-3f249d1488fd","name":"selected"}]
09:53:01 INFO - 1501001581637 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:53:01 INFO - 1501001581639 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:53:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
09:53:01 INFO - TEST-END: test_click.py TestClick.test_container_element1501001581641 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:53:01 INFO - 1501001581642 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:53:01 INFO - 1501001581643 Marionette TRACE 364 <- [1,7,null,{}]
09:53:01 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:53:01 INFO - 1501001581644 Marionette DEBUG Closed connection 364
09:53:01 INFO - 1501001581645 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49776
09:53:01 INFO - 1501001581646 Marionette DEBUG Closed connection 365
09:53:01 INFO - 1501001581647 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49777
09:53:01 INFO - 1501001581648 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581648 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581650 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581653 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9f3ef039-00de-43dc-be6f-1161943eed70","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581654 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:53:01 INFO - 1501001581655 Marionette TRACE 366 <- [1,2,null,{}]
09:53:01 INFO - 1501001581656 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:01 INFO - 1501001581656 Marionette TRACE 366 <- [1,3,null,{}]
09:53:01 INFO - 1501001581657 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:01 INFO - 1501001581657 Marionette TRACE 366 <- [1,4,null,{}]
09:53:01 INFO - 1501001581659 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:53:01 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501001581663 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:53:01 INFO - 1501001581663 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:53:01 INFO - 1501001581665 Marionette TRACE 366 <- [1,6,null,{}]
09:53:01 INFO - 1501001581666 Marionette DEBUG Closed connection 366
09:53:01 INFO - 1501001581666 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49778
09:53:01 INFO - 1501001581667 Marionette DEBUG Closed connection 367
09:53:01 INFO - 1501001581667 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49779
09:53:01 INFO - 1501001581668 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581669 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581670 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581673 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1244f2a7-d11e-472d-9250-6a41904f89a8","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581675 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:53:01 INFO - 1501001581676 Marionette TRACE 368 <- [1,2,null,{}]
09:53:01 INFO - 1501001581677 Marionette DEBUG Closed connection 368
09:53:01 INFO - 1501001581678 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49780
09:53:01 INFO - 1501001581678 Marionette DEBUG Closed connection 369
09:53:01 INFO - 1501001581679 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49781
09:53:01 INFO - 1501001581680 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:01 INFO - 1501001581680 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581682 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581685 Marionette TRACE 370 <- [1,1,null,{"sessionId":"4ce76edb-faad-4924-9ef0-1891361cf799","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581686 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:53:01 INFO - 1501001581688 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"
09:53:01 INFO - 1501001581690 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"
09:53:01 INFO - 1501001581690 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"
09:53:01 INFO - 1501001581695 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"
09:53:01 INFO - 1501001581698 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"
09:53:01 INFO - 1501001581703 Marionette TRACE 370 <- [1,2,null,{}]
09:53:01 INFO - 1501001581706 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:53:01 INFO - 1501001581713 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"015019fa-705e-4b26-add4-71291b909d41","ELEMENT":"015019fa-705e-4b26-add4-71291b909d41"}}]
09:53:01 INFO - 1501001581714 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"015019fa-705e-4b26-add4-71291b909d41"}]
09:53:01 INFO - 1501001581955 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:53:01 INFO - 1501001581956 Marionette TRACE 370 <- [1,4,null,{}]
09:53:01 INFO - 1501001581957 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"015019fa-705e-4b26-add4-71291b909d41","name":"selected"}]
09:53:01 INFO - 1501001581961 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:53:01 INFO - 1501001581962 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:53:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 323ms
09:53:01 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501001581965 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:53:01 INFO - 1501001581966 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:53:01 INFO - 1501001581967 Marionette TRACE 370 <- [1,7,null,{}]
09:53:01 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:53:01 INFO - 1501001581969 Marionette DEBUG Closed connection 370
09:53:01 INFO - 1501001581970 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49782
09:53:01 INFO - 1501001581971 Marionette DEBUG Closed connection 371
09:53:01 INFO - 1501001581971 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49783
09:53:01 INFO - 1501001581972 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:01 INFO - 1501001581973 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:01 INFO - 1501001581975 Marionette DEBUG Register listener.js for window 4294967297
09:53:01 INFO - 1501001581977 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b4a0f355-3e9d-496d-a909-28b75b7ca4f2","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:01 INFO - 1501001581978 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:53:01 INFO - 1501001581979 Marionette TRACE 372 <- [1,2,null,{}]
09:53:01 INFO - 1501001581980 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:01 INFO - 1501001581980 Marionette TRACE 372 <- [1,3,null,{}]
09:53:01 INFO - 1501001581981 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:01 INFO - 1501001581981 Marionette TRACE 372 <- [1,4,null,{}]
09:53:01 INFO - 1501001581983 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:53:01 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501001581985 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:53:01 INFO - 1501001581986 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:53:01 INFO - 1501001581987 Marionette TRACE 372 <- [1,6,null,{}]
09:53:01 INFO - 1501001581988 Marionette DEBUG Closed connection 372
09:53:01 INFO - 1501001581989 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49784
09:53:01 INFO - 1501001581990 Marionette DEBUG Closed connection 373
09:53:02 INFO - 1501001581990 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49785
09:53:02 INFO - 1501001581991 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001581992 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001581993 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001581996 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f32d8c45-1d2c-496f-b77a-113a71699a65","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001581997 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:53:02 INFO - 1501001581998 Marionette TRACE 374 <- [1,2,null,{}]
09:53:02 INFO - 1501001582000 Marionette DEBUG Closed connection 374
09:53:02 INFO - 1501001582000 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49786
09:53:02 INFO - 1501001582001 Marionette DEBUG Closed connection 375
09:53:02 INFO - 1501001582002 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49787
09:53:02 INFO - 1501001582003 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:02 INFO - 1501001582003 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582005 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582008 Marionette TRACE 376 <- [1,1,null,{"sessionId":"20764a41-d4ab-4474-aa2e-63cc0740b4b8","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582010 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:53:02 INFO - 1501001582015 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"
09:53:02 INFO - 1501001582018 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"
09:53:02 INFO - 1501001582018 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"
09:53:02 INFO - 1501001582025 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"
09:53:02 INFO - 1501001582029 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"
09:53:02 INFO - 1501001582035 Marionette TRACE 376 <- [1,2,null,{}]
09:53:02 INFO - 1501001582038 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:53:02 INFO - 1501001582040 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8c6512ee-db0c-45bb-9a3c-23b8220f3532","ELEMENT":"8c6512ee-db0c-45bb-9a3c-23b8220f3532"}}]
09:53:02 INFO - 1501001582042 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8c6512ee-db0c-45bb-9a3c-23b8220f3532"}]
09:53:02 INFO - 1501001582278 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:53:02 INFO - 1501001582279 Marionette TRACE 376 <- [1,4,null,{}]
09:53:02 INFO - 1501001582281 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:53:02 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501001582283 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582284 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:53:02 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 317ms
09:53:02 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:53:02 INFO - 1501001582285 Marionette TRACE 376 <- [1,6,null,{}]
09:53:02 INFO - 1501001582287 Marionette DEBUG Closed connection 376
09:53:02 INFO - 1501001582287 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49788
09:53:02 INFO - 1501001582288 Marionette DEBUG Closed connection 377
09:53:02 INFO - 1501001582288 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49789
09:53:02 INFO - 1501001582290 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582290 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582292 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582295 Marionette TRACE 378 <- [1,1,null,{"sessionId":"229f099b-2a33-42d9-a289-492424105635","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582296 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:53:02 INFO - 1501001582296 Marionette TRACE 378 <- [1,2,null,{}]
09:53:02 INFO - 1501001582297 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:02 INFO - 1501001582297 Marionette TRACE 378 <- [1,3,null,{}]
09:53:02 INFO - 1501001582298 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:02 INFO - 1501001582298 Marionette TRACE 378 <- [1,4,null,{}]
09:53:02 INFO - 1501001582300 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:53:02 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501001582302 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582303 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:53:02 INFO - 1501001582304 Marionette TRACE 378 <- [1,6,null,{}]
09:53:02 INFO - 1501001582306 Marionette DEBUG Closed connection 378
09:53:02 INFO - 1501001582306 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49790
09:53:02 INFO - 1501001582307 Marionette DEBUG Closed connection 379
09:53:02 INFO - 1501001582307 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49791
09:53:02 INFO - 1501001582308 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582309 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582311 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582313 Marionette TRACE 380 <- [1,1,null,{"sessionId":"44928631-5a21-4389-85f8-37eaa837c36f","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582315 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:53:02 INFO - 1501001582316 Marionette TRACE 380 <- [1,2,null,{}]
09:53:02 INFO - 1501001582317 Marionette DEBUG Closed connection 380
09:53:02 INFO - 1501001582318 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49792
09:53:02 INFO - 1501001582318 Marionette DEBUG Closed connection 381
09:53:02 INFO - 1501001582319 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49793
09:53:02 INFO - 1501001582320 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:02 INFO - 1501001582320 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582322 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582325 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c89dda86-81b6-4eab-8fd3-34cef165576f","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582326 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:53:02 INFO - 1501001582328 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"
09:53:02 INFO - 1501001582330 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"
09:53:02 INFO - 1501001582330 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"
09:53:02 INFO - 1501001582335 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"
09:53:02 INFO - 1501001582338 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"
09:53:02 INFO - 1501001582340 Marionette TRACE 382 <- [1,2,null,{}]
09:53:02 INFO - 1501001582347 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:53:02 INFO - 1501001582350 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c01a2403-63e0-4374-9fa0-98ddd0d88d94","ELEMENT":"c01a2403-63e0-4374-9fa0-98ddd0d88d94"}}]
09:53:02 INFO - 1501001582351 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c01a2403-63e0-4374-9fa0-98ddd0d88d94"}]
09:53:02 INFO - 1501001582592 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:53:02 INFO - 1501001582593 Marionette TRACE 382 <- [1,4,null,{}]
09:53:02 INFO - 1501001582594 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:53:02 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501001582597 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582598 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:53:02 INFO - 1501001582599 Marionette TRACE 382 <- [1,6,null,{}]
09:53:02 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 314ms
09:53:02 INFO - TEST-START | test_click.py TestClick.test_input_file
09:53:02 INFO - 1501001582601 Marionette DEBUG Closed connection 382
09:53:02 INFO - 1501001582602 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49794
09:53:02 INFO - 1501001582603 Marionette DEBUG Closed connection 383
09:53:02 INFO - 1501001582604 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49795
09:53:02 INFO - 1501001582605 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582605 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582607 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582610 Marionette TRACE 384 <- [1,1,null,{"sessionId":"00689dff-be15-474f-81d8-779495294ab3","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582611 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:53:02 INFO - 1501001582611 Marionette TRACE 384 <- [1,2,null,{}]
09:53:02 INFO - 1501001582612 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:02 INFO - 1501001582612 Marionette TRACE 384 <- [1,3,null,{}]
09:53:02 INFO - 1501001582613 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:02 INFO - 1501001582613 Marionette TRACE 384 <- [1,4,null,{}]
09:53:02 INFO - 1501001582615 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:53:02 INFO - TEST-START: test_click.py TestClick.test_input_file1501001582617 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582618 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:53:02 INFO - 1501001582619 Marionette TRACE 384 <- [1,6,null,{}]
09:53:02 INFO - 1501001582621 Marionette DEBUG Closed connection 384
09:53:02 INFO - 1501001582622 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49796
09:53:02 INFO - 1501001582623 Marionette DEBUG Closed connection 385
09:53:02 INFO - 1501001582623 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49797
09:53:02 INFO - 1501001582624 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582625 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582627 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582637 Marionette TRACE 386 <- [1,1,null,{"sessionId":"de07dc4d-4da0-4ba9-88c9-84107fe8289f","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582638 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:53:02 INFO - 1501001582640 Marionette TRACE 386 <- [1,2,null,{}]
09:53:02 INFO - 1501001582641 Marionette DEBUG Closed connection 386
09:53:02 INFO - 1501001582641 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49798
09:53:02 INFO - 1501001582642 Marionette DEBUG Closed connection 387
09:53:02 INFO - 1501001582643 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49799
09:53:02 INFO - 1501001582644 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:02 INFO - 1501001582644 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582646 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582649 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0b458d38-dad8-47b7-a743-a966a8e975bf","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582650 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:53:02 INFO - 1501001582652 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"
09:53:02 INFO - 1501001582653 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"
09:53:02 INFO - 1501001582654 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"
09:53:02 INFO - 1501001582661 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:53:02 INFO - 1501001582662 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:53:02 INFO - 1501001582668 Marionette TRACE 388 <- [1,2,null,{}]
09:53:02 INFO - 1501001582670 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:53:02 INFO - 1501001582673 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a92924c-a801-45b0-8881-19cd6e8a72ac","ELEMENT":"5a92924c-a801-45b0-8881-19cd6e8a72ac"}}]
09:53:02 INFO - 1501001582674 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5a92924c-a801-45b0-8881-19cd6e8a72ac"}]
09:53:02 INFO - 1501001582701 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
09:53:02 INFO - 1501001582702 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:53:02 INFO - TEST-END: test_click.py TestClick.test_input_file1501001582705 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582706 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:53:02 INFO - 1501001582707 Marionette TRACE 388 <- [1,6,null,{}]
09:53:02 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
09:53:02 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:53:02 INFO - 1501001582709 Marionette DEBUG Closed connection 388
09:53:02 INFO - 1501001582710 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49800
09:53:02 INFO - 1501001582711 Marionette DEBUG Closed connection 389
09:53:02 INFO - 1501001582712 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49801
09:53:02 INFO - 1501001582713 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582713 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582716 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582719 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0d641cdc-92c9-442d-b0ba-d36bea79b1d3","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582720 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:53:02 INFO - 1501001582720 Marionette TRACE 390 <- [1,2,null,{}]
09:53:02 INFO - 1501001582721 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:02 INFO - 1501001582722 Marionette TRACE 390 <- [1,3,null,{}]
09:53:02 INFO - 1501001582722 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:02 INFO - 1501001582723 Marionette TRACE 390 <- [1,4,null,{}]
09:53:02 INFO - 1501001582724 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:53:02 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501001582727 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:53:02 INFO - 1501001582728 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:53:02 INFO - 1501001582729 Marionette TRACE 390 <- [1,6,null,{}]
09:53:02 INFO - 1501001582730 Marionette DEBUG Closed connection 390
09:53:02 INFO - 1501001582731 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49802
09:53:02 INFO - 1501001582731 Marionette DEBUG Closed connection 391
09:53:02 INFO - 1501001582732 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49803
09:53:02 INFO - 1501001582733 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:02 INFO - 1501001582733 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582735 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582738 Marionette TRACE 392 <- [1,1,null,{"sessionId":"20195aa5-6988-4472-9062-44914ad312f2","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582739 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:53:02 INFO - 1501001582740 Marionette TRACE 392 <- [1,2,null,{}]
09:53:02 INFO - 1501001582741 Marionette DEBUG Closed connection 392
09:53:02 INFO - 1501001582742 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49804
09:53:02 INFO - 1501001582742 Marionette DEBUG Closed connection 393
09:53:02 INFO - 1501001582743 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49805
09:53:02 INFO - 1501001582744 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:02 INFO - 1501001582744 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:02 INFO - 1501001582746 Marionette DEBUG Register listener.js for window 4294967297
09:53:02 INFO - 1501001582749 Marionette TRACE 394 <- [1,1,null,{"sessionId":"99881461-d011-4a59-a2fd-4766e05335c4","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:02 INFO - 1501001582750 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:53:02 INFO - 1501001582752 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:53:02 INFO - 1501001582753 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:53:02 INFO - 1501001582753 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:53:02 INFO - 1501001582761 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"
09:53:02 INFO - 1501001582762 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"
09:53:02 INFO - 1501001582767 Marionette TRACE 394 <- [1,2,null,{}]
09:53:02 INFO - 1501001582770 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:53:02 INFO - 1501001582773 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e2c67c8-8748-4dec-a6ac-68468de76980","ELEMENT":"9e2c67c8-8748-4dec-a6ac-68468de76980"}}]
09:53:02 INFO - 1501001582774 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:53:02 INFO - 1501001582778 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4f6f220-5b63-4eaf-96a6-061bd430381b","ELEMENT":"c4f6f220-5b63-4eaf-96a6-061bd430381b"}}]
09:53:02 INFO - 1501001582779 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9e2c67c8-8748-4dec-a6ac-68468de76980"}]
09:53:03 INFO - 1501001583019 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:53:03 INFO - 1501001583020 Marionette TRACE 394 <- [1,5,null,{}]
09:53:03 INFO - 1501001583021 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c4f6f220-5b63-4eaf-96a6-061bd430381b"}]
09:53:03 INFO - 1501001583035 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
09:53:03 INFO - 1501001583037 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:53:03 INFO - 1501001583039 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:53:03 INFO - 1501001583040 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:53:03 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501001583042 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:53:03 INFO - 1501001583043 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:53:03 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 336ms
09:53:03 INFO - 1501001583045 Marionette TRACE 394 <- [1,9,null,{}]
09:53:03 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:53:03 INFO - 1501001583046 Marionette DEBUG Closed connection 394
09:53:03 INFO - 1501001583047 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49806
09:53:03 INFO - 1501001583048 Marionette DEBUG Closed connection 395
09:53:03 INFO - 1501001583049 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49807
09:53:03 INFO - 1501001583050 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:03 INFO - 1501001583050 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:03 INFO - 1501001583052 Marionette DEBUG Register listener.js for window 4294967297
09:53:03 INFO - 1501001583055 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7d6e6a5b-7789-4dbd-ae34-42035c665d9a","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:03 INFO - 1501001583057 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:53:03 INFO - 1501001583057 Marionette TRACE 396 <- [1,2,null,{}]
09:53:03 INFO - 1501001583058 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:53:03 INFO - 1501001583058 Marionette TRACE 396 <- [1,3,null,{}]
09:53:03 INFO - 1501001583059 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:53:03 INFO - 1501001583060 Marionette TRACE 396 <- [1,4,null,{}]
09:53:03 INFO - 1501001583061 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:53:03 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501001583064 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:53:03 INFO - 1501001583065 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:53:03 INFO - 1501001583066 Marionette TRACE 396 <- [1,6,null,{}]
09:53:03 INFO - 1501001583067 Marionette DEBUG Closed connection 396
09:53:03 INFO - 1501001583067 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49808
09:53:03 INFO - 1501001583068 Marionette DEBUG Closed connection 397
09:53:03 INFO - 1501001583069 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49809
09:53:03 INFO - 1501001583070 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:53:03 INFO - 1501001583070 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:03 INFO - 1501001583072 Marionette DEBUG Register listener.js for window 4294967297
09:53:03 INFO - 1501001583074 Marionette TRACE 398 <- [1,1,null,{"sessionId":"542578e8-f788-4537-a06b-2fb8bd67027c","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:03 INFO - 1501001583075 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:53:03 INFO - 1501001583076 Marionette TRACE 398 <- [1,2,null,{}]
09:53:03 INFO - 1501001583078 Marionette DEBUG Closed connection 398
09:53:03 INFO - 1501001583078 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49810
09:53:03 INFO - 1501001583079 Marionette DEBUG Closed connection 399
09:53:03 INFO - 1501001583080 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49811
09:53:03 INFO - 1501001583081 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:53:03 INFO - 1501001583081 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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}
09:53:03 INFO - 1501001583083 Marionette DEBUG Register listener.js for window 4294967297
09:53:03 INFO - 1501001583086 Marionette TRACE 400 <- [1,1,null,{"sessionId":"766b9ac4-0567-4812-8be5-4751f9eddbe3","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":1600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-015.001\\AppData\\Local\\Temp\\tmp1pkifw.mozrunner","moz:accessibilityChecks":false}}]
09:53:03 INFO - 1501001583087 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:53:03 INFO - 1501001583089 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"
09:53:03 INFO - 1501001583090 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"
09:53:03 INFO - 1501001583091 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"
09:53:03 INFO - 1501001583097 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"
09:53:03 INFO - 1501001583099 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"
09:53:03 INFO - 1501001583102 Marionette TRACE 400 <- [1,2,null,{}]
09:53:03 INFO - 1501001583105 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:53:03 INFO - 1501001583107 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7dbd658-20d7-4291-96a9-c46e94f2dcfe","ELEMENT":"b7dbd658-20d7-4291-96a9-c46e94f2dcfe"}}]
09:53:03 INFO - 1501001583108 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b7dbd658-20d7-4291-96a9-c46e94f2dcfe"}]
09:53:03 INFO - 1501001583132 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:53:03 INFO - 1501001583133 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b7dbd658-20d7-4291-96a9-c46e94f2dcfe"}]
09:53:03 INFO - 1501001583147 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element