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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:20:45 INFO - 1499606445948 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:20:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499606445952 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:20:45 INFO - 1499606445953 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:20:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 127ms
06:20:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:20:45 INFO - 1499606445955 Marionette TRACE 353 <- [1,6,null,{}]
06:20:45 INFO - 1499606445956 Marionette DEBUG Closed connection 353
06:20:45 INFO - 1499606445957 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50026
06:20:45 INFO - 1499606445958 Marionette DEBUG Closed connection 354
06:20:45 INFO - 1499606445959 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50027
06:20:45 INFO - 1499606445960 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:45 INFO - 1499606445961 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:45 INFO - 1499606445963 Marionette DEBUG Register listener.js for window 4294967297
06:20:45 INFO - 1499606445967 Marionette TRACE 355 <- [1,1,null,{"sessionId":"bdea0535-7675-4455-bcad-cbb16ec9ae7a","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:45 INFO - 1499606445968 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:20:45 INFO - 1499606445969 Marionette TRACE 355 <- [1,2,null,{}]
06:20:45 INFO - 1499606445970 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:45 INFO - 1499606445970 Marionette TRACE 355 <- [1,3,null,{}]
06:20:45 INFO - 1499606445971 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:45 INFO - 1499606445971 Marionette TRACE 355 <- [1,4,null,{}]
06:20:45 INFO - 1499606445973 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:20:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499606445976 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:20:45 INFO - 1499606445977 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:20:45 INFO - 1499606445978 Marionette TRACE 355 <- [1,6,null,{}]
06:20:45 INFO - 1499606445980 Marionette DEBUG Closed connection 355
06:20:45 INFO - 1499606445980 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50028
06:20:45 INFO - 1499606445981 Marionette DEBUG Closed connection 356
06:20:45 INFO - 1499606445982 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50029
06:20:45 INFO - 1499606445983 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:45 INFO - 1499606445984 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:45 INFO - 1499606445986 Marionette DEBUG Register listener.js for window 4294967297
06:20:45 INFO - 1499606445989 Marionette TRACE 357 <- [1,1,null,{"sessionId":"e21c3915-bc0f-4c28-9f37-659baea8bc52","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:45 INFO - 1499606445991 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:20:45 INFO - 1499606445992 Marionette TRACE 357 <- [1,2,null,{}]
06:20:45 INFO - 1499606445993 Marionette DEBUG Closed connection 357
06:20:45 INFO - 1499606445994 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50030
06:20:45 INFO - 1499606445995 Marionette DEBUG Closed connection 358
06:20:45 INFO - 1499606445996 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50031
06:20:45 INFO - 1499606445997 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:45 INFO - 1499606445997 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446000 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446003 Marionette TRACE 359 <- [1,1,null,{"sessionId":"d08f074e-b4de-4077-a471-0e61f49ce44c","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446004 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49584/clicks.html"}]
06:20:46 INFO - 1499606446005 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49584/hidden.html"
06:20:46 INFO - 1499606446013 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49584/hidden.html"
06:20:46 INFO - 1499606446013 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49584/hidden.html"
06:20:46 INFO - 1499606446023 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49584/clicks.html"
06:20:46 INFO - 1499606446025 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49584/clicks.html"
06:20:46 INFO - 1499606446028 Marionette TRACE 359 <- [1,2,null,{}]
06:20:46 INFO - 1499606446037 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:20:46 INFO - 1499606446042 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01315fb7-97f4-4935-8196-d1e7de93e151","ELEMENT":"01315fb7-97f4-4935-8196-d1e7de93e151"}}]
06:20:46 INFO - 1499606446043 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"01315fb7-97f4-4935-8196-d1e7de93e151"}]
06:20:46 INFO - 1499606446089 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49584/clicks.html"
06:20:46 INFO - 1499606446097 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49584/clicks.html"
06:20:46 INFO - 1499606446097 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49584/clicks.html"
06:20:46 INFO - 1499606446104 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49584/test.html"
06:20:46 INFO - 1499606446107 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49584/test.html"
06:20:46 INFO - 1499606446122 Marionette TRACE 359 <- [1,4,null,{}]
06:20:46 INFO - 1499606446130 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:20:46 INFO - 1499606446137 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab877df2-1d88-48fa-a62e-a3db0b3de28c","ELEMENT":"ab877df2-1d88-48fa-a62e-a3db0b3de28c"}}]
06:20:46 INFO - 1499606446138 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:20:46 INFO - 1499606446139 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:20:46 INFO - 1499606446141 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:20:46 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499606446143 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:20:46 INFO - 1499606446144 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:20:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 190ms
06:20:46 INFO - TEST-START | test_click.py TestClick.test_container_element
06:20:46 INFO - 1499606446146 Marionette TRACE 359 <- [1,8,null,{}]
06:20:46 INFO - 1499606446147 Marionette DEBUG Closed connection 359
06:20:46 INFO - 1499606446148 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50034
06:20:46 INFO - 1499606446149 Marionette DEBUG Closed connection 360
06:20:46 INFO - 1499606446150 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50035
06:20:46 INFO - 1499606446151 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446151 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446154 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446157 Marionette TRACE 361 <- [1,1,null,{"sessionId":"a272cf8e-c46c-4fe4-9375-0fb8936d26c2","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446158 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:20:46 INFO - 1499606446159 Marionette TRACE 361 <- [1,2,null,{}]
06:20:46 INFO - 1499606446160 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:46 INFO - 1499606446160 Marionette TRACE 361 <- [1,3,null,{}]
06:20:46 INFO - 1499606446161 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:46 INFO - 1499606446161 Marionette TRACE 361 <- [1,4,null,{}]
06:20:46 INFO - 1499606446163 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:20:46 INFO - TEST-START: test_click.py TestClick.test_container_element1499606446167 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:20:46 INFO - 1499606446168 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:20:46 INFO - 1499606446169 Marionette TRACE 361 <- [1,6,null,{}]
06:20:46 INFO - 1499606446171 Marionette DEBUG Closed connection 361
06:20:46 INFO - 1499606446171 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50036
06:20:46 INFO - 1499606446172 Marionette DEBUG Closed connection 362
06:20:46 INFO - 1499606446173 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50037
06:20:46 INFO - 1499606446174 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446174 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446177 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446180 Marionette TRACE 363 <- [1,1,null,{"sessionId":"7fed844b-70a0-426e-82df-8b9cb43c17b5","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446181 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:20:46 INFO - 1499606446182 Marionette TRACE 363 <- [1,2,null,{}]
06:20:46 INFO - 1499606446184 Marionette DEBUG Closed connection 363
06:20:46 INFO - 1499606446185 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50038
06:20:46 INFO - 1499606446185 Marionette DEBUG Closed connection 364
06:20:46 INFO - 1499606446186 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50039
06:20:46 INFO - 1499606446187 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:46 INFO - 1499606446188 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446190 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446193 Marionette TRACE 365 <- [1,1,null,{"sessionId":"e983ccd0-c816-4701-a7ef-d16922e4c3dc","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446195 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:20:46 INFO - 1499606446196 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49584/test.html"
06:20:46 INFO - 1499606446199 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49584/test.html"
06:20:46 INFO - 1499606446200 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49584/test.html"
06:20:46 INFO - 1499606446207 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:20:46 INFO - 1499606446212 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:20:46 INFO - 1499606446216 Marionette TRACE 365 <- [1,2,null,{}]
06:20:46 INFO - 1499606446226 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:20:46 INFO - 1499606446229 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd1cd1c6-3dfd-457f-9c45-234a237dff40","ELEMENT":"bd1cd1c6-3dfd-457f-9c45-234a237dff40"}}]
06:20:46 INFO - 1499606446230 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"bd1cd1c6-3dfd-457f-9c45-234a237dff40"}]
06:20:46 INFO - 1499606446469 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:20:46 INFO - 1499606446470 Marionette TRACE 365 <- [1,4,null,{}]
06:20:46 INFO - 1499606446471 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"bd1cd1c6-3dfd-457f-9c45-234a237dff40","name":"selected"}]
06:20:46 INFO - 1499606446475 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:20:46 INFO - 1499606446477 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:20:46 INFO - TEST-END: test_click.py TestClick.test_container_element1499606446480 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:20:46 INFO - 1499606446481 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:20:46 INFO - 1499606446482 Marionette TRACE 365 <- [1,7,null,{}]
06:20:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 336ms
06:20:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:20:46 INFO - 1499606446484 Marionette DEBUG Closed connection 365
06:20:46 INFO - 1499606446485 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50040
06:20:46 INFO - 1499606446486 Marionette DEBUG Closed connection 366
06:20:46 INFO - 1499606446487 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50041
06:20:46 INFO - 1499606446488 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446488 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446491 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446494 Marionette TRACE 367 <- [1,1,null,{"sessionId":"e31fad0f-0fe4-4bb3-966b-a10301e906e0","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446495 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:20:46 INFO - 1499606446495 Marionette TRACE 367 <- [1,2,null,{}]
06:20:46 INFO - 1499606446496 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:46 INFO - 1499606446497 Marionette TRACE 367 <- [1,3,null,{}]
06:20:46 INFO - 1499606446498 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:46 INFO - 1499606446498 Marionette TRACE 367 <- [1,4,null,{}]
06:20:46 INFO - 1499606446500 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:20:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499606446503 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:20:46 INFO - 1499606446504 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:20:46 INFO - 1499606446505 Marionette TRACE 367 <- [1,6,null,{}]
06:20:46 INFO - 1499606446506 Marionette DEBUG Closed connection 367
06:20:46 INFO - 1499606446507 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50042
06:20:46 INFO - 1499606446508 Marionette DEBUG Closed connection 368
06:20:46 INFO - 1499606446508 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50043
06:20:46 INFO - 1499606446509 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446510 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446512 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446515 Marionette TRACE 369 <- [1,1,null,{"sessionId":"f9833169-a337-483c-8bcb-fd51d1cd343b","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446517 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:20:46 INFO - 1499606446518 Marionette TRACE 369 <- [1,2,null,{}]
06:20:46 INFO - 1499606446519 Marionette DEBUG Closed connection 369
06:20:46 INFO - 1499606446520 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50044
06:20:46 INFO - 1499606446521 Marionette DEBUG Closed connection 370
06:20:46 INFO - 1499606446521 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50045
06:20:46 INFO - 1499606446522 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:46 INFO - 1499606446523 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446525 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446528 Marionette TRACE 371 <- [1,1,null,{"sessionId":"f132c1e0-9ec8-4455-bf91-9c49494a3c6b","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446529 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:20:46 INFO - 1499606446531 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:20:46 INFO - 1499606446533 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:20:46 INFO - 1499606446533 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:20:46 INFO - 1499606446539 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:20:46 INFO - 1499606446542 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:20:46 INFO - 1499606446544 Marionette TRACE 371 <- [1,2,null,{}]
06:20:46 INFO - 1499606446547 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:20:46 INFO - 1499606446555 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a775cbaa-68d6-4b74-8642-e0b701e9c0df","ELEMENT":"a775cbaa-68d6-4b74-8642-e0b701e9c0df"}}]
06:20:46 INFO - 1499606446557 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"a775cbaa-68d6-4b74-8642-e0b701e9c0df"}]
06:20:46 INFO - 1499606446798 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:20:46 INFO - 1499606446799 Marionette TRACE 371 <- [1,4,null,{}]
06:20:46 INFO - 1499606446800 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"a775cbaa-68d6-4b74-8642-e0b701e9c0df","name":"selected"}]
06:20:46 INFO - 1499606446804 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:20:46 INFO - 1499606446805 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:20:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499606446808 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:20:46 INFO - 1499606446809 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:20:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 328ms
06:20:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:20:46 INFO - 1499606446810 Marionette TRACE 371 <- [1,7,null,{}]
06:20:46 INFO - 1499606446812 Marionette DEBUG Closed connection 371
06:20:46 INFO - 1499606446813 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50046
06:20:46 INFO - 1499606446813 Marionette DEBUG Closed connection 372
06:20:46 INFO - 1499606446814 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50047
06:20:46 INFO - 1499606446815 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446816 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446818 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446821 Marionette TRACE 373 <- [1,1,null,{"sessionId":"33ced04f-4406-41f1-804c-339f04b8719f","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446822 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:20:46 INFO - 1499606446823 Marionette TRACE 373 <- [1,2,null,{}]
06:20:46 INFO - 1499606446825 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:46 INFO - 1499606446826 Marionette TRACE 373 <- [1,3,null,{}]
06:20:46 INFO - 1499606446827 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:46 INFO - 1499606446827 Marionette TRACE 373 <- [1,4,null,{}]
06:20:46 INFO - 1499606446829 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:20:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499606446832 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:20:46 INFO - 1499606446833 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:20:46 INFO - 1499606446834 Marionette TRACE 373 <- [1,6,null,{}]
06:20:46 INFO - 1499606446836 Marionette DEBUG Closed connection 373
06:20:46 INFO - 1499606446836 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50048
06:20:46 INFO - 1499606446837 Marionette DEBUG Closed connection 374
06:20:46 INFO - 1499606446838 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50049
06:20:46 INFO - 1499606446839 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:46 INFO - 1499606446839 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446842 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446845 Marionette TRACE 375 <- [1,1,null,{"sessionId":"a5459ed8-7b59-4429-920b-f2e6e562820d","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446846 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:20:46 INFO - 1499606446847 Marionette TRACE 375 <- [1,2,null,{}]
06:20:46 INFO - 1499606446849 Marionette DEBUG Closed connection 375
06:20:46 INFO - 1499606446849 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50050
06:20:46 INFO - 1499606446850 Marionette DEBUG Closed connection 376
06:20:46 INFO - 1499606446851 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50051
06:20:46 INFO - 1499606446852 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:46 INFO - 1499606446852 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:46 INFO - 1499606446855 Marionette DEBUG Register listener.js for window 4294967297
06:20:46 INFO - 1499606446865 Marionette TRACE 377 <- [1,1,null,{"sessionId":"3132ed5e-9d7d-4290-b6d0-661610f6e306","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:46 INFO - 1499606446867 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:20:46 INFO - 1499606446868 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:20:46 INFO - 1499606446870 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:20:46 INFO - 1499606446871 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:20:46 INFO - 1499606446877 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:20:46 INFO - 1499606446879 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:20:46 INFO - 1499606446883 Marionette TRACE 377 <- [1,2,null,{}]
06:20:46 INFO - 1499606446892 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:20:46 INFO - 1499606446895 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ad5ad7e-9f26-4970-a332-c07ebfe0fc31","ELEMENT":"0ad5ad7e-9f26-4970-a332-c07ebfe0fc31"}}]
06:20:46 INFO - 1499606446896 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"0ad5ad7e-9f26-4970-a332-c07ebfe0fc31"}]
06:20:47 INFO - 1499606447138 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:20:47 INFO - 1499606447138 Marionette TRACE 377 <- [1,4,null,{}]
06:20:47 INFO - 1499606447140 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:20:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499606447143 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447144 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:20:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 335ms
06:20:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:20:47 INFO - 1499606447145 Marionette TRACE 377 <- [1,6,null,{}]
06:20:47 INFO - 1499606447147 Marionette DEBUG Closed connection 377
06:20:47 INFO - 1499606447148 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50052
06:20:47 INFO - 1499606447149 Marionette DEBUG Closed connection 378
06:20:47 INFO - 1499606447149 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50053
06:20:47 INFO - 1499606447150 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447151 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447153 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447156 Marionette TRACE 379 <- [1,1,null,{"sessionId":"4da09580-2e9d-445f-b8b0-c07a1c8795a9","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447157 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:20:47 INFO - 1499606447158 Marionette TRACE 379 <- [1,2,null,{}]
06:20:47 INFO - 1499606447159 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:47 INFO - 1499606447159 Marionette TRACE 379 <- [1,3,null,{}]
06:20:47 INFO - 1499606447160 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:47 INFO - 1499606447160 Marionette TRACE 379 <- [1,4,null,{}]
06:20:47 INFO - 1499606447162 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:20:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499606447165 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447166 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:20:47 INFO - 1499606447167 Marionette TRACE 379 <- [1,6,null,{}]
06:20:47 INFO - 1499606447169 Marionette DEBUG Closed connection 379
06:20:47 INFO - 1499606447169 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50054
06:20:47 INFO - 1499606447170 Marionette DEBUG Closed connection 380
06:20:47 INFO - 1499606447171 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50055
06:20:47 INFO - 1499606447172 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447172 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447175 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447178 Marionette TRACE 381 <- [1,1,null,{"sessionId":"d3ec9c26-c578-4045-b282-9a1a14cfd3c0","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447179 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:20:47 INFO - 1499606447180 Marionette TRACE 381 <- [1,2,null,{}]
06:20:47 INFO - 1499606447182 Marionette DEBUG Closed connection 381
06:20:47 INFO - 1499606447182 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50056
06:20:47 INFO - 1499606447183 Marionette DEBUG Closed connection 382
06:20:47 INFO - 1499606447184 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50057
06:20:47 INFO - 1499606447185 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:47 INFO - 1499606447186 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447188 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447191 Marionette TRACE 383 <- [1,1,null,{"sessionId":"992ff3ad-5a31-462e-8c2b-4e11363d2e62","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447193 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:20:47 INFO - 1499606447194 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:20:47 INFO - 1499606447196 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:20:47 INFO - 1499606447196 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:20:47 INFO - 1499606447204 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:20:47 INFO - 1499606447206 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:20:47 INFO - 1499606447212 Marionette TRACE 383 <- [1,2,null,{}]
06:20:47 INFO - 1499606447215 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:20:47 INFO - 1499606447221 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5f69c2c-6181-447c-8f8a-58fd258fb573","ELEMENT":"a5f69c2c-6181-447c-8f8a-58fd258fb573"}}]
06:20:47 INFO - 1499606447223 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"a5f69c2c-6181-447c-8f8a-58fd258fb573"}]
06:20:47 INFO - 1499606447464 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:20:47 INFO - 1499606447465 Marionette TRACE 383 <- [1,4,null,{}]
06:20:47 INFO - 1499606447466 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:20:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499606447469 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447470 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:20:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 325ms
06:20:47 INFO - 1499606447472 Marionette TRACE 383 <- [1,6,null,{}]
06:20:47 INFO - TEST-START | test_click.py TestClick.test_input_file
06:20:47 INFO - 1499606447473 Marionette DEBUG Closed connection 383
06:20:47 INFO - 1499606447474 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50058
06:20:47 INFO - 1499606447475 Marionette DEBUG Closed connection 384
06:20:47 INFO - 1499606447476 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50059
06:20:47 INFO - 1499606447477 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447477 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447480 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447483 Marionette TRACE 385 <- [1,1,null,{"sessionId":"eb85e067-b2b7-44e2-8f6f-720806d669a3","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447484 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:20:47 INFO - 1499606447485 Marionette TRACE 385 <- [1,2,null,{}]
06:20:47 INFO - 1499606447486 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:47 INFO - 1499606447486 Marionette TRACE 385 <- [1,3,null,{}]
06:20:47 INFO - 1499606447487 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:47 INFO - 1499606447487 Marionette TRACE 385 <- [1,4,null,{}]
06:20:47 INFO - 1499606447489 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:20:47 INFO - TEST-START: test_click.py TestClick.test_input_file1499606447492 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447493 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:20:47 INFO - 1499606447494 Marionette TRACE 385 <- [1,6,null,{}]
06:20:47 INFO - 1499606447496 Marionette DEBUG Closed connection 385
06:20:47 INFO - 1499606447496 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50060
06:20:47 INFO - 1499606447497 Marionette DEBUG Closed connection 386
06:20:47 INFO - 1499606447498 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50061
06:20:47 INFO - 1499606447499 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447500 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447502 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447505 Marionette TRACE 387 <- [1,1,null,{"sessionId":"95a5563d-388a-4f37-aa4f-fd847ceb59f8","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447506 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:20:47 INFO - 1499606447507 Marionette TRACE 387 <- [1,2,null,{}]
06:20:47 INFO - 1499606447509 Marionette DEBUG Closed connection 387
06:20:47 INFO - 1499606447509 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50062
06:20:47 INFO - 1499606447510 Marionette DEBUG Closed connection 388
06:20:47 INFO - 1499606447511 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50063
06:20:47 INFO - 1499606447512 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:47 INFO - 1499606447512 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447515 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447518 Marionette TRACE 389 <- [1,1,null,{"sessionId":"4e2aa404-a9b3-49b1-b328-0cd8f937f5cb","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447519 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:20:47 INFO - 1499606447520 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:20:47 INFO - 1499606447522 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:20:47 INFO - 1499606447523 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:20:47 INFO - 1499606447529 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:20:47 INFO - 1499606447533 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:20:47 INFO - 1499606447544 Marionette TRACE 389 <- [1,2,null,{}]
06:20:47 INFO - 1499606447548 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:20:47 INFO - 1499606447554 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ba4c753-38e6-4a7b-a417-a10b1056f62a","ELEMENT":"4ba4c753-38e6-4a7b-a417-a10b1056f62a"}}]
06:20:47 INFO - 1499606447555 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"4ba4c753-38e6-4a7b-a417-a10b1056f62a"}]
06:20:47 INFO - 1499606447581 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:20:47 INFO - 1499606447583 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:20:47 INFO - TEST-END: test_click.py TestClick.test_input_file1499606447585 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447586 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:20:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
06:20:47 INFO - 1499606447588 Marionette TRACE 389 <- [1,6,null,{}]
06:20:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:20:47 INFO - 1499606447589 Marionette DEBUG Closed connection 389
06:20:47 INFO - 1499606447590 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50064
06:20:47 INFO - 1499606447591 Marionette DEBUG Closed connection 390
06:20:47 INFO - 1499606447592 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50065
06:20:47 INFO - 1499606447593 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447593 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447596 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447599 Marionette TRACE 391 <- [1,1,null,{"sessionId":"0a85cb29-2171-41f5-85a9-efbfb5b322cf","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447600 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:20:47 INFO - 1499606447601 Marionette TRACE 391 <- [1,2,null,{}]
06:20:47 INFO - 1499606447602 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:47 INFO - 1499606447602 Marionette TRACE 391 <- [1,3,null,{}]
06:20:47 INFO - 1499606447603 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:47 INFO - 1499606447604 Marionette TRACE 391 <- [1,4,null,{}]
06:20:47 INFO - 1499606447605 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:20:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499606447608 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447609 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:20:47 INFO - 1499606447610 Marionette TRACE 391 <- [1,6,null,{}]
06:20:47 INFO - 1499606447612 Marionette DEBUG Closed connection 391
06:20:47 INFO - 1499606447613 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50066
06:20:47 INFO - 1499606447613 Marionette DEBUG Closed connection 392
06:20:47 INFO - 1499606447614 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50067
06:20:47 INFO - 1499606447615 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447616 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447618 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447621 Marionette TRACE 393 <- [1,1,null,{"sessionId":"6bb77258-2dee-42d2-a663-5f28f03128e4","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447622 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:20:47 INFO - 1499606447623 Marionette TRACE 393 <- [1,2,null,{}]
06:20:47 INFO - 1499606447625 Marionette DEBUG Closed connection 393
06:20:47 INFO - 1499606447626 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50068
06:20:47 INFO - 1499606447626 Marionette DEBUG Closed connection 394
06:20:47 INFO - 1499606447627 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50069
06:20:47 INFO - 1499606447628 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:47 INFO - 1499606447629 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447632 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447635 Marionette TRACE 395 <- [1,1,null,{"sessionId":"35208070-1ee6-4719-a89b-36ea5e0d0d5a","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447636 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:20:47 INFO - 1499606447637 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:20:47 INFO - 1499606447639 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:20:47 INFO - 1499606447640 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:20:47 INFO - 1499606447646 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:20:47 INFO - 1499606447648 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:20:47 INFO - 1499606447650 Marionette TRACE 395 <- [1,2,null,{}]
06:20:47 INFO - 1499606447659 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:20:47 INFO - 1499606447661 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5fe2a647-e059-44e5-a537-bc00205a690d","ELEMENT":"5fe2a647-e059-44e5-a537-bc00205a690d"}}]
06:20:47 INFO - 1499606447662 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:20:47 INFO - 1499606447665 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79e70f57-ac19-4975-b5e9-4a4dae5b93ff","ELEMENT":"79e70f57-ac19-4975-b5e9-4a4dae5b93ff"}}]
06:20:47 INFO - 1499606447666 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"5fe2a647-e059-44e5-a537-bc00205a690d"}]
06:20:47 INFO - 1499606447909 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:20:47 INFO - 1499606447910 Marionette TRACE 395 <- [1,5,null,{}]
06:20:47 INFO - 1499606447911 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"79e70f57-ac19-4975-b5e9-4a4dae5b93ff"}]
06:20:47 INFO - 1499606447925 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:20:47 INFO - 1499606447927 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:20:47 INFO - 1499606447930 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:20:47 INFO - 1499606447932 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:20:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499606447934 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:20:47 INFO - 1499606447935 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:20:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
06:20:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:20:47 INFO - 1499606447937 Marionette TRACE 395 <- [1,9,null,{}]
06:20:47 INFO - 1499606447938 Marionette DEBUG Closed connection 395
06:20:47 INFO - 1499606447939 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50070
06:20:47 INFO - 1499606447940 Marionette DEBUG Closed connection 396
06:20:47 INFO - 1499606447940 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50071
06:20:47 INFO - 1499606447941 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447942 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447944 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447948 Marionette TRACE 397 <- [1,1,null,{"sessionId":"9460e12c-d197-49f2-9417-0519cbc5a950","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447949 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:20:47 INFO - 1499606447950 Marionette TRACE 397 <- [1,2,null,{}]
06:20:47 INFO - 1499606447950 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:20:47 INFO - 1499606447951 Marionette TRACE 397 <- [1,3,null,{}]
06:20:47 INFO - 1499606447952 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:20:47 INFO - 1499606447952 Marionette TRACE 397 <- [1,4,null,{}]
06:20:47 INFO - 1499606447954 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:20:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499606447957 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:20:47 INFO - 1499606447957 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:20:47 INFO - 1499606447959 Marionette TRACE 397 <- [1,6,null,{}]
06:20:47 INFO - 1499606447960 Marionette DEBUG Closed connection 397
06:20:47 INFO - 1499606447961 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50072
06:20:47 INFO - 1499606447962 Marionette DEBUG Closed connection 398
06:20:47 INFO - 1499606447962 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50073
06:20:47 INFO - 1499606447963 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:20:47 INFO - 1499606447964 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447966 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447969 Marionette TRACE 399 <- [1,1,null,{"sessionId":"e45c302a-e413-40cd-9dc9-77191af390a2","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447970 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:20:47 INFO - 1499606447972 Marionette TRACE 399 <- [1,2,null,{}]
06:20:47 INFO - 1499606447973 Marionette DEBUG Closed connection 399
06:20:47 INFO - 1499606447974 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50074
06:20:47 INFO - 1499606447975 Marionette DEBUG Closed connection 400
06:20:47 INFO - 1499606447975 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50075
06:20:47 INFO - 1499606447977 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:20:47 INFO - 1499606447977 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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}
06:20:47 INFO - 1499606447980 Marionette DEBUG Register listener.js for window 4294967297
06:20:47 INFO - 1499606447983 Marionette TRACE 401 <- [1,1,null,{"sessionId":"d0ad673a-3d0d-427e-ae54-f90a29db7f75","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":3368,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpzbt8kj.mozrunner","moz:accessibilityChecks":false}}]
06:20:47 INFO - 1499606447984 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:20:47 INFO - 1499606447986 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:20:47 INFO - 1499606447988 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:20:47 INFO - 1499606447988 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:20:47 INFO - 1499606447995 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:20:48 INFO - 1499606447998 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:20:48 INFO - 1499606448000 Marionette TRACE 401 <- [1,2,null,{}]
06:20:48 INFO - 1499606448007 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:20:48 INFO - 1499606448011 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c8f195f9-e690-4e49-bf75-e887682c1e14","ELEMENT":"c8f195f9-e690-4e49-bf75-e887682c1e14"}}]
06:20:48 INFO - 1499606448012 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c8f195f9-e690-4e49-bf75-e887682c1e14"}]
06:20:48 INFO - 1499606448033 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:20:48 INFO - 1499606448034 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"c8f195f9-e690-4e49-bf75-e887682c1e14"}]
06:20:48 INFO - 1499606448050 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element