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]
05:22:26 INFO - 1500121346921 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:22:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
05:22:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:22:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500121346925 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:22:26 INFO - 1500121346926 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:22:26 INFO - 1500121346927 Marionette TRACE 352 <- [1,6,null,{}]
05:22:26 INFO - 1500121346929 Marionette DEBUG Closed connection 352
05:22:26 INFO - 1500121346930 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50341
05:22:26 INFO - 1500121346931 Marionette DEBUG Closed connection 353
05:22:26 INFO - 1500121346932 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50342
05:22:26 INFO - 1500121346933 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:26 INFO - 1500121346933 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:26 INFO - 1500121346935 Marionette DEBUG Register listener.js for window 15
05:22:26 INFO - 1500121346938 Marionette TRACE 354 <- [1,1,null,{"sessionId":"3a1030c2-67fd-4bef-9fe5-8c889e94cbc2","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:26 INFO - 1500121346940 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:22:26 INFO - 1500121346941 Marionette TRACE 354 <- [1,2,null,{}]
05:22:26 INFO - 1500121346942 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:26 INFO - 1500121346942 Marionette TRACE 354 <- [1,3,null,{}]
05:22:26 INFO - 1500121346943 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:26 INFO - 1500121346943 Marionette TRACE 354 <- [1,4,null,{}]
05:22:26 INFO - 1500121346945 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:22:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500121346948 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:22:26 INFO - 1500121346949 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:22:26 INFO - 1500121346950 Marionette TRACE 354 <- [1,6,null,{}]
05:22:26 INFO - 1500121346952 Marionette DEBUG Closed connection 354
05:22:26 INFO - 1500121346953 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50343
05:22:26 INFO - 1500121346953 Marionette DEBUG Closed connection 355
05:22:26 INFO - 1500121346954 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50344
05:22:26 INFO - 1500121346955 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:26 INFO - 1500121346956 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:26 INFO - 1500121346958 Marionette DEBUG Register listener.js for window 15
05:22:26 INFO - 1500121346960 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b027b3ae-7833-463d-8774-5af75ef58cb0","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:26 INFO - 1500121346962 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:22:26 INFO - 1500121346963 Marionette TRACE 356 <- [1,2,null,{}]
05:22:26 INFO - 1500121346965 Marionette DEBUG Closed connection 356
05:22:26 INFO - 1500121346966 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50345
05:22:26 INFO - 1500121346967 Marionette DEBUG Closed connection 357
05:22:26 INFO - 1500121346967 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50346
05:22:26 INFO - 1500121346969 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:26 INFO - 1500121346969 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:26 INFO - 1500121346972 Marionette DEBUG Register listener.js for window 15
05:22:26 INFO - 1500121346976 Marionette TRACE 358 <- [1,1,null,{"sessionId":"521f2c22-a991-4e5b-b113-fa147578a07c","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:26 INFO - 1500121346979 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49902/clicks.html"}]
05:22:26 INFO - 1500121346980 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49902/hidden.html"
05:22:26 INFO - 1500121346986 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49902/hidden.html"
05:22:26 INFO - 1500121346986 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49902/hidden.html"
05:22:27 INFO - 1500121347004 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49902/clicks.html"
05:22:27 INFO - 1500121347010 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49902/clicks.html"
05:22:27 INFO - 1500121347014 Marionette TRACE 358 <- [1,2,null,{}]
05:22:27 INFO - 1500121347016 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:22:27 INFO - 1500121347018 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2dfa416a-7066-40a3-9ebc-aeed7862bd5e","ELEMENT":"2dfa416a-7066-40a3-9ebc-aeed7862bd5e"}}]
05:22:27 INFO - 1500121347019 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2dfa416a-7066-40a3-9ebc-aeed7862bd5e"}]
05:22:27 INFO - 1500121347061 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49902/clicks.html"
05:22:27 INFO - 1500121347066 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49902/clicks.html"
05:22:27 INFO - 1500121347067 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49902/clicks.html"
05:22:27 INFO - 1500121347087 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49902/test.html"
05:22:27 INFO - 1500121347091 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49902/test.html"
05:22:27 INFO - 1500121347095 Marionette TRACE 358 <- [1,4,null,{}]
05:22:27 INFO - 1500121347096 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:22:27 INFO - 1500121347099 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e777e331-be6d-46c3-a851-85f0ba5fad2c","ELEMENT":"e777e331-be6d-46c3-a851-85f0ba5fad2c"}}]
05:22:27 INFO - 1500121347100 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:22:27 INFO - 1500121347100 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:22:27 INFO - 1500121347102 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:22:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 189ms
05:22:27 INFO - TEST-START | test_click.py TestClick.test_container_element
05:22:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500121347113 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:22:27 INFO - 1500121347115 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:22:27 INFO - 1500121347116 Marionette TRACE 358 <- [1,8,null,{}]
05:22:27 INFO - 1500121347118 Marionette DEBUG Closed connection 358
05:22:27 INFO - 1500121347119 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50349
05:22:27 INFO - 1500121347120 Marionette DEBUG Closed connection 359
05:22:27 INFO - 1500121347121 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50350
05:22:27 INFO - 1500121347122 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347124 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347126 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347138 Marionette TRACE 360 <- [1,1,null,{"sessionId":"bd7b1803-5a6e-4862-a682-6204e9d6b874","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347140 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:22:27 INFO - 1500121347140 Marionette TRACE 360 <- [1,2,null,{}]
05:22:27 INFO - 1500121347141 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:27 INFO - 1500121347142 Marionette TRACE 360 <- [1,3,null,{}]
05:22:27 INFO - 1500121347142 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:27 INFO - 1500121347143 Marionette TRACE 360 <- [1,4,null,{}]
05:22:27 INFO - 1500121347144 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:22:27 INFO - TEST-START: test_click.py TestClick.test_container_element1500121347147 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:22:27 INFO - 1500121347148 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:22:27 INFO - 1500121347149 Marionette TRACE 360 <- [1,6,null,{}]
05:22:27 INFO - 1500121347151 Marionette DEBUG Closed connection 360
05:22:27 INFO - 1500121347152 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50351
05:22:27 INFO - 1500121347153 Marionette DEBUG Closed connection 361
05:22:27 INFO - 1500121347153 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50352
05:22:27 INFO - 1500121347154 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347155 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347157 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347160 Marionette TRACE 362 <- [1,1,null,{"sessionId":"472741fb-7005-4f87-ba5b-a8530e495ba7","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347162 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:22:27 INFO - 1500121347163 Marionette TRACE 362 <- [1,2,null,{}]
05:22:27 INFO - 1500121347165 Marionette DEBUG Closed connection 362
05:22:27 INFO - 1500121347165 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50353
05:22:27 INFO - 1500121347166 Marionette DEBUG Closed connection 363
05:22:27 INFO - 1500121347167 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50354
05:22:27 INFO - 1500121347168 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:27 INFO - 1500121347168 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347170 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347173 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d6e0b00a-109a-4f78-9562-310755c924ad","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347175 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:22:27 INFO - 1500121347176 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49902/test.html"
05:22:27 INFO - 1500121347178 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49902/test.html"
05:22:27 INFO - 1500121347178 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49902/test.html"
05:22:27 INFO - 1500121347197 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347199 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347203 Marionette TRACE 364 <- [1,2,null,{}]
05:22:27 INFO - 1500121347211 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:22:27 INFO - 1500121347215 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e2ecce72-5ddb-423b-88fb-ede738e393bc","ELEMENT":"e2ecce72-5ddb-423b-88fb-ede738e393bc"}}]
05:22:27 INFO - 1500121347216 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e2ecce72-5ddb-423b-88fb-ede738e393bc"}]
05:22:27 INFO - 1500121347456 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:22:27 INFO - 1500121347457 Marionette TRACE 364 <- [1,4,null,{}]
05:22:27 INFO - 1500121347458 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e2ecce72-5ddb-423b-88fb-ede738e393bc","name":"selected"}]
05:22:27 INFO - 1500121347462 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:22:27 INFO - 1500121347464 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:22:27 INFO - TEST-END: test_click.py TestClick.test_container_element1500121347466 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:22:27 INFO - 1500121347467 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:22:27 INFO - 1500121347468 Marionette TRACE 364 <- [1,7,null,{}]
05:22:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
05:22:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:22:27 INFO - 1500121347470 Marionette DEBUG Closed connection 364
05:22:27 INFO - 1500121347471 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50355
05:22:27 INFO - 1500121347472 Marionette DEBUG Closed connection 365
05:22:27 INFO - 1500121347473 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50356
05:22:27 INFO - 1500121347474 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347474 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347476 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347479 Marionette TRACE 366 <- [1,1,null,{"sessionId":"cfea3bb5-e90f-4403-adab-e4f7dc6c5d6b","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347481 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:22:27 INFO - 1500121347482 Marionette TRACE 366 <- [1,2,null,{}]
05:22:27 INFO - 1500121347482 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:27 INFO - 1500121347483 Marionette TRACE 366 <- [1,3,null,{}]
05:22:27 INFO - 1500121347484 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:27 INFO - 1500121347484 Marionette TRACE 366 <- [1,4,null,{}]
05:22:27 INFO - 1500121347486 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:22:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500121347488 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:22:27 INFO - 1500121347489 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:22:27 INFO - 1500121347491 Marionette TRACE 366 <- [1,6,null,{}]
05:22:27 INFO - 1500121347492 Marionette DEBUG Closed connection 366
05:22:27 INFO - 1500121347493 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50357
05:22:27 INFO - 1500121347494 Marionette DEBUG Closed connection 367
05:22:27 INFO - 1500121347494 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50358
05:22:27 INFO - 1500121347495 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347496 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347498 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347501 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4ba420b5-a57b-4571-afb2-4a101dddb632","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347503 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:22:27 INFO - 1500121347504 Marionette TRACE 368 <- [1,2,null,{}]
05:22:27 INFO - 1500121347506 Marionette DEBUG Closed connection 368
05:22:27 INFO - 1500121347506 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50359
05:22:27 INFO - 1500121347507 Marionette DEBUG Closed connection 369
05:22:27 INFO - 1500121347508 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50360
05:22:27 INFO - 1500121347509 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:27 INFO - 1500121347509 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347511 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347514 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e0283a32-87bc-4407-9372-fe98e996deae","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347516 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:22:27 INFO - 1500121347518 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347521 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347521 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347548 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347553 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347562 Marionette TRACE 370 <- [1,2,null,{}]
05:22:27 INFO - 1500121347565 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:22:27 INFO - 1500121347567 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"97d05482-9711-4118-ad47-e3d67504db38","ELEMENT":"97d05482-9711-4118-ad47-e3d67504db38"}}]
05:22:27 INFO - 1500121347568 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"97d05482-9711-4118-ad47-e3d67504db38"}]
05:22:27 INFO - 1500121347807 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:22:27 INFO - 1500121347808 Marionette TRACE 370 <- [1,4,null,{}]
05:22:27 INFO - 1500121347809 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"97d05482-9711-4118-ad47-e3d67504db38","name":"selected"}]
05:22:27 INFO - 1500121347813 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:22:27 INFO - 1500121347814 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:22:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500121347817 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:22:27 INFO - 1500121347818 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:22:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 351ms
05:22:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:22:27 INFO - 1500121347819 Marionette TRACE 370 <- [1,7,null,{}]
05:22:27 INFO - 1500121347821 Marionette DEBUG Closed connection 370
05:22:27 INFO - 1500121347822 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50361
05:22:27 INFO - 1500121347823 Marionette DEBUG Closed connection 371
05:22:27 INFO - 1500121347824 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50362
05:22:27 INFO - 1500121347825 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347825 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347827 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347830 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ded1b4fa-ce7a-47fe-922c-b829ba522782","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347839 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:22:27 INFO - 1500121347840 Marionette TRACE 372 <- [1,2,null,{}]
05:22:27 INFO - 1500121347840 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:27 INFO - 1500121347841 Marionette TRACE 372 <- [1,3,null,{}]
05:22:27 INFO - 1500121347842 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:27 INFO - 1500121347842 Marionette TRACE 372 <- [1,4,null,{}]
05:22:27 INFO - 1500121347844 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:22:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500121347847 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:22:27 INFO - 1500121347848 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:22:27 INFO - 1500121347849 Marionette TRACE 372 <- [1,6,null,{}]
05:22:27 INFO - 1500121347851 Marionette DEBUG Closed connection 372
05:22:27 INFO - 1500121347851 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50363
05:22:27 INFO - 1500121347852 Marionette DEBUG Closed connection 373
05:22:27 INFO - 1500121347853 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50364
05:22:27 INFO - 1500121347854 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:27 INFO - 1500121347854 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347856 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347877 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ceb5c781-742d-4efa-bb70-64ad026c31c7","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347879 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:22:27 INFO - 1500121347880 Marionette TRACE 374 <- [1,2,null,{}]
05:22:27 INFO - 1500121347885 Marionette DEBUG Closed connection 374
05:22:27 INFO - 1500121347886 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50365
05:22:27 INFO - 1500121347890 Marionette DEBUG Closed connection 375
05:22:27 INFO - 1500121347891 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50366
05:22:27 INFO - 1500121347913 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:27 INFO - 1500121347914 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:27 INFO - 1500121347916 Marionette DEBUG Register listener.js for window 15
05:22:27 INFO - 1500121347919 Marionette TRACE 376 <- [1,1,null,{"sessionId":"38e807b7-b2c0-4a59-aa08-4cd313d84276","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:27 INFO - 1500121347921 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:22:27 INFO - 1500121347922 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347925 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347925 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:27 INFO - 1500121347936 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:22:27 INFO - 1500121347939 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:22:27 INFO - 1500121347950 Marionette TRACE 376 <- [1,2,null,{}]
05:22:27 INFO - 1500121347952 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:22:27 INFO - 1500121347954 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f77fc222-1641-46ec-bb3d-dc5bc2f30422","ELEMENT":"f77fc222-1641-46ec-bb3d-dc5bc2f30422"}}]
05:22:27 INFO - 1500121347956 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f77fc222-1641-46ec-bb3d-dc5bc2f30422"}]
05:22:28 INFO - 1500121348193 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:22:28 INFO - 1500121348194 Marionette TRACE 376 <- [1,4,null,{}]
05:22:28 INFO - 1500121348195 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:22:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500121348198 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348199 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348200 Marionette TRACE 376 <- [1,6,null,{}]
05:22:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 381ms
05:22:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:22:28 INFO - 1500121348202 Marionette DEBUG Closed connection 376
05:22:28 INFO - 1500121348203 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50367
05:22:28 INFO - 1500121348204 Marionette DEBUG Closed connection 377
05:22:28 INFO - 1500121348205 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50368
05:22:28 INFO - 1500121348206 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348206 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348208 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348211 Marionette TRACE 378 <- [1,1,null,{"sessionId":"abef1720-6ec5-4f61-a0d5-0c7e170e415a","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348213 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:22:28 INFO - 1500121348213 Marionette TRACE 378 <- [1,2,null,{}]
05:22:28 INFO - 1500121348214 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:28 INFO - 1500121348215 Marionette TRACE 378 <- [1,3,null,{}]
05:22:28 INFO - 1500121348215 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:28 INFO - 1500121348216 Marionette TRACE 378 <- [1,4,null,{}]
05:22:28 INFO - 1500121348218 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:22:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500121348221 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348222 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348223 Marionette TRACE 378 <- [1,6,null,{}]
05:22:28 INFO - 1500121348225 Marionette DEBUG Closed connection 378
05:22:28 INFO - 1500121348225 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50369
05:22:28 INFO - 1500121348226 Marionette DEBUG Closed connection 379
05:22:28 INFO - 1500121348227 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50370
05:22:28 INFO - 1500121348228 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348228 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348230 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348233 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a04671c5-d77c-477e-8889-56d4f52712f4","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348235 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:22:28 INFO - 1500121348236 Marionette TRACE 380 <- [1,2,null,{}]
05:22:28 INFO - 1500121348238 Marionette DEBUG Closed connection 380
05:22:28 INFO - 1500121348238 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50371
05:22:28 INFO - 1500121348239 Marionette DEBUG Closed connection 381
05:22:28 INFO - 1500121348240 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50372
05:22:28 INFO - 1500121348241 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:28 INFO - 1500121348241 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348243 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348246 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e3aa98f3-e06d-4219-ba0c-37929d86f365","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348248 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:22:28 INFO - 1500121348250 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:22:28 INFO - 1500121348252 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:22:28 INFO - 1500121348252 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:22:28 INFO - 1500121348269 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:28 INFO - 1500121348272 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:28 INFO - 1500121348284 Marionette TRACE 382 <- [1,2,null,{}]
05:22:28 INFO - 1500121348285 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:22:28 INFO - 1500121348288 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"966022c4-990d-4e44-a702-f738b95aff09","ELEMENT":"966022c4-990d-4e44-a702-f738b95aff09"}}]
05:22:28 INFO - 1500121348291 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"966022c4-990d-4e44-a702-f738b95aff09"}]
05:22:28 INFO - 1500121348528 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:22:28 INFO - 1500121348529 Marionette TRACE 382 <- [1,4,null,{}]
05:22:28 INFO - 1500121348531 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:22:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500121348533 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348534 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348535 Marionette TRACE 382 <- [1,6,null,{}]
05:22:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 335ms
05:22:28 INFO - TEST-START | test_click.py TestClick.test_input_file
05:22:28 INFO - 1500121348537 Marionette DEBUG Closed connection 382
05:22:28 INFO - 1500121348538 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50373
05:22:28 INFO - 1500121348539 Marionette DEBUG Closed connection 383
05:22:28 INFO - 1500121348540 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50374
05:22:28 INFO - 1500121348541 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348541 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348543 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348546 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4635b93e-a36e-4147-9fce-e1baf7cb391e","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348548 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:22:28 INFO - 1500121348548 Marionette TRACE 384 <- [1,2,null,{}]
05:22:28 INFO - 1500121348549 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:28 INFO - 1500121348549 Marionette TRACE 384 <- [1,3,null,{}]
05:22:28 INFO - 1500121348550 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:28 INFO - 1500121348551 Marionette TRACE 384 <- [1,4,null,{}]
05:22:28 INFO - 1500121348553 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:22:28 INFO - TEST-START: test_click.py TestClick.test_input_file1500121348555 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348556 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348557 Marionette TRACE 384 <- [1,6,null,{}]
05:22:28 INFO - 1500121348559 Marionette DEBUG Closed connection 384
05:22:28 INFO - 1500121348560 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50375
05:22:28 INFO - 1500121348560 Marionette DEBUG Closed connection 385
05:22:28 INFO - 1500121348561 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50376
05:22:28 INFO - 1500121348562 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348563 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348565 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348575 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e19ed982-2966-4d83-b125-ef8b324ceae9","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348577 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:22:28 INFO - 1500121348578 Marionette TRACE 386 <- [1,2,null,{}]
05:22:28 INFO - 1500121348580 Marionette DEBUG Closed connection 386
05:22:28 INFO - 1500121348581 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50377
05:22:28 INFO - 1500121348581 Marionette DEBUG Closed connection 387
05:22:28 INFO - 1500121348582 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50378
05:22:28 INFO - 1500121348583 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:28 INFO - 1500121348584 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348586 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348589 Marionette TRACE 388 <- [1,1,null,{"sessionId":"a9efee3b-433d-4d2d-9954-bec2636a3fdb","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348591 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:22:28 INFO - 1500121348592 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:28 INFO - 1500121348594 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:28 INFO - 1500121348595 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:22:28 INFO - 1500121348612 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:22:28 INFO - 1500121348613 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:22:28 INFO - 1500121348617 Marionette TRACE 388 <- [1,2,null,{}]
05:22:28 INFO - 1500121348620 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:22:28 INFO - 1500121348629 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f3a018b-a4dc-4f9d-ae5b-8974a2976e65","ELEMENT":"6f3a018b-a4dc-4f9d-ae5b-8974a2976e65"}}]
05:22:28 INFO - 1500121348630 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6f3a018b-a4dc-4f9d-ae5b-8974a2976e65"}]
05:22:28 INFO - 1500121348656 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]
05:22:28 INFO - 1500121348659 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:22:28 INFO - TEST-END: test_click.py TestClick.test_input_file1500121348662 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348663 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348664 Marionette TRACE 388 <- [1,6,null,{}]
05:22:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 128ms
05:22:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:22:28 INFO - 1500121348667 Marionette DEBUG Closed connection 388
05:22:28 INFO - 1500121348668 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50379
05:22:28 INFO - 1500121348669 Marionette DEBUG Closed connection 389
05:22:28 INFO - 1500121348669 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50380
05:22:28 INFO - 1500121348670 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348671 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348673 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348676 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c0ac16c2-15cc-4652-bbee-08deaf63453d","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348678 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:22:28 INFO - 1500121348678 Marionette TRACE 390 <- [1,2,null,{}]
05:22:28 INFO - 1500121348679 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:28 INFO - 1500121348679 Marionette TRACE 390 <- [1,3,null,{}]
05:22:28 INFO - 1500121348680 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:28 INFO - 1500121348681 Marionette TRACE 390 <- [1,4,null,{}]
05:22:28 INFO - 1500121348682 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:22:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500121348685 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:22:28 INFO - 1500121348686 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:22:28 INFO - 1500121348687 Marionette TRACE 390 <- [1,6,null,{}]
05:22:28 INFO - 1500121348689 Marionette DEBUG Closed connection 390
05:22:28 INFO - 1500121348689 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50381
05:22:28 INFO - 1500121348690 Marionette DEBUG Closed connection 391
05:22:28 INFO - 1500121348691 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50382
05:22:28 INFO - 1500121348692 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:28 INFO - 1500121348692 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348694 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348697 Marionette TRACE 392 <- [1,1,null,{"sessionId":"06af6e85-292c-4b0a-a35f-c2cf4dc8fe23","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348699 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:22:28 INFO - 1500121348700 Marionette TRACE 392 <- [1,2,null,{}]
05:22:28 INFO - 1500121348702 Marionette DEBUG Closed connection 392
05:22:28 INFO - 1500121348703 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50383
05:22:28 INFO - 1500121348704 Marionette DEBUG Closed connection 393
05:22:28 INFO - 1500121348704 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50384
05:22:28 INFO - 1500121348706 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:28 INFO - 1500121348707 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:28 INFO - 1500121348709 Marionette DEBUG Register listener.js for window 15
05:22:28 INFO - 1500121348711 Marionette TRACE 394 <- [1,1,null,{"sessionId":"bbaaa765-9cd1-45a1-ad4a-d0d4f7c1edcb","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:28 INFO - 1500121348715 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:22:28 INFO - 1500121348716 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:22:28 INFO - 1500121348718 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:22:28 INFO - 1500121348718 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:22:28 INFO - 1500121348739 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:22:28 INFO - 1500121348741 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:22:28 INFO - 1500121348744 Marionette TRACE 394 <- [1,2,null,{}]
05:22:28 INFO - 1500121348745 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:22:28 INFO - 1500121348748 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a935bf5-3ec9-4a51-8991-306e48c3c9c1","ELEMENT":"9a935bf5-3ec9-4a51-8991-306e48c3c9c1"}}]
05:22:28 INFO - 1500121348749 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:22:28 INFO - 1500121348751 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4d9bbde2-5cca-4adb-8567-fdc2193f149e","ELEMENT":"4d9bbde2-5cca-4adb-8567-fdc2193f149e"}}]
05:22:28 INFO - 1500121348751 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9a935bf5-3ec9-4a51-8991-306e48c3c9c1"}]
05:22:28 INFO - 1500121348992 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:22:28 INFO - 1500121348993 Marionette TRACE 394 <- [1,5,null,{}]
05:22:28 INFO - 1500121348994 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4d9bbde2-5cca-4adb-8567-fdc2193f149e"}]
05:22:29 INFO - 1500121349007 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]
05:22:29 INFO - 1500121349009 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:22:29 INFO - 1500121349012 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:22:29 INFO - 1500121349013 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:22:29 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500121349016 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:22:29 INFO - 1500121349017 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:22:29 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 354ms
05:22:29 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:22:29 INFO - 1500121349018 Marionette TRACE 394 <- [1,9,null,{}]
05:22:29 INFO - 1500121349020 Marionette DEBUG Closed connection 394
05:22:29 INFO - 1500121349021 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50385
05:22:29 INFO - 1500121349021 Marionette DEBUG Closed connection 395
05:22:29 INFO - 1500121349022 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50386
05:22:29 INFO - 1500121349023 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:29 INFO - 1500121349024 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:29 INFO - 1500121349026 Marionette DEBUG Register listener.js for window 15
05:22:29 INFO - 1500121349029 Marionette TRACE 396 <- [1,1,null,{"sessionId":"341a0e26-4427-44bb-a39e-803553643c79","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:29 INFO - 1500121349031 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:22:29 INFO - 1500121349031 Marionette TRACE 396 <- [1,2,null,{}]
05:22:29 INFO - 1500121349032 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:22:29 INFO - 1500121349032 Marionette TRACE 396 <- [1,3,null,{}]
05:22:29 INFO - 1500121349033 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:22:29 INFO - 1500121349034 Marionette TRACE 396 <- [1,4,null,{}]
05:22:29 INFO - 1500121349035 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:22:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500121349038 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:22:29 INFO - 1500121349039 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:22:29 INFO - 1500121349040 Marionette TRACE 396 <- [1,6,null,{}]
05:22:29 INFO - 1500121349042 Marionette DEBUG Closed connection 396
05:22:29 INFO - 1500121349043 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50387
05:22:29 INFO - 1500121349043 Marionette DEBUG Closed connection 397
05:22:29 INFO - 1500121349044 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50388
05:22:29 INFO - 1500121349045 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:22:29 INFO - 1500121349045 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:29 INFO - 1500121349047 Marionette DEBUG Register listener.js for window 15
05:22:29 INFO - 1500121349050 Marionette TRACE 398 <- [1,1,null,{"sessionId":"afbd346e-814d-4605-8114-3f4cff63b08d","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:29 INFO - 1500121349052 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:22:29 INFO - 1500121349053 Marionette TRACE 398 <- [1,2,null,{}]
05:22:29 INFO - 1500121349055 Marionette DEBUG Closed connection 398
05:22:29 INFO - 1500121349055 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50389
05:22:29 INFO - 1500121349056 Marionette DEBUG Closed connection 399
05:22:29 INFO - 1500121349057 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50390
05:22:29 INFO - 1500121349058 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:22:29 INFO - 1500121349066 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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}
05:22:29 INFO - 1500121349069 Marionette DEBUG Register listener.js for window 15
05:22:29 INFO - 1500121349071 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1b749921-cbd2-4c7f-91a5-549f5ef5ffcc","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":3288,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjltciq.mozrunner","moz:accessibilityChecks":false}}]
05:22:29 INFO - 1500121349074 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:22:29 INFO - 1500121349075 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:22:29 INFO - 1500121349077 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:22:29 INFO - 1500121349077 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:22:29 INFO - 1500121349099 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:22:29 INFO - 1500121349101 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:22:29 INFO - 1500121349104 Marionette TRACE 400 <- [1,2,null,{}]
05:22:29 INFO - 1500121349112 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:22:29 INFO - 1500121349115 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8596127c-ffae-48ae-a6de-da45ccabce1b","ELEMENT":"8596127c-ffae-48ae-a6de-da45ccabce1b"}}]
05:22:29 INFO - 1500121349120 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8596127c-ffae-48ae-a6de-da45ccabce1b"}]
05:22:29 INFO - 1500121349142 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:22:29 INFO - 1500121349143 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8596127c-ffae-48ae-a6de-da45ccabce1b"}]
05:22:29 INFO - 1500121349161 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element