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]
06:47:05 INFO - 1500212825937 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}]
06:47:05 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500212825940 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:47:05 INFO - 1500212825940 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:47:05 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 132ms
06:47:05 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:47:05 INFO - 1500212825942 Marionette TRACE 352 <- [1,6,null,{}]
06:47:05 INFO - 1500212825943 Marionette DEBUG Closed connection 352
06:47:05 INFO - 1500212825944 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50038
06:47:05 INFO - 1500212825945 Marionette DEBUG Closed connection 353
06:47:05 INFO - 1500212825946 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50039
06:47:05 INFO - 1500212825947 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:05 INFO - 1500212825948 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:05 INFO - 1500212825951 Marionette DEBUG Register listener.js for window 15
06:47:05 INFO - 1500212825954 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a214d236-aabb-4025-abb1-2ceb0a8cd68f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:05 INFO - 1500212825955 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:47:05 INFO - 1500212825956 Marionette TRACE 354 <- [1,2,null,{}]
06:47:05 INFO - 1500212825957 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:05 INFO - 1500212825957 Marionette TRACE 354 <- [1,3,null,{}]
06:47:05 INFO - 1500212825958 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:05 INFO - 1500212825958 Marionette TRACE 354 <- [1,4,null,{}]
06:47:05 INFO - 1500212825960 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}]
06:47:05 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500212825962 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:47:05 INFO - 1500212825963 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:47:05 INFO - 1500212825964 Marionette TRACE 354 <- [1,6,null,{}]
06:47:05 INFO - 1500212825966 Marionette DEBUG Closed connection 354
06:47:05 INFO - 1500212825967 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50040
06:47:05 INFO - 1500212825968 Marionette DEBUG Closed connection 355
06:47:05 INFO - 1500212825968 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50041
06:47:05 INFO - 1500212825969 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:05 INFO - 1500212825977 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:05 INFO - 1500212825979 Marionette DEBUG Register listener.js for window 15
06:47:05 INFO - 1500212825982 Marionette TRACE 356 <- [1,1,null,{"sessionId":"0168f83d-2c5f-4944-88a1-1d5fed2cee91","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:05 INFO - 1500212825984 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:47:05 INFO - 1500212825985 Marionette TRACE 356 <- [1,2,null,{}]
06:47:05 INFO - 1500212825987 Marionette DEBUG Closed connection 356
06:47:05 INFO - 1500212825987 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50042
06:47:05 INFO - 1500212825988 Marionette DEBUG Closed connection 357
06:47:05 INFO - 1500212825989 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50043
06:47:05 INFO - 1500212825990 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:05 INFO - 1500212825990 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:05 INFO - 1500212825992 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212825995 Marionette TRACE 358 <- [1,1,null,{"sessionId":"640c61eb-9aa1-448a-89b5-d9658c288b2c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212825997 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49602/clicks.html"}]
06:47:06 INFO - 1500212825999 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/hidden.html"
06:47:06 INFO - 1500212826004 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/hidden.html"
06:47:06 INFO - 1500212826004 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/hidden.html"
06:47:06 INFO - 1500212826017 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49602/clicks.html"
06:47:06 INFO - 1500212826024 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49602/clicks.html"
06:47:06 INFO - 1500212826028 Marionette TRACE 358 <- [1,2,null,{}]
06:47:06 INFO - 1500212826029 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:47:06 INFO - 1500212826032 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"864e875a-06e1-48ad-ba37-a9a02e7bdfcf","ELEMENT":"864e875a-06e1-48ad-ba37-a9a02e7bdfcf"}}]
06:47:06 INFO - 1500212826032 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"864e875a-06e1-48ad-ba37-a9a02e7bdfcf"}]
06:47:06 INFO - 1500212826079 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/clicks.html"
06:47:06 INFO - 1500212826088 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/clicks.html"
06:47:06 INFO - 1500212826088 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/clicks.html"
06:47:06 INFO - 1500212826106 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49602/test.html"
06:47:06 INFO - 1500212826108 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49602/test.html"
06:47:06 INFO - 1500212826111 Marionette TRACE 358 <- [1,4,null,{}]
06:47:06 INFO - 1500212826112 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:47:06 INFO - 1500212826123 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c16bc892-3bbc-4a96-a311-f9347e338c3a","ELEMENT":"c16bc892-3bbc-4a96-a311-f9347e338c3a"}}]
06:47:06 INFO - 1500212826125 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:47:06 INFO - 1500212826125 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:47:06 INFO - 1500212826127 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}]
06:47:06 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500212826129 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:47:06 INFO - 1500212826130 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:47:06 INFO - 1500212826131 Marionette TRACE 358 <- [1,8,null,{}]
06:47:06 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 189ms
06:47:06 INFO - TEST-START | test_click.py TestClick.test_container_element
06:47:06 INFO - 1500212826133 Marionette DEBUG Closed connection 358
06:47:06 INFO - 1500212826134 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50046
06:47:06 INFO - 1500212826135 Marionette DEBUG Closed connection 359
06:47:06 INFO - 1500212826136 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50047
06:47:06 INFO - 1500212826137 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826138 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826140 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826143 Marionette TRACE 360 <- [1,1,null,{"sessionId":"786e2c1b-fa77-46ca-80fc-abf4a1bcfae3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826145 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:47:06 INFO - 1500212826145 Marionette TRACE 360 <- [1,2,null,{}]
06:47:06 INFO - 1500212826146 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:06 INFO - 1500212826146 Marionette TRACE 360 <- [1,3,null,{}]
06:47:06 INFO - 1500212826147 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:06 INFO - 1500212826148 Marionette TRACE 360 <- [1,4,null,{}]
06:47:06 INFO - 1500212826149 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}]
06:47:06 INFO - TEST-START: test_click.py TestClick.test_container_element1500212826152 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:47:06 INFO - 1500212826153 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:47:06 INFO - 1500212826154 Marionette TRACE 360 <- [1,6,null,{}]
06:47:06 INFO - 1500212826156 Marionette DEBUG Closed connection 360
06:47:06 INFO - 1500212826157 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50048
06:47:06 INFO - 1500212826157 Marionette DEBUG Closed connection 361
06:47:06 INFO - 1500212826158 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50049
06:47:06 INFO - 1500212826159 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826160 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826162 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826164 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6508cda6-5243-4024-ae43-0a026239344c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826166 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:47:06 INFO - 1500212826167 Marionette TRACE 362 <- [1,2,null,{}]
06:47:06 INFO - 1500212826169 Marionette DEBUG Closed connection 362
06:47:06 INFO - 1500212826170 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50050
06:47:06 INFO - 1500212826170 Marionette DEBUG Closed connection 363
06:47:06 INFO - 1500212826171 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50051
06:47:06 INFO - 1500212826172 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:06 INFO - 1500212826173 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826175 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826177 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9485ab01-3bdb-45d3-8ef1-e6377021ff13","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826179 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"}]
06:47:06 INFO - 1500212826181 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49602/test.html"
06:47:06 INFO - 1500212826184 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49602/test.html"
06:47:06 INFO - 1500212826184 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49602/test.html"
06:47:06 INFO - 1500212826213 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826218 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826229 Marionette TRACE 364 <- [1,2,null,{}]
06:47:06 INFO - 1500212826231 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:47:06 INFO - 1500212826233 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"631865c0-38ec-42f8-a8af-6cd47f18ea0b","ELEMENT":"631865c0-38ec-42f8-a8af-6cd47f18ea0b"}}]
06:47:06 INFO - 1500212826234 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"631865c0-38ec-42f8-a8af-6cd47f18ea0b"}]
06:47:06 INFO - 1500212826472 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:47:06 INFO - 1500212826472 Marionette TRACE 364 <- [1,4,null,{}]
06:47:06 INFO - 1500212826474 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"631865c0-38ec-42f8-a8af-6cd47f18ea0b","name":"selected"}]
06:47:06 INFO - 1500212826478 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:47:06 INFO - 1500212826479 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}]
06:47:06 INFO - TEST-END: test_click.py TestClick.test_container_element1500212826482 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:47:06 INFO - 1500212826483 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:47:06 INFO - 1500212826484 Marionette TRACE 364 <- [1,7,null,{}]
06:47:06 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
06:47:06 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:47:06 INFO - 1500212826486 Marionette DEBUG Closed connection 364
06:47:06 INFO - 1500212826487 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50052
06:47:06 INFO - 1500212826487 Marionette DEBUG Closed connection 365
06:47:06 INFO - 1500212826488 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50053
06:47:06 INFO - 1500212826489 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826490 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826492 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826495 Marionette TRACE 366 <- [1,1,null,{"sessionId":"445d0e87-9c06-4fae-8135-5ac0b902c185","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826496 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:47:06 INFO - 1500212826497 Marionette TRACE 366 <- [1,2,null,{}]
06:47:06 INFO - 1500212826498 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:06 INFO - 1500212826498 Marionette TRACE 366 <- [1,3,null,{}]
06:47:06 INFO - 1500212826499 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:06 INFO - 1500212826499 Marionette TRACE 366 <- [1,4,null,{}]
06:47:06 INFO - 1500212826501 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}]
06:47:06 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500212826504 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:47:06 INFO - 1500212826505 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:47:06 INFO - 1500212826507 Marionette TRACE 366 <- [1,6,null,{}]
06:47:06 INFO - 1500212826508 Marionette DEBUG Closed connection 366
06:47:06 INFO - 1500212826509 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50054
06:47:06 INFO - 1500212826510 Marionette DEBUG Closed connection 367
06:47:06 INFO - 1500212826511 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50055
06:47:06 INFO - 1500212826512 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826512 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826514 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826525 Marionette TRACE 368 <- [1,1,null,{"sessionId":"30dceb50-5399-47fd-970f-36caa1f28a22","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826527 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:47:06 INFO - 1500212826528 Marionette TRACE 368 <- [1,2,null,{}]
06:47:06 INFO - 1500212826530 Marionette DEBUG Closed connection 368
06:47:06 INFO - 1500212826531 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50056
06:47:06 INFO - 1500212826531 Marionette DEBUG Closed connection 369
06:47:06 INFO - 1500212826532 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50057
06:47:06 INFO - 1500212826533 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:06 INFO - 1500212826534 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826536 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826538 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9ba9a2e0-73a8-4623-a5c7-5e26b5c626d6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826540 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"}]
06:47:06 INFO - 1500212826542 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826544 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826544 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826560 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826562 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826565 Marionette TRACE 370 <- [1,2,null,{}]
06:47:06 INFO - 1500212826571 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:47:06 INFO - 1500212826574 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18f99d10-970c-4923-b5a5-42a585e19ee8","ELEMENT":"18f99d10-970c-4923-b5a5-42a585e19ee8"}}]
06:47:06 INFO - 1500212826578 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"18f99d10-970c-4923-b5a5-42a585e19ee8"}]
06:47:06 INFO - 1500212826817 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:47:06 INFO - 1500212826817 Marionette TRACE 370 <- [1,4,null,{}]
06:47:06 INFO - 1500212826819 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"18f99d10-970c-4923-b5a5-42a585e19ee8","name":"selected"}]
06:47:06 INFO - 1500212826823 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:47:06 INFO - 1500212826824 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}]
06:47:06 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500212826827 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:47:06 INFO - 1500212826827 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:47:06 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 345ms
06:47:06 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:47:06 INFO - 1500212826829 Marionette TRACE 370 <- [1,7,null,{}]
06:47:06 INFO - 1500212826830 Marionette DEBUG Closed connection 370
06:47:06 INFO - 1500212826831 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50058
06:47:06 INFO - 1500212826832 Marionette DEBUG Closed connection 371
06:47:06 INFO - 1500212826833 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50059
06:47:06 INFO - 1500212826834 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826834 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826836 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826839 Marionette TRACE 372 <- [1,1,null,{"sessionId":"28d4949b-a656-4d12-8c3b-3edae69d003e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826841 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:47:06 INFO - 1500212826841 Marionette TRACE 372 <- [1,2,null,{}]
06:47:06 INFO - 1500212826842 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:06 INFO - 1500212826843 Marionette TRACE 372 <- [1,3,null,{}]
06:47:06 INFO - 1500212826843 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:06 INFO - 1500212826844 Marionette TRACE 372 <- [1,4,null,{}]
06:47:06 INFO - 1500212826846 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}]
06:47:06 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500212826848 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:47:06 INFO - 1500212826849 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:47:06 INFO - 1500212826850 Marionette TRACE 372 <- [1,6,null,{}]
06:47:06 INFO - 1500212826852 Marionette DEBUG Closed connection 372
06:47:06 INFO - 1500212826853 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50060
06:47:06 INFO - 1500212826853 Marionette DEBUG Closed connection 373
06:47:06 INFO - 1500212826854 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50061
06:47:06 INFO - 1500212826855 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:06 INFO - 1500212826855 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826857 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826860 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0e39bccd-f947-4c4f-83a2-f6a50c51aae9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826862 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:47:06 INFO - 1500212826863 Marionette TRACE 374 <- [1,2,null,{}]
06:47:06 INFO - 1500212826865 Marionette DEBUG Closed connection 374
06:47:06 INFO - 1500212826866 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50062
06:47:06 INFO - 1500212826866 Marionette DEBUG Closed connection 375
06:47:06 INFO - 1500212826867 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50063
06:47:06 INFO - 1500212826868 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:06 INFO - 1500212826868 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:06 INFO - 1500212826870 Marionette DEBUG Register listener.js for window 15
06:47:06 INFO - 1500212826873 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e22cdc9d-21dc-4d23-91ce-693db55a4d55","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:06 INFO - 1500212826875 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"}]
06:47:06 INFO - 1500212826877 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826879 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826880 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:06 INFO - 1500212826903 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:47:06 INFO - 1500212826905 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:47:06 INFO - 1500212826908 Marionette TRACE 376 <- [1,2,null,{}]
06:47:06 INFO - 1500212826909 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:47:06 INFO - 1500212826911 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"58a09227-f768-4b60-bbdd-74d6e9b51d31","ELEMENT":"58a09227-f768-4b60-bbdd-74d6e9b51d31"}}]
06:47:06 INFO - 1500212826912 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"58a09227-f768-4b60-bbdd-74d6e9b51d31"}]
06:47:07 INFO - 1500212827151 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:47:07 INFO - 1500212827151 Marionette TRACE 376 <- [1,4,null,{}]
06:47:07 INFO - 1500212827153 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}]
06:47:07 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500212827156 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827157 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827158 Marionette TRACE 376 <- [1,6,null,{}]
06:47:07 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 329ms
06:47:07 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:47:07 INFO - 1500212827160 Marionette DEBUG Closed connection 376
06:47:07 INFO - 1500212827161 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50064
06:47:07 INFO - 1500212827161 Marionette DEBUG Closed connection 377
06:47:07 INFO - 1500212827162 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50065
06:47:07 INFO - 1500212827163 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827164 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827166 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827169 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a107736d-81d2-4c4f-a3de-9f5748c979db","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827170 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:47:07 INFO - 1500212827171 Marionette TRACE 378 <- [1,2,null,{}]
06:47:07 INFO - 1500212827172 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:07 INFO - 1500212827172 Marionette TRACE 378 <- [1,3,null,{}]
06:47:07 INFO - 1500212827173 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:07 INFO - 1500212827173 Marionette TRACE 378 <- [1,4,null,{}]
06:47:07 INFO - 1500212827175 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}]
06:47:07 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500212827177 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827178 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827179 Marionette TRACE 378 <- [1,6,null,{}]
06:47:07 INFO - 1500212827181 Marionette DEBUG Closed connection 378
06:47:07 INFO - 1500212827182 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50066
06:47:07 INFO - 1500212827182 Marionette DEBUG Closed connection 379
06:47:07 INFO - 1500212827183 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50067
06:47:07 INFO - 1500212827184 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827185 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827186 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827189 Marionette TRACE 380 <- [1,1,null,{"sessionId":"99168cb9-aeff-47f7-8d55-ab6a412bbfae","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827198 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:47:07 INFO - 1500212827199 Marionette TRACE 380 <- [1,2,null,{}]
06:47:07 INFO - 1500212827201 Marionette DEBUG Closed connection 380
06:47:07 INFO - 1500212827201 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50068
06:47:07 INFO - 1500212827202 Marionette DEBUG Closed connection 381
06:47:07 INFO - 1500212827203 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50069
06:47:07 INFO - 1500212827204 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:07 INFO - 1500212827204 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827206 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827209 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0ca7fa66-bbc5-42eb-ac4d-63e79f29b70b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827211 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"}]
06:47:07 INFO - 1500212827212 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:47:07 INFO - 1500212827214 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:47:07 INFO - 1500212827214 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
06:47:07 INFO - 1500212827232 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:07 INFO - 1500212827239 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:07 INFO - 1500212827242 Marionette TRACE 382 <- [1,2,null,{}]
06:47:07 INFO - 1500212827244 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:47:07 INFO - 1500212827246 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c3ba9279-6306-4cef-9431-fa39d07da989","ELEMENT":"c3ba9279-6306-4cef-9431-fa39d07da989"}}]
06:47:07 INFO - 1500212827247 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c3ba9279-6306-4cef-9431-fa39d07da989"}]
06:47:07 INFO - 1500212827492 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:47:07 INFO - 1500212827492 Marionette TRACE 382 <- [1,4,null,{}]
06:47:07 INFO - 1500212827494 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}]
06:47:07 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500212827497 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827498 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827499 Marionette TRACE 382 <- [1,6,null,{}]
06:47:07 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 341ms
06:47:07 INFO - TEST-START | test_click.py TestClick.test_input_file
06:47:07 INFO - 1500212827501 Marionette DEBUG Closed connection 382
06:47:07 INFO - 1500212827502 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50070
06:47:07 INFO - 1500212827502 Marionette DEBUG Closed connection 383
06:47:07 INFO - 1500212827503 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50071
06:47:07 INFO - 1500212827504 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827505 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827507 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827509 Marionette TRACE 384 <- [1,1,null,{"sessionId":"77784185-4f93-45bb-bba5-1a26d411da01","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827511 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:47:07 INFO - 1500212827512 Marionette TRACE 384 <- [1,2,null,{}]
06:47:07 INFO - 1500212827512 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:07 INFO - 1500212827513 Marionette TRACE 384 <- [1,3,null,{}]
06:47:07 INFO - 1500212827514 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:07 INFO - 1500212827514 Marionette TRACE 384 <- [1,4,null,{}]
06:47:07 INFO - 1500212827516 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}]
06:47:07 INFO - TEST-START: test_click.py TestClick.test_input_file1500212827518 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827519 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827520 Marionette TRACE 384 <- [1,6,null,{}]
06:47:07 INFO - 1500212827522 Marionette DEBUG Closed connection 384
06:47:07 INFO - 1500212827523 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50072
06:47:07 INFO - 1500212827523 Marionette DEBUG Closed connection 385
06:47:07 INFO - 1500212827524 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50073
06:47:07 INFO - 1500212827525 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827526 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827528 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827531 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5e85eaf9-3643-41b1-b189-881704e497af","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827532 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:47:07 INFO - 1500212827533 Marionette TRACE 386 <- [1,2,null,{}]
06:47:07 INFO - 1500212827535 Marionette DEBUG Closed connection 386
06:47:07 INFO - 1500212827536 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50074
06:47:07 INFO - 1500212827536 Marionette DEBUG Closed connection 387
06:47:07 INFO - 1500212827537 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50075
06:47:07 INFO - 1500212827538 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:07 INFO - 1500212827539 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827541 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827543 Marionette TRACE 388 <- [1,1,null,{"sessionId":"542e26f4-2845-497d-aee1-3ecb0f2c705c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827545 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:47:07 INFO - 1500212827547 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:07 INFO - 1500212827548 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:07 INFO - 1500212827549 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
06:47:07 INFO - 1500212827571 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:47:07 INFO - 1500212827572 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:47:07 INFO - 1500212827575 Marionette TRACE 388 <- [1,2,null,{}]
06:47:07 INFO - 1500212827578 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:47:07 INFO - 1500212827580 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42d17025-b724-4f3d-81cb-c842602bd97e","ELEMENT":"42d17025-b724-4f3d-81cb-c842602bd97e"}}]
06:47:07 INFO - 1500212827581 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"42d17025-b724-4f3d-81cb-c842602bd97e"}]
06:47:07 INFO - 1500212827613 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]
06:47:07 INFO - 1500212827615 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}]
06:47:07 INFO - TEST-END: test_click.py TestClick.test_input_file1500212827618 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827621 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827622 Marionette TRACE 388 <- [1,6,null,{}]
06:47:07 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 123ms
06:47:07 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:47:07 INFO - 1500212827624 Marionette DEBUG Closed connection 388
06:47:07 INFO - 1500212827625 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50076
06:47:07 INFO - 1500212827626 Marionette DEBUG Closed connection 389
06:47:07 INFO - 1500212827627 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50077
06:47:07 INFO - 1500212827628 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827628 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827630 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827633 Marionette TRACE 390 <- [1,1,null,{"sessionId":"191d83a6-e685-4c8c-b603-9b2fcb69143f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827635 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:47:07 INFO - 1500212827635 Marionette TRACE 390 <- [1,2,null,{}]
06:47:07 INFO - 1500212827636 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:07 INFO - 1500212827636 Marionette TRACE 390 <- [1,3,null,{}]
06:47:07 INFO - 1500212827637 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:07 INFO - 1500212827638 Marionette TRACE 390 <- [1,4,null,{}]
06:47:07 INFO - 1500212827639 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}]
06:47:07 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500212827642 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:47:07 INFO - 1500212827643 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:47:07 INFO - 1500212827644 Marionette TRACE 390 <- [1,6,null,{}]
06:47:07 INFO - 1500212827645 Marionette DEBUG Closed connection 390
06:47:07 INFO - 1500212827646 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50078
06:47:07 INFO - 1500212827647 Marionette DEBUG Closed connection 391
06:47:07 INFO - 1500212827648 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50079
06:47:07 INFO - 1500212827648 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827649 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827651 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827654 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a11f1b6c-2ac0-4ff3-8b70-4e8284ec734e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827655 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:47:07 INFO - 1500212827656 Marionette TRACE 392 <- [1,2,null,{}]
06:47:07 INFO - 1500212827658 Marionette DEBUG Closed connection 392
06:47:07 INFO - 1500212827659 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50080
06:47:07 INFO - 1500212827659 Marionette DEBUG Closed connection 393
06:47:07 INFO - 1500212827660 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50081
06:47:07 INFO - 1500212827662 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:07 INFO - 1500212827662 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827664 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827673 Marionette TRACE 394 <- [1,1,null,{"sessionId":"28076394-5f41-46c5-9edb-a6fc6d6a264a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827676 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"}]
06:47:07 INFO - 1500212827677 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:47:07 INFO - 1500212827679 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:47:07 INFO - 1500212827679 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:47:07 INFO - 1500212827697 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:47:07 INFO - 1500212827700 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:47:07 INFO - 1500212827709 Marionette TRACE 394 <- [1,2,null,{}]
06:47:07 INFO - 1500212827711 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:47:07 INFO - 1500212827713 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bb49fe0c-0ab3-44f1-8081-1f22dd31d641","ELEMENT":"bb49fe0c-0ab3-44f1-8081-1f22dd31d641"}}]
06:47:07 INFO - 1500212827714 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:47:07 INFO - 1500212827716 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b252975d-1916-449f-a4ae-c999748e2098","ELEMENT":"b252975d-1916-449f-a4ae-c999748e2098"}}]
06:47:07 INFO - 1500212827717 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"bb49fe0c-0ab3-44f1-8081-1f22dd31d641"}]
06:47:07 INFO - 1500212827951 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:47:07 INFO - 1500212827951 Marionette TRACE 394 <- [1,5,null,{}]
06:47:07 INFO - 1500212827953 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b252975d-1916-449f-a4ae-c999748e2098"}]
06:47:07 INFO - 1500212827967 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]
06:47:07 INFO - 1500212827968 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:47:07 INFO - 1500212827971 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:47:07 INFO - 1500212827972 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}]
06:47:07 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500212827975 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:47:07 INFO - 1500212827976 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:47:07 INFO - 1500212827977 Marionette TRACE 394 <- [1,9,null,{}]
06:47:07 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 354ms
06:47:07 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:47:07 INFO - 1500212827979 Marionette DEBUG Closed connection 394
06:47:07 INFO - 1500212827980 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50082
06:47:07 INFO - 1500212827981 Marionette DEBUG Closed connection 395
06:47:07 INFO - 1500212827981 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50083
06:47:07 INFO - 1500212827982 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:07 INFO - 1500212827983 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:07 INFO - 1500212827985 Marionette DEBUG Register listener.js for window 15
06:47:07 INFO - 1500212827988 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ffeeef6e-7e44-4de2-acdb-348169351725","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:07 INFO - 1500212827990 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:47:07 INFO - 1500212827990 Marionette TRACE 396 <- [1,2,null,{}]
06:47:07 INFO - 1500212827991 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:47:07 INFO - 1500212827991 Marionette TRACE 396 <- [1,3,null,{}]
06:47:07 INFO - 1500212827992 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:47:07 INFO - 1500212827993 Marionette TRACE 396 <- [1,4,null,{}]
06:47:07 INFO - 1500212827994 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}]
06:47:08 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500212827997 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:47:08 INFO - 1500212827998 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:47:08 INFO - 1500212827999 Marionette TRACE 396 <- [1,6,null,{}]
06:47:08 INFO - 1500212828001 Marionette DEBUG Closed connection 396
06:47:08 INFO - 1500212828001 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50084
06:47:08 INFO - 1500212828002 Marionette DEBUG Closed connection 397
06:47:08 INFO - 1500212828003 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50085
06:47:08 INFO - 1500212828004 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:47:08 INFO - 1500212828004 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:08 INFO - 1500212828006 Marionette DEBUG Register listener.js for window 15
06:47:08 INFO - 1500212828009 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b30feb38-572a-4287-af14-dfefb26ef105","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:08 INFO - 1500212828011 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:47:08 INFO - 1500212828012 Marionette TRACE 398 <- [1,2,null,{}]
06:47:08 INFO - 1500212828013 Marionette DEBUG Closed connection 398
06:47:08 INFO - 1500212828014 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50086
06:47:08 INFO - 1500212828014 Marionette DEBUG Closed connection 399
06:47:08 INFO - 1500212828015 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50087
06:47:08 INFO - 1500212828017 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:47:08 INFO - 1500212828018 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}
06:47:08 INFO - 1500212828020 Marionette DEBUG Register listener.js for window 15
06:47:08 INFO - 1500212828024 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b6ef325c-840f-4657-acf2-0099032f730e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1800,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8tz4fx.mozrunner","moz:accessibilityChecks":false}}]
06:47:08 INFO - 1500212828026 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"}]
06:47:08 INFO - 1500212828027 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:47:08 INFO - 1500212828029 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:47:08 INFO - 1500212828030 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
06:47:08 INFO - 1500212828046 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:47:08 INFO - 1500212828049 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
06:47:08 INFO - 1500212828058 Marionette TRACE 400 <- [1,2,null,{}]
06:47:08 INFO - 1500212828059 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:47:08 INFO - 1500212828062 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4a92072-6be1-4ed3-bdff-db322743bd9d","ELEMENT":"c4a92072-6be1-4ed3-bdff-db322743bd9d"}}]
06:47:08 INFO - 1500212828063 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c4a92072-6be1-4ed3-bdff-db322743bd9d"}]
06:47:08 INFO - 1500212828084 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:47:08 INFO - 1500212828087 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c4a92072-6be1-4ed3-bdff-db322743bd9d"}]
06:47:08 INFO - 1500212828105 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element