could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
05:10:40 INFO - 1499256640775 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}]
05:10:40 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499256640778 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:10:40 INFO - 1499256640779 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:10:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 136ms
05:10:40 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:10:40 INFO - 1499256640780 Marionette TRACE 352 <- [1,6,null,{}]
05:10:40 INFO - 1499256640782 Marionette DEBUG Closed connection 352
05:10:40 INFO - 1499256640783 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49776
05:10:40 INFO - 1499256640783 Marionette DEBUG Closed connection 353
05:10:40 INFO - 1499256640784 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49777
05:10:40 INFO - 1499256640785 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:40 INFO - 1499256640785 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:40 INFO - 1499256640788 Marionette DEBUG Register listener.js for window 4294967297
05:10:40 INFO - 1499256640792 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ffa7fa04-b487-449c-976c-82ff4d56c5eb","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:40 INFO - 1499256640793 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:10:40 INFO - 1499256640793 Marionette TRACE 354 <- [1,2,null,{}]
05:10:40 INFO - 1499256640794 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:40 INFO - 1499256640794 Marionette TRACE 354 <- [1,3,null,{}]
05:10:40 INFO - 1499256640795 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:40 INFO - 1499256640796 Marionette TRACE 354 <- [1,4,null,{}]
05:10:40 INFO - 1499256640797 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}]
05:10:40 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499256640800 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:10:40 INFO - 1499256640801 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:10:40 INFO - 1499256640803 Marionette TRACE 354 <- [1,6,null,{}]
05:10:40 INFO - 1499256640804 Marionette DEBUG Closed connection 354
05:10:40 INFO - 1499256640805 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49778
05:10:40 INFO - 1499256640806 Marionette DEBUG Closed connection 355
05:10:40 INFO - 1499256640806 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49779
05:10:40 INFO - 1499256640807 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:40 INFO - 1499256640808 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:40 INFO - 1499256640811 Marionette DEBUG Register listener.js for window 4294967297
05:10:40 INFO - 1499256640814 Marionette TRACE 356 <- [1,1,null,{"sessionId":"da1992e7-ad79-45c2-bc5a-58a2ebcb0483","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:40 INFO - 1499256640815 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:10:40 INFO - 1499256640817 Marionette TRACE 356 <- [1,2,null,{}]
05:10:40 INFO - 1499256640818 Marionette DEBUG Closed connection 356
05:10:40 INFO - 1499256640819 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49780
05:10:40 INFO - 1499256640820 Marionette DEBUG Closed connection 357
05:10:40 INFO - 1499256640821 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49781
05:10:40 INFO - 1499256640822 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:40 INFO - 1499256640823 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:40 INFO - 1499256640825 Marionette DEBUG Register listener.js for window 4294967297
05:10:40 INFO - 1499256640828 Marionette TRACE 358 <- [1,1,null,{"sessionId":"88c52079-014a-4800-9b9c-96c1b0176916","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:40 INFO - 1499256640830 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49336/clicks.html"}]
05:10:40 INFO - 1499256640831 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/hidden.html"
05:10:40 INFO - 1499256640840 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/hidden.html"
05:10:40 INFO - 1499256640840 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/hidden.html"
05:10:40 INFO - 1499256640847 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/clicks.html"
05:10:40 INFO - 1499256640853 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/clicks.html"
05:10:40 INFO - 1499256640856 Marionette TRACE 358 <- [1,2,null,{}]
05:10:40 INFO - 1499256640860 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:10:40 INFO - 1499256640863 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bba9120f-485e-407e-a94a-5e6af1304993","ELEMENT":"bba9120f-485e-407e-a94a-5e6af1304993"}}]
05:10:40 INFO - 1499256640864 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"bba9120f-485e-407e-a94a-5e6af1304993"}]
05:10:40 INFO - 1499256640909 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/clicks.html"
05:10:40 INFO - 1499256640917 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/clicks.html"
05:10:40 INFO - 1499256640917 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/clicks.html"
05:10:40 INFO - 1499256640927 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/test.html"
05:10:40 INFO - 1499256640933 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/test.html"
05:10:40 INFO - 1499256640934 Marionette TRACE 358 <- [1,4,null,{}]
05:10:40 INFO - 1499256640937 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:10:40 INFO - 1499256640942 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85de5152-8917-413e-8346-4f0a563cb5fe","ELEMENT":"85de5152-8917-413e-8346-4f0a563cb5fe"}}]
05:10:40 INFO - 1499256640944 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:10:40 INFO - 1499256640944 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:10:40 INFO - 1499256640947 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}]
05:10:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499256640950 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:10:40 INFO - 1499256640951 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:10:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
05:10:40 INFO - TEST-START | test_click.py TestClick.test_container_element
05:10:40 INFO - 1499256640953 Marionette TRACE 358 <- [1,8,null,{}]
05:10:40 INFO - 1499256640954 Marionette DEBUG Closed connection 358
05:10:40 INFO - 1499256640955 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49784
05:10:40 INFO - 1499256640956 Marionette DEBUG Closed connection 359
05:10:40 INFO - 1499256640957 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49785
05:10:40 INFO - 1499256640958 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:40 INFO - 1499256640958 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:40 INFO - 1499256640961 Marionette DEBUG Register listener.js for window 4294967297
05:10:40 INFO - 1499256640965 Marionette TRACE 360 <- [1,1,null,{"sessionId":"328df888-99d1-4fab-924c-55cbcc8f46a6","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:40 INFO - 1499256640966 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:10:40 INFO - 1499256640967 Marionette TRACE 360 <- [1,2,null,{}]
05:10:40 INFO - 1499256640968 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:40 INFO - 1499256640968 Marionette TRACE 360 <- [1,3,null,{}]
05:10:40 INFO - 1499256640969 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:40 INFO - 1499256640969 Marionette TRACE 360 <- [1,4,null,{}]
05:10:40 INFO - 1499256640971 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}]
05:10:40 INFO - TEST-START: test_click.py TestClick.test_container_element1499256640974 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:10:40 INFO - 1499256640975 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:10:40 INFO - 1499256640977 Marionette TRACE 360 <- [1,6,null,{}]
05:10:40 INFO - 1499256640978 Marionette DEBUG Closed connection 360
05:10:40 INFO - 1499256640979 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49786
05:10:40 INFO - 1499256640979 Marionette DEBUG Closed connection 361
05:10:40 INFO - 1499256640980 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49787
05:10:40 INFO - 1499256640981 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:40 INFO - 1499256640982 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:40 INFO - 1499256640985 Marionette DEBUG Register listener.js for window 4294967297
05:10:40 INFO - 1499256640988 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cc60ddf1-a274-4057-bb25-71c0fbaf67b6","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:40 INFO - 1499256640989 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:10:40 INFO - 1499256640990 Marionette TRACE 362 <- [1,2,null,{}]
05:10:40 INFO - 1499256640992 Marionette DEBUG Closed connection 362
05:10:40 INFO - 1499256640993 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49788
05:10:40 INFO - 1499256640993 Marionette DEBUG Closed connection 363
05:10:40 INFO - 1499256640994 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49789
05:10:40 INFO - 1499256640995 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:40 INFO - 1499256640996 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256640998 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641001 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9ee856a1-bae2-41ca-a97a-bfdfdf5242c0","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641002 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"}]
05:10:41 INFO - 1499256641004 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/test.html"
05:10:41 INFO - 1499256641006 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/test.html"
05:10:41 INFO - 1499256641006 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/test.html"
05:10:41 INFO - 1499256641019 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641025 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641033 Marionette TRACE 364 <- [1,2,null,{}]
05:10:41 INFO - 1499256641037 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:10:41 INFO - 1499256641042 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ccb6166b-5414-4bb1-b1f4-12270cf8829a","ELEMENT":"ccb6166b-5414-4bb1-b1f4-12270cf8829a"}}]
05:10:41 INFO - 1499256641043 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ccb6166b-5414-4bb1-b1f4-12270cf8829a"}]
05:10:41 INFO - 1499256641286 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:10:41 INFO - 1499256641287 Marionette TRACE 364 <- [1,4,null,{}]
05:10:41 INFO - 1499256641288 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"ccb6166b-5414-4bb1-b1f4-12270cf8829a","name":"selected"}]
05:10:41 INFO - 1499256641293 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:10:41 INFO - 1499256641295 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}]
05:10:41 INFO - TEST-END: test_click.py TestClick.test_container_element1499256641297 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:10:41 INFO - 1499256641298 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:10:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 346ms
05:10:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:10:41 INFO - 1499256641300 Marionette TRACE 364 <- [1,7,null,{}]
05:10:41 INFO - 1499256641301 Marionette DEBUG Closed connection 364
05:10:41 INFO - 1499256641302 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49790
05:10:41 INFO - 1499256641303 Marionette DEBUG Closed connection 365
05:10:41 INFO - 1499256641304 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49791
05:10:41 INFO - 1499256641305 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:41 INFO - 1499256641305 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641308 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641311 Marionette TRACE 366 <- [1,1,null,{"sessionId":"906528de-8f76-4913-83f2-5c491ab01b50","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641313 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:10:41 INFO - 1499256641313 Marionette TRACE 366 <- [1,2,null,{}]
05:10:41 INFO - 1499256641314 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:41 INFO - 1499256641314 Marionette TRACE 366 <- [1,3,null,{}]
05:10:41 INFO - 1499256641315 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:41 INFO - 1499256641316 Marionette TRACE 366 <- [1,4,null,{}]
05:10:41 INFO - 1499256641318 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}]
05:10:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499256641321 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:10:41 INFO - 1499256641322 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:10:41 INFO - 1499256641323 Marionette TRACE 366 <- [1,6,null,{}]
05:10:41 INFO - 1499256641325 Marionette DEBUG Closed connection 366
05:10:41 INFO - 1499256641325 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49792
05:10:41 INFO - 1499256641326 Marionette DEBUG Closed connection 367
05:10:41 INFO - 1499256641327 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49793
05:10:41 INFO - 1499256641328 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:41 INFO - 1499256641328 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641331 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641334 Marionette TRACE 368 <- [1,1,null,{"sessionId":"37b715bc-8a6d-46c3-a4d5-23ef6f2f634a","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641335 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:10:41 INFO - 1499256641336 Marionette TRACE 368 <- [1,2,null,{}]
05:10:41 INFO - 1499256641338 Marionette DEBUG Closed connection 368
05:10:41 INFO - 1499256641338 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49794
05:10:41 INFO - 1499256641339 Marionette DEBUG Closed connection 369
05:10:41 INFO - 1499256641340 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49795
05:10:41 INFO - 1499256641341 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:41 INFO - 1499256641342 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641344 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641347 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b045084c-3249-4dc9-9294-d94226fc2855","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641349 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"}]
05:10:41 INFO - 1499256641351 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641353 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641354 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641360 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641363 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641367 Marionette TRACE 370 <- [1,2,null,{}]
05:10:41 INFO - 1499256641370 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:10:41 INFO - 1499256641377 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"04f30af8-f9d9-4257-80bb-ae1ce360c57d","ELEMENT":"04f30af8-f9d9-4257-80bb-ae1ce360c57d"}}]
05:10:41 INFO - 1499256641378 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"04f30af8-f9d9-4257-80bb-ae1ce360c57d"}]
05:10:41 INFO - 1499256641623 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:10:41 INFO - 1499256641624 Marionette TRACE 370 <- [1,4,null,{}]
05:10:41 INFO - 1499256641625 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"04f30af8-f9d9-4257-80bb-ae1ce360c57d","name":"selected"}]
05:10:41 INFO - 1499256641630 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:10:41 INFO - 1499256641631 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}]
05:10:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499256641634 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:10:41 INFO - 1499256641635 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:10:41 INFO - 1499256641636 Marionette TRACE 370 <- [1,7,null,{}]
05:10:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 336ms
05:10:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:10:41 INFO - 1499256641638 Marionette DEBUG Closed connection 370
05:10:41 INFO - 1499256641639 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49796
05:10:41 INFO - 1499256641640 Marionette DEBUG Closed connection 371
05:10:41 INFO - 1499256641641 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49797
05:10:41 INFO - 1499256641642 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:41 INFO - 1499256641642 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641645 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641648 Marionette TRACE 372 <- [1,1,null,{"sessionId":"6b5ca7ed-f808-47bd-8b87-589d5cf1c472","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641649 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:10:41 INFO - 1499256641650 Marionette TRACE 372 <- [1,2,null,{}]
05:10:41 INFO - 1499256641651 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:41 INFO - 1499256641651 Marionette TRACE 372 <- [1,3,null,{}]
05:10:41 INFO - 1499256641652 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:41 INFO - 1499256641653 Marionette TRACE 372 <- [1,4,null,{}]
05:10:41 INFO - 1499256641655 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}]
05:10:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499256641658 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:10:41 INFO - 1499256641659 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:10:41 INFO - 1499256641660 Marionette TRACE 372 <- [1,6,null,{}]
05:10:41 INFO - 1499256641661 Marionette DEBUG Closed connection 372
05:10:41 INFO - 1499256641662 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49798
05:10:41 INFO - 1499256641663 Marionette DEBUG Closed connection 373
05:10:41 INFO - 1499256641664 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49799
05:10:41 INFO - 1499256641665 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:41 INFO - 1499256641666 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641669 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641673 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2870b078-4ff0-4b60-b124-77dc8d7c8024","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641674 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:10:41 INFO - 1499256641676 Marionette TRACE 374 <- [1,2,null,{}]
05:10:41 INFO - 1499256641677 Marionette DEBUG Closed connection 374
05:10:41 INFO - 1499256641678 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49800
05:10:41 INFO - 1499256641679 Marionette DEBUG Closed connection 375
05:10:41 INFO - 1499256641679 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49801
05:10:41 INFO - 1499256641680 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:41 INFO - 1499256641681 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641684 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641687 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9da2cb25-b678-49d9-ba70-29de24661b56","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641689 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"}]
05:10:41 INFO - 1499256641691 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641693 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641693 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:41 INFO - 1499256641701 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:10:41 INFO - 1499256641704 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:10:41 INFO - 1499256641712 Marionette TRACE 376 <- [1,2,null,{}]
05:10:41 INFO - 1499256641716 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:10:41 INFO - 1499256641718 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1df8432a-0310-4791-a3c5-8d2f60d69b2e","ELEMENT":"1df8432a-0310-4791-a3c5-8d2f60d69b2e"}}]
05:10:41 INFO - 1499256641720 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1df8432a-0310-4791-a3c5-8d2f60d69b2e"}]
05:10:41 INFO - 1499256641968 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:10:41 INFO - 1499256641969 Marionette TRACE 376 <- [1,4,null,{}]
05:10:41 INFO - 1499256641971 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}]
05:10:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499256641973 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:10:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 339ms
05:10:41 INFO - 1499256641974 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:10:41 INFO - 1499256641976 Marionette TRACE 376 <- [1,6,null,{}]
05:10:41 INFO - 1499256641978 Marionette DEBUG Closed connection 376
05:10:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:10:41 INFO - 1499256641981 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49802
05:10:41 INFO - 1499256641981 Marionette DEBUG Closed connection 377
05:10:41 INFO - 1499256641982 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49803
05:10:41 INFO - 1499256641983 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:41 INFO - 1499256641983 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:41 INFO - 1499256641986 Marionette DEBUG Register listener.js for window 4294967297
05:10:41 INFO - 1499256641989 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d7abc1b1-1c6b-49b2-8d5e-9c60ae7a9c00","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:41 INFO - 1499256641990 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:10:41 INFO - 1499256641991 Marionette TRACE 378 <- [1,2,null,{}]
05:10:41 INFO - 1499256641992 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:41 INFO - 1499256641992 Marionette TRACE 378 <- [1,3,null,{}]
05:10:41 INFO - 1499256641993 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:41 INFO - 1499256641994 Marionette TRACE 378 <- [1,4,null,{}]
05:10:41 INFO - 1499256641996 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}]
05:10:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499256641998 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:10:42 INFO - 1499256641999 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:10:42 INFO - 1499256642001 Marionette TRACE 378 <- [1,6,null,{}]
05:10:42 INFO - 1499256642003 Marionette DEBUG Closed connection 378
05:10:42 INFO - 1499256642003 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49804
05:10:42 INFO - 1499256642004 Marionette DEBUG Closed connection 379
05:10:42 INFO - 1499256642005 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49805
05:10:42 INFO - 1499256642006 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:42 INFO - 1499256642006 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642009 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642012 Marionette TRACE 380 <- [1,1,null,{"sessionId":"84910207-fced-40a2-a76c-dda1ea09805f","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642013 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:10:42 INFO - 1499256642015 Marionette TRACE 380 <- [1,2,null,{}]
05:10:42 INFO - 1499256642016 Marionette DEBUG Closed connection 380
05:10:42 INFO - 1499256642017 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49806
05:10:42 INFO - 1499256642018 Marionette DEBUG Closed connection 381
05:10:42 INFO - 1499256642018 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49807
05:10:42 INFO - 1499256642019 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:42 INFO - 1499256642020 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642023 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642034 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d6577289-f57c-4f2c-bb65-92cd29604e6a","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642036 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"}]
05:10:42 INFO - 1499256642038 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:10:42 INFO - 1499256642040 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:10:42 INFO - 1499256642040 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:10:42 INFO - 1499256642047 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:42 INFO - 1499256642051 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:42 INFO - 1499256642052 Marionette TRACE 382 <- [1,2,null,{}]
05:10:42 INFO - 1499256642059 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:10:42 INFO - 1499256642062 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b205a996-2566-4cd4-a2d4-fc2a969e6d6b","ELEMENT":"b205a996-2566-4cd4-a2d4-fc2a969e6d6b"}}]
05:10:42 INFO - 1499256642063 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b205a996-2566-4cd4-a2d4-fc2a969e6d6b"}]
05:10:42 INFO - 1499256642305 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:10:42 INFO - 1499256642306 Marionette TRACE 382 <- [1,4,null,{}]
05:10:42 INFO - 1499256642308 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}]
05:10:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499256642367 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:10:42 INFO - 1499256642371 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:10:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 397ms
05:10:42 INFO - 1499256642375 Marionette TRACE 382 <- [1,6,null,{}]
05:10:42 INFO - TEST-START | test_click.py TestClick.test_input_file
05:10:42 INFO - 1499256642379 Marionette DEBUG Closed connection 382
05:10:42 INFO - 1499256642380 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49808
05:10:42 INFO - 1499256642382 Marionette DEBUG Closed connection 383
05:10:42 INFO - 1499256642383 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49809
05:10:42 INFO - 1499256642385 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:42 INFO - 1499256642388 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642392 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642407 Marionette TRACE 384 <- [1,1,null,{"sessionId":"14820913-3814-4d0d-a691-c2f66b1583b9","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642410 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:10:42 INFO - 1499256642411 Marionette TRACE 384 <- [1,2,null,{}]
05:10:42 INFO - 1499256642413 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:42 INFO - 1499256642414 Marionette TRACE 384 <- [1,3,null,{}]
05:10:42 INFO - 1499256642416 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:42 INFO - 1499256642418 Marionette TRACE 384 <- [1,4,null,{}]
05:10:42 INFO - 1499256642419 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}]
05:10:42 INFO - TEST-START: test_click.py TestClick.test_input_file1499256642423 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:10:42 INFO - 1499256642425 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:10:42 INFO - 1499256642427 Marionette TRACE 384 <- [1,6,null,{}]
05:10:42 INFO - 1499256642429 Marionette DEBUG Closed connection 384
05:10:42 INFO - 1499256642431 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49810
05:10:42 INFO - 1499256642432 Marionette DEBUG Closed connection 385
05:10:42 INFO - 1499256642434 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49811
05:10:42 INFO - 1499256642435 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:42 INFO - 1499256642437 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642475 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642481 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f63dad15-9883-471a-b0c3-ba68b67d3d55","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642482 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:10:42 INFO - 1499256642484 Marionette TRACE 386 <- [1,2,null,{}]
05:10:42 INFO - 1499256642486 Marionette DEBUG Closed connection 386
05:10:42 INFO - 1499256642487 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49812
05:10:42 INFO - 1499256642488 Marionette DEBUG Closed connection 387
05:10:42 INFO - 1499256642489 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49813
05:10:42 INFO - 1499256642491 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:42 INFO - 1499256642492 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642506 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642510 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d8ce0e10-3c99-4aa6-b216-e585385efa0f","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642514 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:10:42 INFO - 1499256642517 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:42 INFO - 1499256642519 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:42 INFO - 1499256642519 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:10:42 INFO - 1499256642526 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:10:42 INFO - 1499256642531 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:10:42 INFO - 1499256642551 Marionette TRACE 388 <- [1,2,null,{}]
05:10:42 INFO - 1499256642561 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:10:42 INFO - 1499256642569 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f313494-bb19-49ca-947d-f278af6d35c2","ELEMENT":"4f313494-bb19-49ca-947d-f278af6d35c2"}}]
05:10:42 INFO - 1499256642571 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4f313494-bb19-49ca-947d-f278af6d35c2"}]
05:10:42 INFO - 1499256642607 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
05:10:42 INFO - 1499256642609 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}]
05:10:42 INFO - TEST-END: test_click.py TestClick.test_input_file1499256642613 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:10:42 INFO - 1499256642614 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:10:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 239ms
05:10:42 INFO - 1499256642615 Marionette TRACE 388 <- [1,6,null,{}]
05:10:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:10:42 INFO - 1499256642617 Marionette DEBUG Closed connection 388
05:10:42 INFO - 1499256642619 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49814
05:10:42 INFO - 1499256642620 Marionette DEBUG Closed connection 389
05:10:42 INFO - 1499256642620 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49815
05:10:42 INFO - 1499256642622 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:42 INFO - 1499256642622 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642625 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642629 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e54f72a6-23fa-4390-aeb7-2a26d0f31173","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642631 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:10:42 INFO - 1499256642632 Marionette TRACE 390 <- [1,2,null,{}]
05:10:42 INFO - 1499256642633 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:42 INFO - 1499256642633 Marionette TRACE 390 <- [1,3,null,{}]
05:10:42 INFO - 1499256642634 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:42 INFO - 1499256642635 Marionette TRACE 390 <- [1,4,null,{}]
05:10:42 INFO - 1499256642636 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}]
05:10:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499256642639 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:10:42 INFO - 1499256642640 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:10:42 INFO - 1499256642642 Marionette TRACE 390 <- [1,6,null,{}]
05:10:42 INFO - 1499256642644 Marionette DEBUG Closed connection 390
05:10:42 INFO - 1499256642644 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49816
05:10:42 INFO - 1499256642645 Marionette DEBUG Closed connection 391
05:10:42 INFO - 1499256642646 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49817
05:10:42 INFO - 1499256642647 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:42 INFO - 1499256642648 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642651 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642656 Marionette TRACE 392 <- [1,1,null,{"sessionId":"69d523d9-ead8-40ef-b200-d143fb969cb3","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642658 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:10:42 INFO - 1499256642660 Marionette TRACE 392 <- [1,2,null,{}]
05:10:42 INFO - 1499256642663 Marionette DEBUG Closed connection 392
05:10:42 INFO - 1499256642665 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49818
05:10:42 INFO - 1499256642666 Marionette DEBUG Closed connection 393
05:10:42 INFO - 1499256642667 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49819
05:10:42 INFO - 1499256642668 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:42 INFO - 1499256642670 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:42 INFO - 1499256642673 Marionette DEBUG Register listener.js for window 4294967297
05:10:42 INFO - 1499256642677 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f3138462-10cd-4e65-bdcd-a2935a90699e","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:42 INFO - 1499256642679 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"}]
05:10:42 INFO - 1499256642681 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:10:42 INFO - 1499256642683 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:10:42 INFO - 1499256642683 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:10:42 INFO - 1499256642701 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:10:42 INFO - 1499256642703 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:10:42 INFO - 1499256642712 Marionette TRACE 394 <- [1,2,null,{}]
05:10:42 INFO - 1499256642715 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:10:42 INFO - 1499256642718 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"965215f1-9a52-4400-808e-bfc110319ed7","ELEMENT":"965215f1-9a52-4400-808e-bfc110319ed7"}}]
05:10:42 INFO - 1499256642719 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:10:42 INFO - 1499256642725 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e6d8fffa-72a9-48eb-a4df-80d82d7701b2","ELEMENT":"e6d8fffa-72a9-48eb-a4df-80d82d7701b2"}}]
05:10:42 INFO - 1499256642733 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"965215f1-9a52-4400-808e-bfc110319ed7"}]
05:10:42 INFO - 1499256642973 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:10:42 INFO - 1499256642974 Marionette TRACE 394 <- [1,5,null,{}]
05:10:42 INFO - 1499256642976 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"e6d8fffa-72a9-48eb-a4df-80d82d7701b2"}]
05:10:42 INFO - 1499256642993 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
05:10:42 INFO - 1499256642995 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:10:43 INFO - 1499256642998 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:10:43 INFO - 1499256643000 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}]
05:10:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499256643002 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:10:43 INFO - 1499256643004 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:10:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 389ms
05:10:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:10:43 INFO - 1499256643005 Marionette TRACE 394 <- [1,9,null,{}]
05:10:43 INFO - 1499256643007 Marionette DEBUG Closed connection 394
05:10:43 INFO - 1499256643008 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49820
05:10:43 INFO - 1499256643009 Marionette DEBUG Closed connection 395
05:10:43 INFO - 1499256643009 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49821
05:10:43 INFO - 1499256643010 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:43 INFO - 1499256643011 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:43 INFO - 1499256643014 Marionette DEBUG Register listener.js for window 4294967297
05:10:43 INFO - 1499256643017 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1251e44d-d130-4f61-9ed0-361c8e4b7ac5","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:43 INFO - 1499256643019 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:10:43 INFO - 1499256643019 Marionette TRACE 396 <- [1,2,null,{}]
05:10:43 INFO - 1499256643020 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:10:43 INFO - 1499256643021 Marionette TRACE 396 <- [1,3,null,{}]
05:10:43 INFO - 1499256643022 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:10:43 INFO - 1499256643023 Marionette TRACE 396 <- [1,4,null,{}]
05:10:43 INFO - 1499256643025 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}]
05:10:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499256643028 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:10:43 INFO - 1499256643029 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:10:43 INFO - 1499256643030 Marionette TRACE 396 <- [1,6,null,{}]
05:10:43 INFO - 1499256643032 Marionette DEBUG Closed connection 396
05:10:43 INFO - 1499256643032 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49822
05:10:43 INFO - 1499256643033 Marionette DEBUG Closed connection 397
05:10:43 INFO - 1499256643034 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49823
05:10:43 INFO - 1499256643035 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:10:43 INFO - 1499256643036 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:43 INFO - 1499256643039 Marionette DEBUG Register listener.js for window 4294967297
05:10:43 INFO - 1499256643042 Marionette TRACE 398 <- [1,1,null,{"sessionId":"db1659d3-1709-4781-bdd0-03b555cdd8a8","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:43 INFO - 1499256643044 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:10:43 INFO - 1499256643045 Marionette TRACE 398 <- [1,2,null,{}]
05:10:43 INFO - 1499256643046 Marionette DEBUG Closed connection 398
05:10:43 INFO - 1499256643047 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49824
05:10:43 INFO - 1499256643048 Marionette DEBUG Closed connection 399
05:10:43 INFO - 1499256643049 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49825
05:10:43 INFO - 1499256643050 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:10:43 INFO - 1499256643051 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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}
05:10:43 INFO - 1499256643054 Marionette DEBUG Register listener.js for window 4294967297
05:10:43 INFO - 1499256643057 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5bf4e539-debe-4495-acd3-67ab1178d7b0","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":2672,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpowd9mb.mozrunner","moz:accessibilityChecks":false}}]
05:10:43 INFO - 1499256643059 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"}]
05:10:43 INFO - 1499256643061 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:10:43 INFO - 1499256643062 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:10:43 INFO - 1499256643063 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:10:43 INFO - 1499256643069 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:10:43 INFO - 1499256643072 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:10:43 INFO - 1499256643083 Marionette TRACE 400 <- [1,2,null,{}]
05:10:43 INFO - 1499256643091 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:10:43 INFO - 1499256643094 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb655cac-184d-4f99-bdd7-753fd0d387a1","ELEMENT":"bb655cac-184d-4f99-bdd7-753fd0d387a1"}}]
05:10:43 INFO - 1499256643095 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bb655cac-184d-4f99-bdd7-753fd0d387a1"}]
05:10:43 INFO - 1499256643121 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:10:43 INFO - 1499256643123 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bb655cac-184d-4f99-bdd7-753fd0d387a1"}]
05:10:43 INFO - 1499256643139 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element