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]
10:45:04 INFO - 1500399904136 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}]
10:45:04 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500399904140 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:45:04 INFO - 1500399904141 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:45:04 INFO - 1500399904142 Marionette TRACE 352 <- [1,6,null,{}]
10:45:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 213ms
10:45:04 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:45:04 INFO - 1500399904145 Marionette DEBUG Closed connection 352
10:45:04 INFO - 1500399904146 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49852
10:45:04 INFO - 1500399904147 Marionette DEBUG Closed connection 353
10:45:04 INFO - 1500399904148 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49853
10:45:04 INFO - 1500399904149 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904149 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904152 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904156 Marionette TRACE 354 <- [1,1,null,{"sessionId":"5278634b-5caa-4c04-9d4b-e649e688b59d","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904158 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:45:04 INFO - 1500399904159 Marionette TRACE 354 <- [1,2,null,{}]
10:45:04 INFO - 1500399904160 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:04 INFO - 1500399904160 Marionette TRACE 354 <- [1,3,null,{}]
10:45:04 INFO - 1500399904161 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:04 INFO - 1500399904162 Marionette TRACE 354 <- [1,4,null,{}]
10:45:04 INFO - 1500399904164 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}]
10:45:04 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500399904167 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:45:04 INFO - 1500399904168 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:45:04 INFO - 1500399904169 Marionette TRACE 354 <- [1,6,null,{}]
10:45:04 INFO - 1500399904172 Marionette DEBUG Closed connection 354
10:45:04 INFO - 1500399904172 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49854
10:45:04 INFO - 1500399904173 Marionette DEBUG Closed connection 355
10:45:04 INFO - 1500399904174 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49855
10:45:04 INFO - 1500399904175 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904176 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904179 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904182 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9d335dab-814c-4fda-a1c4-c13e5bd86226","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904184 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:45:04 INFO - 1500399904186 Marionette TRACE 356 <- [1,2,null,{}]
10:45:04 INFO - 1500399904188 Marionette DEBUG Closed connection 356
10:45:04 INFO - 1500399904189 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49856
10:45:04 INFO - 1500399904190 Marionette DEBUG Closed connection 357
10:45:04 INFO - 1500399904190 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49857
10:45:04 INFO - 1500399904192 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:04 INFO - 1500399904192 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904195 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904198 Marionette TRACE 358 <- [1,1,null,{"sessionId":"06b0735b-95be-4e95-a674-65a70abb3259","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904200 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49413/clicks.html"}]
10:45:04 INFO - 1500399904202 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49413/hidden.html"
10:45:04 INFO - 1500399904208 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49413/hidden.html"
10:45:04 INFO - 1500399904209 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49413/hidden.html"
10:45:04 INFO - 1500399904235 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49413/clicks.html"
10:45:04 INFO - 1500399904237 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49413/clicks.html"
10:45:04 INFO - 1500399904242 Marionette TRACE 358 <- [1,2,null,{}]
10:45:04 INFO - 1500399904250 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:45:04 INFO - 1500399904253 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02728f0a-d383-4633-80ef-1095e9f1a6b5","ELEMENT":"02728f0a-d383-4633-80ef-1095e9f1a6b5"}}]
10:45:04 INFO - 1500399904255 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"02728f0a-d383-4633-80ef-1095e9f1a6b5"}]
10:45:04 INFO - 1500399904300 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49413/clicks.html"
10:45:04 INFO - 1500399904309 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49413/clicks.html"
10:45:04 INFO - 1500399904310 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49413/clicks.html"
10:45:04 INFO - 1500399904341 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49413/test.html"
10:45:04 INFO - 1500399904349 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49413/test.html"
10:45:04 INFO - 1500399904362 Marionette TRACE 358 <- [1,4,null,{}]
10:45:04 INFO - 1500399904364 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:45:04 INFO - 1500399904367 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b1d9f1a-bb26-48b2-a48d-e7113a996781","ELEMENT":"3b1d9f1a-bb26-48b2-a48d-e7113a996781"}}]
10:45:04 INFO - 1500399904368 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:45:04 INFO - 1500399904369 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:45:04 INFO - 1500399904371 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}]
10:45:04 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500399904374 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:45:04 INFO - 1500399904375 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:45:04 INFO - 1500399904376 Marionette TRACE 358 <- [1,8,null,{}]
10:45:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 237ms
10:45:04 INFO - TEST-START | test_click.py TestClick.test_container_element
10:45:04 INFO - 1500399904383 Marionette DEBUG Closed connection 358
10:45:04 INFO - 1500399904384 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49860
10:45:04 INFO - 1500399904385 Marionette DEBUG Closed connection 359
10:45:04 INFO - 1500399904386 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49861
10:45:04 INFO - 1500399904387 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904388 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904391 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904395 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c6fddaf9-0edb-4029-973e-7b75e1a611a8","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904397 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:45:04 INFO - 1500399904398 Marionette TRACE 360 <- [1,2,null,{}]
10:45:04 INFO - 1500399904399 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:04 INFO - 1500399904399 Marionette TRACE 360 <- [1,3,null,{}]
10:45:04 INFO - 1500399904400 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:04 INFO - 1500399904401 Marionette TRACE 360 <- [1,4,null,{}]
10:45:04 INFO - 1500399904403 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}]
10:45:04 INFO - TEST-START: test_click.py TestClick.test_container_element1500399904406 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:45:04 INFO - 1500399904407 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:45:04 INFO - 1500399904408 Marionette TRACE 360 <- [1,6,null,{}]
10:45:04 INFO - 1500399904411 Marionette DEBUG Closed connection 360
10:45:04 INFO - 1500399904413 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49862
10:45:04 INFO - 1500399904414 Marionette DEBUG Closed connection 361
10:45:04 INFO - 1500399904414 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49863
10:45:04 INFO - 1500399904416 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904416 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904419 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904423 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1e22c390-1bcb-4ad1-a43d-a22fc9ed9f3c","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904425 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:45:04 INFO - 1500399904426 Marionette TRACE 362 <- [1,2,null,{}]
10:45:04 INFO - 1500399904430 Marionette DEBUG Closed connection 362
10:45:04 INFO - 1500399904431 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49864
10:45:04 INFO - 1500399904432 Marionette DEBUG Closed connection 363
10:45:04 INFO - 1500399904433 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49865
10:45:04 INFO - 1500399904434 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:04 INFO - 1500399904435 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904437 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904441 Marionette TRACE 364 <- [1,1,null,{"sessionId":"896c9740-66d6-4961-abdf-55a2e218d1cc","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904445 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"}]
10:45:04 INFO - 1500399904446 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49413/test.html"
10:45:04 INFO - 1500399904449 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49413/test.html"
10:45:04 INFO - 1500399904449 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49413/test.html"
10:45:04 INFO - 1500399904474 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"
10:45:04 INFO - 1500399904480 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"
10:45:04 INFO - 1500399904491 Marionette TRACE 364 <- [1,2,null,{}]
10:45:04 INFO - 1500399904493 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:45:04 INFO - 1500399904497 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1351060a-959e-4ba3-8559-57d727926af7","ELEMENT":"1351060a-959e-4ba3-8559-57d727926af7"}}]
10:45:04 INFO - 1500399904499 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1351060a-959e-4ba3-8559-57d727926af7"}]
10:45:04 INFO - 1500399904745 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:45:04 INFO - 1500399904745 Marionette TRACE 364 <- [1,4,null,{}]
10:45:04 INFO - 1500399904747 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1351060a-959e-4ba3-8559-57d727926af7","name":"selected"}]
10:45:04 INFO - 1500399904752 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:45:04 INFO - 1500399904753 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}]
10:45:04 INFO - TEST-END: test_click.py TestClick.test_container_element1500399904756 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:45:04 INFO - 1500399904757 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:45:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 379ms
10:45:04 INFO - 1500399904759 Marionette TRACE 364 <- [1,7,null,{}]
10:45:04 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:45:04 INFO - 1500399904761 Marionette DEBUG Closed connection 364
10:45:04 INFO - 1500399904763 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49868
10:45:04 INFO - 1500399904763 Marionette DEBUG Closed connection 365
10:45:04 INFO - 1500399904764 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49869
10:45:04 INFO - 1500399904765 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904774 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904777 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904781 Marionette TRACE 366 <- [1,1,null,{"sessionId":"38d24066-82ea-4f55-8988-3faeb0475183","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904783 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:45:04 INFO - 1500399904784 Marionette TRACE 366 <- [1,2,null,{}]
10:45:04 INFO - 1500399904784 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:04 INFO - 1500399904785 Marionette TRACE 366 <- [1,3,null,{}]
10:45:04 INFO - 1500399904786 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:04 INFO - 1500399904786 Marionette TRACE 366 <- [1,4,null,{}]
10:45:04 INFO - 1500399904788 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}]
10:45:04 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500399904791 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:45:04 INFO - 1500399904792 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:45:04 INFO - 1500399904794 Marionette TRACE 366 <- [1,6,null,{}]
10:45:04 INFO - 1500399904796 Marionette DEBUG Closed connection 366
10:45:04 INFO - 1500399904797 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49870
10:45:04 INFO - 1500399904798 Marionette DEBUG Closed connection 367
10:45:04 INFO - 1500399904799 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49871
10:45:04 INFO - 1500399904800 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:04 INFO - 1500399904801 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904803 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904807 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1a4948fe-aea6-400c-ba9f-64df91ccb09a","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904809 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:45:04 INFO - 1500399904810 Marionette TRACE 368 <- [1,2,null,{}]
10:45:04 INFO - 1500399904814 Marionette DEBUG Closed connection 368
10:45:04 INFO - 1500399904815 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49872
10:45:04 INFO - 1500399904815 Marionette DEBUG Closed connection 369
10:45:04 INFO - 1500399904816 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49873
10:45:04 INFO - 1500399904817 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:04 INFO - 1500399904818 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:04 INFO - 1500399904821 Marionette DEBUG Register listener.js for window 15
10:45:04 INFO - 1500399904824 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8cb241b4-3221-481f-bdd9-9a0269cc8a26","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:04 INFO - 1500399904826 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"}]
10:45:04 INFO - 1500399904829 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"
10:45:04 INFO - 1500399904831 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"
10:45:04 INFO - 1500399904832 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"
10:45:04 INFO - 1500399904853 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"
10:45:04 INFO - 1500399904855 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"
10:45:04 INFO - 1500399904859 Marionette TRACE 370 <- [1,2,null,{}]
10:45:04 INFO - 1500399904867 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:45:04 INFO - 1500399904870 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"373d8859-9356-4be3-92f8-d2d31a696600","ELEMENT":"373d8859-9356-4be3-92f8-d2d31a696600"}}]
10:45:04 INFO - 1500399904872 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"373d8859-9356-4be3-92f8-d2d31a696600"}]
10:45:05 INFO - 1500399905119 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:45:05 INFO - 1500399905120 Marionette TRACE 370 <- [1,4,null,{}]
10:45:05 INFO - 1500399905121 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"373d8859-9356-4be3-92f8-d2d31a696600","name":"selected"}]
10:45:05 INFO - 1500399905126 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:45:05 INFO - 1500399905128 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}]
10:45:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500399905131 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:45:05 INFO - 1500399905132 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:45:05 INFO - 1500399905133 Marionette TRACE 370 <- [1,7,null,{}]
10:45:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 374ms
10:45:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:45:05 INFO - 1500399905136 Marionette DEBUG Closed connection 370
10:45:05 INFO - 1500399905137 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49874
10:45:05 INFO - 1500399905138 Marionette DEBUG Closed connection 371
10:45:05 INFO - 1500399905138 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49875
10:45:05 INFO - 1500399905140 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:05 INFO - 1500399905140 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905143 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905147 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3089a5e6-4578-4306-a2af-4b717e9f6440","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905149 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:45:05 INFO - 1500399905149 Marionette TRACE 372 <- [1,2,null,{}]
10:45:05 INFO - 1500399905150 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:05 INFO - 1500399905151 Marionette TRACE 372 <- [1,3,null,{}]
10:45:05 INFO - 1500399905152 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:05 INFO - 1500399905152 Marionette TRACE 372 <- [1,4,null,{}]
10:45:05 INFO - 1500399905154 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}]
10:45:05 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500399905157 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:45:05 INFO - 1500399905158 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:45:05 INFO - 1500399905159 Marionette TRACE 372 <- [1,6,null,{}]
10:45:05 INFO - 1500399905162 Marionette DEBUG Closed connection 372
10:45:05 INFO - 1500399905162 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49876
10:45:05 INFO - 1500399905163 Marionette DEBUG Closed connection 373
10:45:05 INFO - 1500399905164 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49877
10:45:05 INFO - 1500399905165 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:05 INFO - 1500399905166 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905168 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905172 Marionette TRACE 374 <- [1,1,null,{"sessionId":"7037d03a-d0b9-4d9f-8e59-8b12bf6d66c1","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905174 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:45:05 INFO - 1500399905176 Marionette TRACE 374 <- [1,2,null,{}]
10:45:05 INFO - 1500399905178 Marionette DEBUG Closed connection 374
10:45:05 INFO - 1500399905179 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49878
10:45:05 INFO - 1500399905180 Marionette DEBUG Closed connection 375
10:45:05 INFO - 1500399905180 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49879
10:45:05 INFO - 1500399905181 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:05 INFO - 1500399905182 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905185 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905188 Marionette TRACE 376 <- [1,1,null,{"sessionId":"83d815bc-aaa0-4790-92ae-db729debccb2","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905191 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"}]
10:45:05 INFO - 1500399905192 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"
10:45:05 INFO - 1500399905195 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"
10:45:05 INFO - 1500399905195 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"
10:45:05 INFO - 1500399905209 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"
10:45:05 INFO - 1500399905213 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"
10:45:05 INFO - 1500399905227 Marionette TRACE 376 <- [1,2,null,{}]
10:45:05 INFO - 1500399905230 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:45:05 INFO - 1500399905232 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fd8a635c-eafc-4455-8508-38e28d1cf763","ELEMENT":"fd8a635c-eafc-4455-8508-38e28d1cf763"}}]
10:45:05 INFO - 1500399905234 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"fd8a635c-eafc-4455-8508-38e28d1cf763"}]
10:45:05 INFO - 1500399905477 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:45:05 INFO - 1500399905478 Marionette TRACE 376 <- [1,4,null,{}]
10:45:05 INFO - 1500399905481 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}]
10:45:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500399905485 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:45:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 408ms
10:45:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:45:05 INFO - 1500399905537 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:45:05 INFO - 1500399905541 Marionette TRACE 376 <- [1,6,null,{}]
10:45:05 INFO - 1500399905545 Marionette DEBUG Closed connection 376
10:45:05 INFO - 1500399905548 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49880
10:45:05 INFO - 1500399905549 Marionette DEBUG Closed connection 377
10:45:05 INFO - 1500399905550 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49881
10:45:05 INFO - 1500399905552 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:05 INFO - 1500399905556 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905559 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905566 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f4c7e10d-56ae-413a-9925-f17f2e1b96ce","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905572 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:45:05 INFO - 1500399905573 Marionette TRACE 378 <- [1,2,null,{}]
10:45:05 INFO - 1500399905575 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:05 INFO - 1500399905576 Marionette TRACE 378 <- [1,3,null,{}]
10:45:05 INFO - 1500399905578 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:05 INFO - 1500399905579 Marionette TRACE 378 <- [1,4,null,{}]
10:45:05 INFO - 1500399905581 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}]
10:45:05 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500399905586 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:45:05 INFO - 1500399905587 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:45:05 INFO - 1500399905590 Marionette TRACE 378 <- [1,6,null,{}]
10:45:05 INFO - 1500399905594 Marionette DEBUG Closed connection 378
10:45:05 INFO - 1500399905595 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49882
10:45:05 INFO - 1500399905596 Marionette DEBUG Closed connection 379
10:45:05 INFO - 1500399905597 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49883
10:45:05 INFO - 1500399905599 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:05 INFO - 1500399905600 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905603 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905608 Marionette TRACE 380 <- [1,1,null,{"sessionId":"fd273554-9a5c-4c8c-ae6b-5a3798a36838","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905611 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:45:05 INFO - 1500399905613 Marionette TRACE 380 <- [1,2,null,{}]
10:45:05 INFO - 1500399905616 Marionette DEBUG Closed connection 380
10:45:05 INFO - 1500399905617 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49884
10:45:05 INFO - 1500399905618 Marionette DEBUG Closed connection 381
10:45:05 INFO - 1500399905618 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49885
10:45:05 INFO - 1500399905620 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:05 INFO - 1500399905621 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:05 INFO - 1500399905624 Marionette DEBUG Register listener.js for window 15
10:45:05 INFO - 1500399905629 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ce00c794-e56f-4d9a-ade5-cbd2fe6205c6","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:05 INFO - 1500399905633 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"}]
10:45:05 INFO - 1500399905637 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"
10:45:05 INFO - 1500399905641 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"
10:45:05 INFO - 1500399905641 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"
10:45:05 INFO - 1500399905679 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"
10:45:05 INFO - 1500399905693 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"
10:45:05 INFO - 1500399905705 Marionette TRACE 382 <- [1,2,null,{}]
10:45:05 INFO - 1500399905707 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:45:05 INFO - 1500399905711 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"18bd4b35-080e-45f9-81b7-e2bf73d94d8a","ELEMENT":"18bd4b35-080e-45f9-81b7-e2bf73d94d8a"}}]
10:45:05 INFO - 1500399905713 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"18bd4b35-080e-45f9-81b7-e2bf73d94d8a"}]
10:45:05 INFO - 1500399905965 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:45:05 INFO - 1500399905966 Marionette TRACE 382 <- [1,4,null,{}]
10:45:05 INFO - 1500399905968 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}]
10:45:05 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500399905973 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:45:05 INFO - 1500399905989 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:45:05 INFO - 1500399905991 Marionette TRACE 382 <- [1,6,null,{}]
10:45:05 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 449ms
10:45:05 INFO - TEST-START | test_click.py TestClick.test_input_file
10:45:05 INFO - 1500399905993 Marionette DEBUG Closed connection 382
10:45:05 INFO - 1500399905994 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49886
10:45:05 INFO - 1500399905996 Marionette DEBUG Closed connection 383
10:45:05 INFO - 1500399905998 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49887
10:45:06 INFO - 1500399906004 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906005 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906008 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906013 Marionette TRACE 384 <- [1,1,null,{"sessionId":"506121ea-37e6-45c4-8a93-37c8a1d5c887","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906016 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:45:06 INFO - 1500399906017 Marionette TRACE 384 <- [1,2,null,{}]
10:45:06 INFO - 1500399906018 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:06 INFO - 1500399906019 Marionette TRACE 384 <- [1,3,null,{}]
10:45:06 INFO - 1500399906020 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:06 INFO - 1500399906020 Marionette TRACE 384 <- [1,4,null,{}]
10:45:06 INFO - 1500399906022 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}]
10:45:06 INFO - TEST-START: test_click.py TestClick.test_input_file1500399906026 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:45:06 INFO - 1500399906027 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:45:06 INFO - 1500399906029 Marionette TRACE 384 <- [1,6,null,{}]
10:45:06 INFO - 1500399906031 Marionette DEBUG Closed connection 384
10:45:06 INFO - 1500399906032 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49888
10:45:06 INFO - 1500399906033 Marionette DEBUG Closed connection 385
10:45:06 INFO - 1500399906034 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49889
10:45:06 INFO - 1500399906035 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906036 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906038 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906044 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7fa7106a-675f-47a0-a2b9-80b70b95f381","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906063 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:45:06 INFO - 1500399906065 Marionette TRACE 386 <- [1,2,null,{}]
10:45:06 INFO - 1500399906071 Marionette DEBUG Closed connection 386
10:45:06 INFO - 1500399906072 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49890
10:45:06 INFO - 1500399906074 Marionette DEBUG Closed connection 387
10:45:06 INFO - 1500399906075 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49891
10:45:06 INFO - 1500399906076 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:06 INFO - 1500399906077 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906079 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906085 Marionette TRACE 388 <- [1,1,null,{"sessionId":"85c853f3-a5b9-4bcc-826a-f8fa9803f35a","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906119 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:45:06 INFO - 1500399906121 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"
10:45:06 INFO - 1500399906124 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"
10:45:06 INFO - 1500399906125 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"
10:45:06 INFO - 1500399906151 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:45:06 INFO - 1500399906155 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:45:06 INFO - 1500399906160 Marionette TRACE 388 <- [1,2,null,{}]
10:45:06 INFO - 1500399906171 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:45:06 INFO - 1500399906175 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"19308466-9481-4107-a0c5-062967a6c01e","ELEMENT":"19308466-9481-4107-a0c5-062967a6c01e"}}]
10:45:06 INFO - 1500399906176 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"19308466-9481-4107-a0c5-062967a6c01e"}]
10:45:06 INFO - 1500399906208 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]
10:45:06 INFO - 1500399906210 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}]
10:45:06 INFO - TEST-END: test_click.py TestClick.test_input_file1500399906213 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:45:06 INFO - 1500399906214 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:45:06 INFO - 1500399906216 Marionette TRACE 388 <- [1,6,null,{}]
10:45:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 225ms
10:45:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:45:06 INFO - 1500399906219 Marionette DEBUG Closed connection 388
10:45:06 INFO - 1500399906220 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49892
10:45:06 INFO - 1500399906221 Marionette DEBUG Closed connection 389
10:45:06 INFO - 1500399906221 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49893
10:45:06 INFO - 1500399906222 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906223 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906226 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906230 Marionette TRACE 390 <- [1,1,null,{"sessionId":"3ff91be6-d92f-46ac-8fe4-8d84f24dc85e","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906232 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:45:06 INFO - 1500399906233 Marionette TRACE 390 <- [1,2,null,{}]
10:45:06 INFO - 1500399906234 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:06 INFO - 1500399906234 Marionette TRACE 390 <- [1,3,null,{}]
10:45:06 INFO - 1500399906235 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:06 INFO - 1500399906236 Marionette TRACE 390 <- [1,4,null,{}]
10:45:06 INFO - 1500399906238 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}]
10:45:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500399906243 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:45:06 INFO - 1500399906245 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:45:06 INFO - 1500399906247 Marionette TRACE 390 <- [1,6,null,{}]
10:45:06 INFO - 1500399906249 Marionette DEBUG Closed connection 390
10:45:06 INFO - 1500399906250 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49894
10:45:06 INFO - 1500399906251 Marionette DEBUG Closed connection 391
10:45:06 INFO - 1500399906252 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49895
10:45:06 INFO - 1500399906257 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906258 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906261 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906266 Marionette TRACE 392 <- [1,1,null,{"sessionId":"296d4a12-5bcf-4e37-9773-dee1fb68de11","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906268 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:45:06 INFO - 1500399906270 Marionette TRACE 392 <- [1,2,null,{}]
10:45:06 INFO - 1500399906272 Marionette DEBUG Closed connection 392
10:45:06 INFO - 1500399906273 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49896
10:45:06 INFO - 1500399906274 Marionette DEBUG Closed connection 393
10:45:06 INFO - 1500399906274 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49897
10:45:06 INFO - 1500399906276 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:06 INFO - 1500399906277 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906279 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906283 Marionette TRACE 394 <- [1,1,null,{"sessionId":"88214eb0-2106-4ab4-9495-00f871d590e3","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906287 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"}]
10:45:06 INFO - 1500399906289 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:45:06 INFO - 1500399906291 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:45:06 INFO - 1500399906292 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:45:06 INFO - 1500399906319 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"
10:45:06 INFO - 1500399906322 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"
10:45:06 INFO - 1500399906327 Marionette TRACE 394 <- [1,2,null,{}]
10:45:06 INFO - 1500399906336 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:45:06 INFO - 1500399906340 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa8a8c06-be03-48a6-ad2a-d282002ef747","ELEMENT":"aa8a8c06-be03-48a6-ad2a-d282002ef747"}}]
10:45:06 INFO - 1500399906341 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:45:06 INFO - 1500399906345 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cdeaef13-42c0-4e0c-bc87-6541567d0f1e","ELEMENT":"cdeaef13-42c0-4e0c-bc87-6541567d0f1e"}}]
10:45:06 INFO - 1500399906346 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"aa8a8c06-be03-48a6-ad2a-d282002ef747"}]
10:45:06 INFO - 1500399906590 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:45:06 INFO - 1500399906591 Marionette TRACE 394 <- [1,5,null,{}]
10:45:06 INFO - 1500399906594 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"cdeaef13-42c0-4e0c-bc87-6541567d0f1e"}]
10:45:06 INFO - 1500399906614 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]
10:45:06 INFO - 1500399906616 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:45:06 INFO - 1500399906620 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:45:06 INFO - 1500399906622 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}]
10:45:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500399906625 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:45:06 INFO - 1500399906626 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:45:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 411ms
10:45:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:45:06 INFO - 1500399906628 Marionette TRACE 394 <- [1,9,null,{}]
10:45:06 INFO - 1500399906631 Marionette DEBUG Closed connection 394
10:45:06 INFO - 1500399906631 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49898
10:45:06 INFO - 1500399906632 Marionette DEBUG Closed connection 395
10:45:06 INFO - 1500399906633 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49899
10:45:06 INFO - 1500399906634 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906644 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906647 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906651 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4bde77d9-a985-4354-b192-a43a043b3466","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906653 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:45:06 INFO - 1500399906654 Marionette TRACE 396 <- [1,2,null,{}]
10:45:06 INFO - 1500399906655 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:45:06 INFO - 1500399906656 Marionette TRACE 396 <- [1,3,null,{}]
10:45:06 INFO - 1500399906657 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:45:06 INFO - 1500399906657 Marionette TRACE 396 <- [1,4,null,{}]
10:45:06 INFO - 1500399906659 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}]
10:45:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500399906663 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:45:06 INFO - 1500399906664 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:45:06 INFO - 1500399906665 Marionette TRACE 396 <- [1,6,null,{}]
10:45:06 INFO - 1500399906668 Marionette DEBUG Closed connection 396
10:45:06 INFO - 1500399906669 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49900
10:45:06 INFO - 1500399906670 Marionette DEBUG Closed connection 397
10:45:06 INFO - 1500399906671 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49901
10:45:06 INFO - 1500399906672 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:45:06 INFO - 1500399906673 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906677 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906682 Marionette TRACE 398 <- [1,1,null,{"sessionId":"61bd03c4-8620-42d3-9b06-10c8024d09b6","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906684 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:45:06 INFO - 1500399906686 Marionette TRACE 398 <- [1,2,null,{}]
10:45:06 INFO - 1500399906688 Marionette DEBUG Closed connection 398
10:45:06 INFO - 1500399906689 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49902
10:45:06 INFO - 1500399906690 Marionette DEBUG Closed connection 399
10:45:06 INFO - 1500399906691 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49903
10:45:06 INFO - 1500399906692 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:45:06 INFO - 1500399906693 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}
10:45:06 INFO - 1500399906695 Marionette DEBUG Register listener.js for window 15
10:45:06 INFO - 1500399906700 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a60efb56-ba80-461d-b9cb-ae1a404d208e","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpiunc8h.mozrunner","moz:accessibilityChecks":false}}]
10:45:06 INFO - 1500399906703 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"}]
10:45:06 INFO - 1500399906705 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"
10:45:06 INFO - 1500399906707 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"
10:45:06 INFO - 1500399906708 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"
10:45:06 INFO - 1500399906732 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"
10:45:06 INFO - 1500399906735 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"
10:45:06 INFO - 1500399906739 Marionette TRACE 400 <- [1,2,null,{}]
10:45:06 INFO - 1500399906741 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:45:06 INFO - 1500399906751 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63f523fd-6ee4-4480-be3d-b32ea617bbc7","ELEMENT":"63f523fd-6ee4-4480-be3d-b32ea617bbc7"}}]
10:45:06 INFO - 1500399906753 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"63f523fd-6ee4-4480-be3d-b32ea617bbc7"}]
10:45:06 INFO - 1500399906778 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:45:06 INFO - 1500399906780 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"63f523fd-6ee4-4480-be3d-b32ea617bbc7"}]
10:45:06 INFO - 1500399906804 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element