could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
11:33:12 INFO - 1500316392774 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}]
11:33:12 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500316392777 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:33:12 INFO - 1500316392777 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:33:12 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:33:12 INFO - 1500316392779 Marionette TRACE 352 <- [1,6,null,{}]
11:33:12 INFO - 1500316392781 Marionette DEBUG Closed connection 352
11:33:12 INFO - 1500316392783 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49761
11:33:12 INFO - 1500316392784 Marionette DEBUG Closed connection 353
11:33:12 INFO - 1500316392784 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49762
11:33:12 INFO - 1500316392786 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:12 INFO - 1500316392786 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:12 INFO - 1500316392789 Marionette DEBUG Register listener.js for window 15
11:33:12 INFO - 1500316392793 Marionette TRACE 354 <- [1,1,null,{"sessionId":"edf2e6ae-c07c-4a59-856f-93744ea861fe","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:12 INFO - 1500316392796 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:33:12 INFO - 1500316392797 Marionette TRACE 354 <- [1,2,null,{}]
11:33:12 INFO - 1500316392798 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:12 INFO - 1500316392798 Marionette TRACE 354 <- [1,3,null,{}]
11:33:12 INFO - 1500316392799 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:12 INFO - 1500316392800 Marionette TRACE 354 <- [1,4,null,{}]
11:33:12 INFO - 1500316392802 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}]
11:33:12 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500316392804 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:33:12 INFO - 1500316392805 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:33:12 INFO - 1500316392807 Marionette TRACE 354 <- [1,6,null,{}]
11:33:12 INFO - 1500316392809 Marionette DEBUG Closed connection 354
11:33:12 INFO - 1500316392810 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49763
11:33:12 INFO - 1500316392810 Marionette DEBUG Closed connection 355
11:33:12 INFO - 1500316392811 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49764
11:33:12 INFO - 1500316392812 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:12 INFO - 1500316392813 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:12 INFO - 1500316392815 Marionette DEBUG Register listener.js for window 15
11:33:12 INFO - 1500316392819 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cf7ef5ee-bf3b-4220-9d73-4777a624df35","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:12 INFO - 1500316392821 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:33:12 INFO - 1500316392822 Marionette TRACE 356 <- [1,2,null,{}]
11:33:12 INFO - 1500316392824 Marionette DEBUG Closed connection 356
11:33:12 INFO - 1500316392824 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49765
11:33:12 INFO - 1500316392825 Marionette DEBUG Closed connection 357
11:33:12 INFO - 1500316392826 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49766
11:33:12 INFO - 1500316392827 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:12 INFO - 1500316392827 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:12 INFO - 1500316392830 Marionette DEBUG Register listener.js for window 15
11:33:12 INFO - 1500316392833 Marionette TRACE 358 <- [1,1,null,{"sessionId":"36bc85b9-2249-44ec-92a4-731c0698ef55","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:12 INFO - 1500316392836 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
11:33:12 INFO - 1500316392837 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
11:33:12 INFO - 1500316392843 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
11:33:12 INFO - 1500316392843 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
11:33:12 INFO - 1500316392862 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
11:33:12 INFO - 1500316392867 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
11:33:12 INFO - 1500316392877 Marionette TRACE 358 <- [1,2,null,{}]
11:33:12 INFO - 1500316392878 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:33:12 INFO - 1500316392880 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"def2cd7f-deee-4925-84c7-14dac953487b","ELEMENT":"def2cd7f-deee-4925-84c7-14dac953487b"}}]
11:33:12 INFO - 1500316392883 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"def2cd7f-deee-4925-84c7-14dac953487b"}]
11:33:12 INFO - 1500316392931 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
11:33:12 INFO - 1500316392940 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
11:33:12 INFO - 1500316392940 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
11:33:12 INFO - 1500316392958 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
11:33:12 INFO - 1500316392962 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
11:33:12 INFO - 1500316392964 Marionette TRACE 358 <- [1,4,null,{}]
11:33:12 INFO - 1500316392972 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:33:12 INFO - 1500316392974 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e165c31-b9b9-434b-a898-6802fc94a51a","ELEMENT":"6e165c31-b9b9-434b-a898-6802fc94a51a"}}]
11:33:12 INFO - 1500316392975 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:33:12 INFO - 1500316392976 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:33:12 INFO - 1500316392977 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}]
11:33:12 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500316392980 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:33:12 INFO - 1500316392981 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:33:12 INFO - 1500316392982 Marionette TRACE 358 <- [1,8,null,{}]
11:33:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 204ms
11:33:12 INFO - TEST-START | test_click.py TestClick.test_container_element
11:33:12 INFO - 1500316392985 Marionette DEBUG Closed connection 358
11:33:12 INFO - 1500316392986 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49769
11:33:12 INFO - 1500316392987 Marionette DEBUG Closed connection 359
11:33:12 INFO - 1500316392988 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49770
11:33:12 INFO - 1500316392989 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:12 INFO - 1500316392989 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:12 INFO - 1500316392992 Marionette DEBUG Register listener.js for window 15
11:33:12 INFO - 1500316392996 Marionette TRACE 360 <- [1,1,null,{"sessionId":"aa022ebe-aa20-45da-9a1a-ef0146dbcf5e","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316392998 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:33:13 INFO - 1500316392999 Marionette TRACE 360 <- [1,2,null,{}]
11:33:13 INFO - 1500316393000 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:13 INFO - 1500316393000 Marionette TRACE 360 <- [1,3,null,{}]
11:33:13 INFO - 1500316393001 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:13 INFO - 1500316393001 Marionette TRACE 360 <- [1,4,null,{}]
11:33:13 INFO - 1500316393003 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}]
11:33:13 INFO - TEST-START: test_click.py TestClick.test_container_element1500316393005 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:33:13 INFO - 1500316393006 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:33:13 INFO - 1500316393008 Marionette TRACE 360 <- [1,6,null,{}]
11:33:13 INFO - 1500316393010 Marionette DEBUG Closed connection 360
11:33:13 INFO - 1500316393010 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49771
11:33:13 INFO - 1500316393011 Marionette DEBUG Closed connection 361
11:33:13 INFO - 1500316393012 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49772
11:33:13 INFO - 1500316393013 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:13 INFO - 1500316393013 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393016 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393020 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4c7760b4-41c0-4dbd-a95f-97d7d52dfc10","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393022 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:33:13 INFO - 1500316393023 Marionette TRACE 362 <- [1,2,null,{}]
11:33:13 INFO - 1500316393026 Marionette DEBUG Closed connection 362
11:33:13 INFO - 1500316393026 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49773
11:33:13 INFO - 1500316393027 Marionette DEBUG Closed connection 363
11:33:13 INFO - 1500316393027 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49774
11:33:13 INFO - 1500316393028 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:13 INFO - 1500316393029 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393032 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393035 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5cb563ea-8b79-49de-97b4-7392110baea6","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393037 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"}]
11:33:13 INFO - 1500316393039 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
11:33:13 INFO - 1500316393041 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
11:33:13 INFO - 1500316393042 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
11:33:13 INFO - 1500316393064 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"
11:33:13 INFO - 1500316393070 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"
11:33:13 INFO - 1500316393074 Marionette TRACE 364 <- [1,2,null,{}]
11:33:13 INFO - 1500316393076 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:33:13 INFO - 1500316393079 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dec0bae4-f0de-4cfa-89f4-184e38c1b36e","ELEMENT":"dec0bae4-f0de-4cfa-89f4-184e38c1b36e"}}]
11:33:13 INFO - 1500316393080 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"dec0bae4-f0de-4cfa-89f4-184e38c1b36e"}]
11:33:13 INFO - 1500316393327 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:33:13 INFO - 1500316393328 Marionette TRACE 364 <- [1,4,null,{}]
11:33:13 INFO - 1500316393329 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"dec0bae4-f0de-4cfa-89f4-184e38c1b36e","name":"selected"}]
11:33:13 INFO - 1500316393333 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:33:13 INFO - 1500316393335 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}]
11:33:13 INFO - TEST-END: test_click.py TestClick.test_container_element1500316393337 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:33:13 INFO - 1500316393338 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:33:13 INFO - 1500316393340 Marionette TRACE 364 <- [1,7,null,{}]
11:33:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
11:33:13 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:33:13 INFO - 1500316393342 Marionette DEBUG Closed connection 364
11:33:13 INFO - 1500316393344 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49775
11:33:13 INFO - 1500316393344 Marionette DEBUG Closed connection 365
11:33:13 INFO - 1500316393345 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49776
11:33:13 INFO - 1500316393346 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:13 INFO - 1500316393346 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393349 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393352 Marionette TRACE 366 <- [1,1,null,{"sessionId":"341b364f-13b5-41ed-ad8f-685556e815f5","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393354 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:33:13 INFO - 1500316393355 Marionette TRACE 366 <- [1,2,null,{}]
11:33:13 INFO - 1500316393356 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:13 INFO - 1500316393357 Marionette TRACE 366 <- [1,3,null,{}]
11:33:13 INFO - 1500316393358 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:13 INFO - 1500316393359 Marionette TRACE 366 <- [1,4,null,{}]
11:33:13 INFO - 1500316393361 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}]
11:33:13 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500316393365 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:33:13 INFO - 1500316393366 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:33:13 INFO - 1500316393367 Marionette TRACE 366 <- [1,6,null,{}]
11:33:13 INFO - 1500316393370 Marionette DEBUG Closed connection 366
11:33:13 INFO - 1500316393370 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49777
11:33:13 INFO - 1500316393371 Marionette DEBUG Closed connection 367
11:33:13 INFO - 1500316393371 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49778
11:33:13 INFO - 1500316393372 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:13 INFO - 1500316393373 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393376 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393386 Marionette TRACE 368 <- [1,1,null,{"sessionId":"618601fa-e6bb-4783-b4e7-7c5d4a1dc0d8","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393389 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:33:13 INFO - 1500316393390 Marionette TRACE 368 <- [1,2,null,{}]
11:33:13 INFO - 1500316393392 Marionette DEBUG Closed connection 368
11:33:13 INFO - 1500316393393 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49779
11:33:13 INFO - 1500316393394 Marionette DEBUG Closed connection 369
11:33:13 INFO - 1500316393394 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49780
11:33:13 INFO - 1500316393395 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:13 INFO - 1500316393396 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393399 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393402 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5dfbe8e2-6160-44fb-abb5-a8f414dca2ef","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393404 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"}]
11:33:13 INFO - 1500316393406 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"
11:33:13 INFO - 1500316393408 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"
11:33:13 INFO - 1500316393408 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"
11:33:13 INFO - 1500316393425 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"
11:33:13 INFO - 1500316393429 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"
11:33:13 INFO - 1500316393432 Marionette TRACE 370 <- [1,2,null,{}]
11:33:13 INFO - 1500316393446 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:33:13 INFO - 1500316393452 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"87ef9130-05f8-4194-a48e-fe9fa05a8c35","ELEMENT":"87ef9130-05f8-4194-a48e-fe9fa05a8c35"}}]
11:33:13 INFO - 1500316393453 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"87ef9130-05f8-4194-a48e-fe9fa05a8c35"}]
11:33:13 INFO - 1500316393700 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:33:13 INFO - 1500316393701 Marionette TRACE 370 <- [1,4,null,{}]
11:33:13 INFO - 1500316393702 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"87ef9130-05f8-4194-a48e-fe9fa05a8c35","name":"selected"}]
11:33:13 INFO - 1500316393706 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:33:13 INFO - 1500316393708 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}]
11:33:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500316393710 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:33:13 INFO - 1500316393711 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:33:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 372ms
11:33:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:33:13 INFO - 1500316393713 Marionette TRACE 370 <- [1,7,null,{}]
11:33:13 INFO - 1500316393715 Marionette DEBUG Closed connection 370
11:33:13 INFO - 1500316393716 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49781
11:33:13 INFO - 1500316393717 Marionette DEBUG Closed connection 371
11:33:13 INFO - 1500316393717 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49782
11:33:13 INFO - 1500316393718 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:13 INFO - 1500316393719 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393721 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393724 Marionette TRACE 372 <- [1,1,null,{"sessionId":"03cadf70-eab5-45e7-9940-b08ee5670153","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393726 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:33:13 INFO - 1500316393727 Marionette TRACE 372 <- [1,2,null,{}]
11:33:13 INFO - 1500316393728 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:13 INFO - 1500316393728 Marionette TRACE 372 <- [1,3,null,{}]
11:33:13 INFO - 1500316393729 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:13 INFO - 1500316393729 Marionette TRACE 372 <- [1,4,null,{}]
11:33:13 INFO - 1500316393731 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}]
11:33:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500316393733 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:33:13 INFO - 1500316393735 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:33:13 INFO - 1500316393736 Marionette TRACE 372 <- [1,6,null,{}]
11:33:13 INFO - 1500316393738 Marionette DEBUG Closed connection 372
11:33:13 INFO - 1500316393739 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49783
11:33:13 INFO - 1500316393739 Marionette DEBUG Closed connection 373
11:33:13 INFO - 1500316393740 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49784
11:33:13 INFO - 1500316393741 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:13 INFO - 1500316393742 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393744 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393748 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c2fbd066-df1c-4282-a6f6-bb3163794b61","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393750 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:33:13 INFO - 1500316393751 Marionette TRACE 374 <- [1,2,null,{}]
11:33:13 INFO - 1500316393753 Marionette DEBUG Closed connection 374
11:33:13 INFO - 1500316393754 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49785
11:33:13 INFO - 1500316393754 Marionette DEBUG Closed connection 375
11:33:13 INFO - 1500316393755 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49786
11:33:13 INFO - 1500316393756 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:13 INFO - 1500316393756 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:13 INFO - 1500316393759 Marionette DEBUG Register listener.js for window 15
11:33:13 INFO - 1500316393762 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ac470e54-a366-4387-ba8e-b18c87f25cbf","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:13 INFO - 1500316393764 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"}]
11:33:13 INFO - 1500316393765 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"
11:33:13 INFO - 1500316393768 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"
11:33:13 INFO - 1500316393768 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"
11:33:13 INFO - 1500316393789 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"
11:33:13 INFO - 1500316393791 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"
11:33:13 INFO - 1500316393795 Marionette TRACE 376 <- [1,2,null,{}]
11:33:13 INFO - 1500316393796 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:33:13 INFO - 1500316393804 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b9a7055a-ffdb-4f44-97ac-544df026be73","ELEMENT":"b9a7055a-ffdb-4f44-97ac-544df026be73"}}]
11:33:13 INFO - 1500316393806 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b9a7055a-ffdb-4f44-97ac-544df026be73"}]
11:33:14 INFO - 1500316394052 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:33:14 INFO - 1500316394053 Marionette TRACE 376 <- [1,4,null,{}]
11:33:14 INFO - 1500316394054 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}]
11:33:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500316394057 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394058 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:33:14 INFO - 1500316394059 Marionette TRACE 376 <- [1,6,null,{}]
11:33:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
11:33:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:33:14 INFO - 1500316394061 Marionette DEBUG Closed connection 376
11:33:14 INFO - 1500316394062 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49787
11:33:14 INFO - 1500316394062 Marionette DEBUG Closed connection 377
11:33:14 INFO - 1500316394063 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49788
11:33:14 INFO - 1500316394064 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394065 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394067 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394070 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cf623ad3-4571-4cb2-9696-f6a466620473","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394072 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:33:14 INFO - 1500316394073 Marionette TRACE 378 <- [1,2,null,{}]
11:33:14 INFO - 1500316394074 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:14 INFO - 1500316394074 Marionette TRACE 378 <- [1,3,null,{}]
11:33:14 INFO - 1500316394075 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:14 INFO - 1500316394076 Marionette TRACE 378 <- [1,4,null,{}]
11:33:14 INFO - 1500316394077 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}]
11:33:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500316394080 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394080 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:33:14 INFO - 1500316394082 Marionette TRACE 378 <- [1,6,null,{}]
11:33:14 INFO - 1500316394084 Marionette DEBUG Closed connection 378
11:33:14 INFO - 1500316394084 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49789
11:33:14 INFO - 1500316394085 Marionette DEBUG Closed connection 379
11:33:14 INFO - 1500316394086 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49790
11:33:14 INFO - 1500316394087 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394087 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394090 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394093 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ae49b827-c6b2-4894-bd43-6a76dcc6069a","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394095 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:33:14 INFO - 1500316394096 Marionette TRACE 380 <- [1,2,null,{}]
11:33:14 INFO - 1500316394098 Marionette DEBUG Closed connection 380
11:33:14 INFO - 1500316394099 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49791
11:33:14 INFO - 1500316394099 Marionette DEBUG Closed connection 381
11:33:14 INFO - 1500316394100 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49792
11:33:14 INFO - 1500316394101 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:14 INFO - 1500316394102 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394104 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394107 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b171baab-ae64-40f7-a54b-1e15ef0f876b","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394109 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"}]
11:33:14 INFO - 1500316394111 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"
11:33:14 INFO - 1500316394113 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"
11:33:14 INFO - 1500316394114 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"
11:33:14 INFO - 1500316394135 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"
11:33:14 INFO - 1500316394137 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"
11:33:14 INFO - 1500316394142 Marionette TRACE 382 <- [1,2,null,{}]
11:33:14 INFO - 1500316394144 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:33:14 INFO - 1500316394146 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c27c1fff-a425-4f29-b7cd-3ad8f4eb95a3","ELEMENT":"c27c1fff-a425-4f29-b7cd-3ad8f4eb95a3"}}]
11:33:14 INFO - 1500316394153 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c27c1fff-a425-4f29-b7cd-3ad8f4eb95a3"}]
11:33:14 INFO - 1500316394399 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:33:14 INFO - 1500316394400 Marionette TRACE 382 <- [1,4,null,{}]
11:33:14 INFO - 1500316394401 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}]
11:33:14 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500316394404 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394405 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:33:14 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
11:33:14 INFO - TEST-START | test_click.py TestClick.test_input_file
11:33:14 INFO - 1500316394407 Marionette TRACE 382 <- [1,6,null,{}]
11:33:14 INFO - 1500316394409 Marionette DEBUG Closed connection 382
11:33:14 INFO - 1500316394409 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49793
11:33:14 INFO - 1500316394410 Marionette DEBUG Closed connection 383
11:33:14 INFO - 1500316394411 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49794
11:33:14 INFO - 1500316394412 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394412 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394415 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394418 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e76c40e2-83d5-4ecb-8fcd-14619fadfa1a","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394420 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:33:14 INFO - 1500316394421 Marionette TRACE 384 <- [1,2,null,{}]
11:33:14 INFO - 1500316394422 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:14 INFO - 1500316394422 Marionette TRACE 384 <- [1,3,null,{}]
11:33:14 INFO - 1500316394423 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:14 INFO - 1500316394423 Marionette TRACE 384 <- [1,4,null,{}]
11:33:14 INFO - 1500316394425 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}]
11:33:14 INFO - TEST-START: test_click.py TestClick.test_input_file1500316394427 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394428 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:33:14 INFO - 1500316394429 Marionette TRACE 384 <- [1,6,null,{}]
11:33:14 INFO - 1500316394432 Marionette DEBUG Closed connection 384
11:33:14 INFO - 1500316394432 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49795
11:33:14 INFO - 1500316394433 Marionette DEBUG Closed connection 385
11:33:14 INFO - 1500316394434 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49796
11:33:14 INFO - 1500316394435 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394435 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394438 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394441 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3d023390-7b28-4702-a747-d8820ea0ef63","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394443 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:33:14 INFO - 1500316394445 Marionette TRACE 386 <- [1,2,null,{}]
11:33:14 INFO - 1500316394447 Marionette DEBUG Closed connection 386
11:33:14 INFO - 1500316394448 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49797
11:33:14 INFO - 1500316394449 Marionette DEBUG Closed connection 387
11:33:14 INFO - 1500316394449 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49798
11:33:14 INFO - 1500316394450 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:14 INFO - 1500316394451 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394460 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394463 Marionette TRACE 388 <- [1,1,null,{"sessionId":"68a6b46e-81fa-4d6e-9c33-d211e3d57228","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394466 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:33:14 INFO - 1500316394467 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"
11:33:14 INFO - 1500316394469 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"
11:33:14 INFO - 1500316394470 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"
11:33:14 INFO - 1500316394491 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:33:14 INFO - 1500316394492 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:33:14 INFO - 1500316394496 Marionette TRACE 388 <- [1,2,null,{}]
11:33:14 INFO - 1500316394507 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:33:14 INFO - 1500316394510 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa430bd5-12dd-4d32-9ac9-18595ab91e08","ELEMENT":"fa430bd5-12dd-4d32-9ac9-18595ab91e08"}}]
11:33:14 INFO - 1500316394511 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fa430bd5-12dd-4d32-9ac9-18595ab91e08"}]
11:33:14 INFO - 1500316394540 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
11:33:14 INFO - 1500316394542 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}]
11:33:14 INFO - TEST-END: test_click.py TestClick.test_input_file1500316394544 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394545 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:33:14 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 139ms
11:33:14 INFO - 1500316394546 Marionette TRACE 388 <- [1,6,null,{}]
11:33:14 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:33:14 INFO - 1500316394548 Marionette DEBUG Closed connection 388
11:33:14 INFO - 1500316394549 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49799
11:33:14 INFO - 1500316394550 Marionette DEBUG Closed connection 389
11:33:14 INFO - 1500316394551 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49800
11:33:14 INFO - 1500316394552 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394552 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394555 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394558 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5b0b995c-8463-41f2-afa3-120901a1590f","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394560 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:33:14 INFO - 1500316394560 Marionette TRACE 390 <- [1,2,null,{}]
11:33:14 INFO - 1500316394561 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:14 INFO - 1500316394562 Marionette TRACE 390 <- [1,3,null,{}]
11:33:14 INFO - 1500316394562 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:14 INFO - 1500316394563 Marionette TRACE 390 <- [1,4,null,{}]
11:33:14 INFO - 1500316394564 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}]
11:33:14 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500316394567 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394567 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:33:14 INFO - 1500316394569 Marionette TRACE 390 <- [1,6,null,{}]
11:33:14 INFO - 1500316394571 Marionette DEBUG Closed connection 390
11:33:14 INFO - 1500316394571 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49801
11:33:14 INFO - 1500316394572 Marionette DEBUG Closed connection 391
11:33:14 INFO - 1500316394573 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49802
11:33:14 INFO - 1500316394574 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394574 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394577 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394580 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fd5f868f-0f53-4baa-b6f0-7c089f286d13","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394582 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:33:14 INFO - 1500316394584 Marionette TRACE 392 <- [1,2,null,{}]
11:33:14 INFO - 1500316394586 Marionette DEBUG Closed connection 392
11:33:14 INFO - 1500316394586 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49803
11:33:14 INFO - 1500316394587 Marionette DEBUG Closed connection 393
11:33:14 INFO - 1500316394588 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49804
11:33:14 INFO - 1500316394589 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:14 INFO - 1500316394589 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394592 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394595 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a7c56897-d7f9-4ece-a0f2-a8a2721464d8","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394597 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"}]
11:33:14 INFO - 1500316394599 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:33:14 INFO - 1500316394601 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:33:14 INFO - 1500316394602 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:33:14 INFO - 1500316394613 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"
11:33:14 INFO - 1500316394617 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"
11:33:14 INFO - 1500316394626 Marionette TRACE 394 <- [1,2,null,{}]
11:33:14 INFO - 1500316394628 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:33:14 INFO - 1500316394632 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe718af4-2643-4bf0-ad3e-686a22f6ee61","ELEMENT":"fe718af4-2643-4bf0-ad3e-686a22f6ee61"}}]
11:33:14 INFO - 1500316394633 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:33:14 INFO - 1500316394635 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ab7120c-f60f-42bf-a12f-487235e79226","ELEMENT":"4ab7120c-f60f-42bf-a12f-487235e79226"}}]
11:33:14 INFO - 1500316394636 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fe718af4-2643-4bf0-ad3e-686a22f6ee61"}]
11:33:14 INFO - 1500316394883 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:33:14 INFO - 1500316394884 Marionette TRACE 394 <- [1,5,null,{}]
11:33:14 INFO - 1500316394886 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4ab7120c-f60f-42bf-a12f-487235e79226"}]
11:33:14 INFO - 1500316394908 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
11:33:14 INFO - 1500316394910 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:33:14 INFO - 1500316394915 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:33:14 INFO - 1500316394917 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}]
11:33:14 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500316394921 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:33:14 INFO - 1500316394923 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:33:14 INFO - 1500316394925 Marionette TRACE 394 <- [1,9,null,{}]
11:33:14 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 379ms
11:33:14 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:33:14 INFO - 1500316394929 Marionette DEBUG Closed connection 394
11:33:14 INFO - 1500316394930 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49805
11:33:14 INFO - 1500316394945 Marionette DEBUG Closed connection 395
11:33:14 INFO - 1500316394946 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49806
11:33:14 INFO - 1500316394948 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394951 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:14 INFO - 1500316394954 Marionette DEBUG Register listener.js for window 15
11:33:14 INFO - 1500316394961 Marionette TRACE 396 <- [1,1,null,{"sessionId":"19496c2c-d9bb-4cd9-b031-cc69a4c173f8","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:14 INFO - 1500316394965 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:33:14 INFO - 1500316394966 Marionette TRACE 396 <- [1,2,null,{}]
11:33:14 INFO - 1500316394967 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:33:14 INFO - 1500316394968 Marionette TRACE 396 <- [1,3,null,{}]
11:33:14 INFO - 1500316394969 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:33:14 INFO - 1500316394970 Marionette TRACE 396 <- [1,4,null,{}]
11:33:14 INFO - 1500316394973 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}]
11:33:14 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500316394976 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:33:14 INFO - 1500316394978 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:33:14 INFO - 1500316394980 Marionette TRACE 396 <- [1,6,null,{}]
11:33:14 INFO - 1500316394987 Marionette DEBUG Closed connection 396
11:33:14 INFO - 1500316394989 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49807
11:33:14 INFO - 1500316394990 Marionette DEBUG Closed connection 397
11:33:14 INFO - 1500316394990 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49808
11:33:14 INFO - 1500316394993 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:33:14 INFO - 1500316394995 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:15 INFO - 1500316394998 Marionette DEBUG Register listener.js for window 15
11:33:15 INFO - 1500316395003 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6c1aa0b0-ff1d-4f15-ad5d-608c957b9c52","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:15 INFO - 1500316395005 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:33:15 INFO - 1500316395007 Marionette TRACE 398 <- [1,2,null,{}]
11:33:15 INFO - 1500316395010 Marionette DEBUG Closed connection 398
11:33:15 INFO - 1500316395010 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49809
11:33:15 INFO - 1500316395011 Marionette DEBUG Closed connection 399
11:33:15 INFO - 1500316395012 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49810
11:33:15 INFO - 1500316395015 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:33:15 INFO - 1500316395016 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}
11:33:15 INFO - 1500316395019 Marionette DEBUG Register listener.js for window 15
11:33:15 INFO - 1500316395023 Marionette TRACE 400 <- [1,1,null,{"sessionId":"18cb54bb-623f-40cf-9d13-c9d028c5e63f","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-218.001\\AppData\\Local\\Temp\\tmpznppk5.mozrunner","moz:accessibilityChecks":false}}]
11:33:15 INFO - 1500316395026 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"}]
11:33:15 INFO - 1500316395028 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"
11:33:15 INFO - 1500316395039 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"
11:33:15 INFO - 1500316395039 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"
11:33:15 INFO - 1500316395073 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"
11:33:15 INFO - 1500316395076 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"
11:33:15 INFO - 1500316395082 Marionette TRACE 400 <- [1,2,null,{}]
11:33:15 INFO - 1500316395089 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:33:15 INFO - 1500316395092 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf1e9e2b-bf13-4a7f-afca-07bdce22e3eb","ELEMENT":"bf1e9e2b-bf13-4a7f-afca-07bdce22e3eb"}}]
11:33:15 INFO - 1500316395093 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bf1e9e2b-bf13-4a7f-afca-07bdce22e3eb"}]
11:33:15 INFO - 1500316395121 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:33:15 INFO - 1500316395122 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bf1e9e2b-bf13-4a7f-afca-07bdce22e3eb"}]
11:33:15 INFO - 1500316395142 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element