could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:05:27 INFO - 1498928727141 Marionette TRACE 358 -> [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:05:27 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498928727144 Marionette TRACE 358 <- [1,5,null,{"value":null}]
10:05:27 INFO - 1498928727145 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
10:05:27 INFO - 1498928727146 Marionette TRACE 358 <- [1,6,null,{}]
10:05:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 148ms
10:05:27 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:05:27 INFO - 1498928727149 Marionette DEBUG Closed connection 358
10:05:27 INFO - 1498928727150 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49721
10:05:27 INFO - 1498928727151 Marionette DEBUG Closed connection 359
10:05:27 INFO - 1498928727151 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49722
10:05:27 INFO - 1498928727152 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727153 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727156 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727159 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e71ed857-32c3-4fb8-b87a-f3938360ec78","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727160 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:05:27 INFO - 1498928727161 Marionette TRACE 360 <- [1,2,null,{}]
10:05:27 INFO - 1498928727162 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:27 INFO - 1498928727162 Marionette TRACE 360 <- [1,3,null,{}]
10:05:27 INFO - 1498928727164 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:27 INFO - 1498928727164 Marionette TRACE 360 <- [1,4,null,{}]
10:05:27 INFO - 1498928727166 Marionette TRACE 360 -> [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:05:27 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498928727168 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:05:27 INFO - 1498928727169 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:05:27 INFO - 1498928727171 Marionette TRACE 360 <- [1,6,null,{}]
10:05:27 INFO - 1498928727173 Marionette DEBUG Closed connection 360
10:05:27 INFO - 1498928727173 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49723
10:05:27 INFO - 1498928727174 Marionette DEBUG Closed connection 361
10:05:27 INFO - 1498928727175 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49724
10:05:27 INFO - 1498928727176 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727176 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727179 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727184 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9051a861-3343-4706-aa6c-82d9b48f429c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727185 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:05:27 INFO - 1498928727186 Marionette TRACE 362 <- [1,2,null,{}]
10:05:27 INFO - 1498928727188 Marionette DEBUG Closed connection 362
10:05:27 INFO - 1498928727189 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49725
10:05:27 INFO - 1498928727190 Marionette DEBUG Closed connection 363
10:05:27 INFO - 1498928727190 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49726
10:05:27 INFO - 1498928727191 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:27 INFO - 1498928727192 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727195 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727198 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8c73a267-8f5b-4040-b295-b596ef7647b4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727199 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49325/clicks.html"}]
10:05:27 INFO - 1498928727201 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/hidden.html"
10:05:27 INFO - 1498928727207 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/hidden.html"
10:05:27 INFO - 1498928727207 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/hidden.html"
10:05:27 INFO - 1498928727225 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/clicks.html"
10:05:27 INFO - 1498928727231 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/clicks.html"
10:05:27 INFO - 1498928727241 Marionette TRACE 364 <- [1,2,null,{}]
10:05:27 INFO - 1498928727242 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:05:27 INFO - 1498928727245 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"17088030-c2d5-4f75-918f-cd68119a4999","ELEMENT":"17088030-c2d5-4f75-918f-cd68119a4999"}}]
10:05:27 INFO - 1498928727247 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"17088030-c2d5-4f75-918f-cd68119a4999"}]
10:05:27 INFO - 1498928727290 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/clicks.html"
10:05:27 INFO - 1498928727300 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/clicks.html"
10:05:27 INFO - 1498928727301 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/clicks.html"
10:05:27 INFO - 1498928727321 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/test.html"
10:05:27 INFO - 1498928727322 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/test.html"
10:05:27 INFO - 1498928727325 Marionette TRACE 364 <- [1,4,null,{}]
10:05:27 INFO - 1498928727326 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:05:27 INFO - 1498928727335 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7516d788-bf88-49bc-9b3f-c8585c0f1f2a","ELEMENT":"7516d788-bf88-49bc-9b3f-c8585c0f1f2a"}}]
10:05:27 INFO - 1498928727337 Marionette TRACE 364 -> [0,6,"getTitle",{}]
10:05:27 INFO - 1498928727338 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
10:05:27 INFO - 1498928727340 Marionette TRACE 364 -> [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:05:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498928727343 Marionette TRACE 364 <- [1,7,null,{"value":null}]
10:05:27 INFO - 1498928727344 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
10:05:27 INFO - 1498928727345 Marionette TRACE 364 <- [1,8,null,{}]
10:05:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 199ms
10:05:27 INFO - TEST-START | test_click.py TestClick.test_container_element
10:05:27 INFO - 1498928727349 Marionette DEBUG Closed connection 364
10:05:27 INFO - 1498928727349 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49729
10:05:27 INFO - 1498928727350 Marionette DEBUG Closed connection 365
10:05:27 INFO - 1498928727351 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49730
10:05:27 INFO - 1498928727353 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727354 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727357 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727360 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4b927b88-ea16-4753-89e6-81de736eabc7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727376 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:05:27 INFO - 1498928727377 Marionette TRACE 366 <- [1,2,null,{}]
10:05:27 INFO - 1498928727378 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:27 INFO - 1498928727378 Marionette TRACE 366 <- [1,3,null,{}]
10:05:27 INFO - 1498928727379 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:27 INFO - 1498928727380 Marionette TRACE 366 <- [1,4,null,{}]
10:05:27 INFO - 1498928727381 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')","sandbox":"simpletest","line":316}]
10:05:27 INFO - TEST-START: test_click.py TestClick.test_container_element1498928727384 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:05:27 INFO - 1498928727385 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:05:27 INFO - 1498928727386 Marionette TRACE 366 <- [1,6,null,{}]
10:05:27 INFO - 1498928727389 Marionette DEBUG Closed connection 366
10:05:27 INFO - 1498928727389 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49731
10:05:27 INFO - 1498928727390 Marionette DEBUG Closed connection 367
10:05:27 INFO - 1498928727391 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49732
10:05:27 INFO - 1498928727392 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727392 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727395 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727399 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d914e103-0d78-4884-9376-b72769926f8f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727400 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:05:27 INFO - 1498928727402 Marionette TRACE 368 <- [1,2,null,{}]
10:05:27 INFO - 1498928727404 Marionette DEBUG Closed connection 368
10:05:27 INFO - 1498928727404 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49733
10:05:27 INFO - 1498928727405 Marionette DEBUG Closed connection 369
10:05:27 INFO - 1498928727406 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49734
10:05:27 INFO - 1498928727407 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:27 INFO - 1498928727407 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727410 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727413 Marionette TRACE 370 <- [1,1,null,{"sessionId":"de7f94ef-6ac8-4d57-9261-7ea1651a36d3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727415 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%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:05:27 INFO - 1498928727416 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/test.html"
10:05:27 INFO - 1498928727418 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/test.html"
10:05:27 INFO - 1498928727419 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/test.html"
10:05:27 INFO - 1498928727441 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:05:27 INFO - 1498928727450 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:05:27 INFO - 1498928727459 Marionette TRACE 370 <- [1,2,null,{}]
10:05:27 INFO - 1498928727461 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:05:27 INFO - 1498928727463 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"081c4f83-5947-451b-b026-983cd8767afd","ELEMENT":"081c4f83-5947-451b-b026-983cd8767afd"}}]
10:05:27 INFO - 1498928727475 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"081c4f83-5947-451b-b026-983cd8767afd"}]
10:05:27 INFO - 1498928727723 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:27 INFO - 1498928727724 Marionette TRACE 370 <- [1,4,null,{}]
10:05:27 INFO - 1498928727725 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"081c4f83-5947-451b-b026-983cd8767afd","name":"selected"}]
10:05:27 INFO - 1498928727731 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:05:27 INFO - 1498928727733 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')","sandbox":"simpletest","line":328}]
10:05:27 INFO - TEST-END: test_click.py TestClick.test_container_element1498928727735 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:05:27 INFO - 1498928727736 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:05:27 INFO - 1498928727738 Marionette TRACE 370 <- [1,7,null,{}]
10:05:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 392ms
10:05:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:05:27 INFO - 1498928727740 Marionette DEBUG Closed connection 370
10:05:27 INFO - 1498928727741 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49735
10:05:27 INFO - 1498928727741 Marionette DEBUG Closed connection 371
10:05:27 INFO - 1498928727742 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49736
10:05:27 INFO - 1498928727743 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727744 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727746 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727750 Marionette TRACE 372 <- [1,1,null,{"sessionId":"560a702f-5f8d-490b-803b-21529f8b4612","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727751 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:05:27 INFO - 1498928727751 Marionette TRACE 372 <- [1,2,null,{}]
10:05:27 INFO - 1498928727752 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:27 INFO - 1498928727753 Marionette TRACE 372 <- [1,3,null,{}]
10:05:27 INFO - 1498928727754 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:27 INFO - 1498928727754 Marionette TRACE 372 <- [1,4,null,{}]
10:05:27 INFO - 1498928727756 Marionette TRACE 372 -> [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:05:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498928727758 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:05:27 INFO - 1498928727759 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:05:27 INFO - 1498928727760 Marionette TRACE 372 <- [1,6,null,{}]
10:05:27 INFO - 1498928727763 Marionette DEBUG Closed connection 372
10:05:27 INFO - 1498928727763 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49737
10:05:27 INFO - 1498928727764 Marionette DEBUG Closed connection 373
10:05:27 INFO - 1498928727765 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49738
10:05:27 INFO - 1498928727766 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:27 INFO - 1498928727766 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727769 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727772 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4ee3a7bf-52d4-414b-9d2f-04fbf7182de9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727773 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:05:27 INFO - 1498928727774 Marionette TRACE 374 <- [1,2,null,{}]
10:05:27 INFO - 1498928727777 Marionette DEBUG Closed connection 374
10:05:27 INFO - 1498928727777 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49739
10:05:27 INFO - 1498928727778 Marionette DEBUG Closed connection 375
10:05:27 INFO - 1498928727778 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49740
10:05:27 INFO - 1498928727780 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:27 INFO - 1498928727780 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:27 INFO - 1498928727783 Marionette DEBUG Register listener.js for window 7
10:05:27 INFO - 1498928727786 Marionette TRACE 376 <- [1,1,null,{"sessionId":"879adc67-11af-44ae-90be-a9bb07bae29d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:27 INFO - 1498928727787 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%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:05:27 INFO - 1498928727788 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:05:27 INFO - 1498928727790 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:05:27 INFO - 1498928727791 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:05:27 INFO - 1498928727808 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:05:27 INFO - 1498928727812 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:05:27 INFO - 1498928727822 Marionette TRACE 376 <- [1,2,null,{}]
10:05:27 INFO - 1498928727824 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:05:27 INFO - 1498928727827 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"413ef0bf-683e-419a-bf1c-c61e0ef32954","ELEMENT":"413ef0bf-683e-419a-bf1c-c61e0ef32954"}}]
10:05:27 INFO - 1498928727828 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"413ef0bf-683e-419a-bf1c-c61e0ef32954"}]
10:05:28 INFO - 1498928728071 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:28 INFO - 1498928728072 Marionette TRACE 376 <- [1,4,null,{}]
10:05:28 INFO - 1498928728073 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"413ef0bf-683e-419a-bf1c-c61e0ef32954","name":"selected"}]
10:05:28 INFO - 1498928728078 Marionette TRACE 376 <- [1,5,null,{"value":true}]
10:05:28 INFO - 1498928728080 Marionette TRACE 376 -> [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:05:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498928728083 Marionette TRACE 376 <- [1,6,null,{"value":null}]
10:05:28 INFO - 1498928728084 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
10:05:28 INFO - 1498928728085 Marionette TRACE 376 <- [1,7,null,{}]
10:05:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 346ms
10:05:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:05:28 INFO - 1498928728087 Marionette DEBUG Closed connection 376
10:05:28 INFO - 1498928728088 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49741
10:05:28 INFO - 1498928728089 Marionette DEBUG Closed connection 377
10:05:28 INFO - 1498928728090 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49742
10:05:28 INFO - 1498928728091 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728091 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728100 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728104 Marionette TRACE 378 <- [1,1,null,{"sessionId":"808e0ae3-c235-4288-acf7-0a919970a823","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728105 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:05:28 INFO - 1498928728106 Marionette TRACE 378 <- [1,2,null,{}]
10:05:28 INFO - 1498928728107 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:28 INFO - 1498928728107 Marionette TRACE 378 <- [1,3,null,{}]
10:05:28 INFO - 1498928728108 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:28 INFO - 1498928728109 Marionette TRACE 378 <- [1,4,null,{}]
10:05:28 INFO - 1498928728110 Marionette TRACE 378 -> [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:05:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498928728113 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728114 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728116 Marionette TRACE 378 <- [1,6,null,{}]
10:05:28 INFO - 1498928728118 Marionette DEBUG Closed connection 378
10:05:28 INFO - 1498928728119 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49743
10:05:28 INFO - 1498928728119 Marionette DEBUG Closed connection 379
10:05:28 INFO - 1498928728120 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49744
10:05:28 INFO - 1498928728121 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728122 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728125 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728129 Marionette TRACE 380 <- [1,1,null,{"sessionId":"6a511c7e-a621-48ea-be61-75c83cf71b1f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728130 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:05:28 INFO - 1498928728131 Marionette TRACE 380 <- [1,2,null,{}]
10:05:28 INFO - 1498928728134 Marionette DEBUG Closed connection 380
10:05:28 INFO - 1498928728135 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49745
10:05:28 INFO - 1498928728136 Marionette DEBUG Closed connection 381
10:05:28 INFO - 1498928728136 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49746
10:05:28 INFO - 1498928728137 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:28 INFO - 1498928728138 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728141 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728147 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b2e33454-208d-4244-b0e3-a7fdab2675ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728149 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%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:05:28 INFO - 1498928728151 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:05:28 INFO - 1498928728153 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:05:28 INFO - 1498928728154 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:05:28 INFO - 1498928728173 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:05:28 INFO - 1498928728177 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:05:28 INFO - 1498928728186 Marionette TRACE 382 <- [1,2,null,{}]
10:05:28 INFO - 1498928728188 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:05:28 INFO - 1498928728191 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b880768-0206-4883-be71-a69514668852","ELEMENT":"1b880768-0206-4883-be71-a69514668852"}}]
10:05:28 INFO - 1498928728192 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1b880768-0206-4883-be71-a69514668852"}]
10:05:28 INFO - 1498928728431 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:28 INFO - 1498928728432 Marionette TRACE 382 <- [1,4,null,{}]
10:05:28 INFO - 1498928728433 Marionette TRACE 382 -> [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:05:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498928728436 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728437 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728438 Marionette TRACE 382 <- [1,6,null,{}]
10:05:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
10:05:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:05:28 INFO - 1498928728440 Marionette DEBUG Closed connection 382
10:05:28 INFO - 1498928728441 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49747
10:05:28 INFO - 1498928728442 Marionette DEBUG Closed connection 383
10:05:28 INFO - 1498928728443 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49748
10:05:28 INFO - 1498928728444 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728444 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728447 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728450 Marionette TRACE 384 <- [1,1,null,{"sessionId":"bf3dbf3c-ca15-4c6a-910f-93a9850d5c02","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728451 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:05:28 INFO - 1498928728452 Marionette TRACE 384 <- [1,2,null,{}]
10:05:28 INFO - 1498928728453 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:28 INFO - 1498928728453 Marionette TRACE 384 <- [1,3,null,{}]
10:05:28 INFO - 1498928728454 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:28 INFO - 1498928728455 Marionette TRACE 384 <- [1,4,null,{}]
10:05:28 INFO - 1498928728456 Marionette TRACE 384 -> [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:05:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498928728458 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728459 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728460 Marionette TRACE 384 <- [1,6,null,{}]
10:05:28 INFO - 1498928728462 Marionette DEBUG Closed connection 384
10:05:28 INFO - 1498928728463 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49749
10:05:28 INFO - 1498928728464 Marionette DEBUG Closed connection 385
10:05:28 INFO - 1498928728465 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49750
10:05:28 INFO - 1498928728466 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728466 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728469 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728473 Marionette TRACE 386 <- [1,1,null,{"sessionId":"00c5abaa-ced0-4503-b646-28f38aaedd20","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728474 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:05:28 INFO - 1498928728475 Marionette TRACE 386 <- [1,2,null,{}]
10:05:28 INFO - 1498928728477 Marionette DEBUG Closed connection 386
10:05:28 INFO - 1498928728478 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49751
10:05:28 INFO - 1498928728478 Marionette DEBUG Closed connection 387
10:05:28 INFO - 1498928728479 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49752
10:05:28 INFO - 1498928728480 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:28 INFO - 1498928728481 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728483 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728486 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4dcc0cbc-71ef-4f82-b9d1-d805afdd085d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728487 Marionette TRACE 388 -> [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:05:28 INFO - 1498928728489 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:05:28 INFO - 1498928728491 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:05:28 INFO - 1498928728491 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:05:28 INFO - 1498928728509 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:05:28 INFO - 1498928728517 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:05:28 INFO - 1498928728520 Marionette TRACE 388 <- [1,2,null,{}]
10:05:28 INFO - 1498928728522 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:05:28 INFO - 1498928728524 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1bf8f524-ad5d-4174-81d3-a89c7fa4f02d","ELEMENT":"1bf8f524-ad5d-4174-81d3-a89c7fa4f02d"}}]
10:05:28 INFO - 1498928728525 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"1bf8f524-ad5d-4174-81d3-a89c7fa4f02d"}]
10:05:28 INFO - 1498928728781 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:28 INFO - 1498928728782 Marionette TRACE 388 <- [1,4,null,{}]
10:05:28 INFO - 1498928728783 Marionette TRACE 388 -> [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:05:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498928728786 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728787 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728788 Marionette TRACE 388 <- [1,6,null,{}]
10:05:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 349ms
10:05:28 INFO - TEST-START | test_click.py TestClick.test_input_file
10:05:28 INFO - 1498928728790 Marionette DEBUG Closed connection 388
10:05:28 INFO - 1498928728791 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49753
10:05:28 INFO - 1498928728792 Marionette DEBUG Closed connection 389
10:05:28 INFO - 1498928728792 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49754
10:05:28 INFO - 1498928728794 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728794 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728797 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728800 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5967a6cc-56a9-4ccc-b06b-e4508aa77830","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728801 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:05:28 INFO - 1498928728801 Marionette TRACE 390 <- [1,2,null,{}]
10:05:28 INFO - 1498928728802 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:28 INFO - 1498928728803 Marionette TRACE 390 <- [1,3,null,{}]
10:05:28 INFO - 1498928728804 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:28 INFO - 1498928728804 Marionette TRACE 390 <- [1,4,null,{}]
10:05:28 INFO - 1498928728806 Marionette TRACE 390 -> [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:05:28 INFO - TEST-START: test_click.py TestClick.test_input_file1498928728809 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728809 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728811 Marionette TRACE 390 <- [1,6,null,{}]
10:05:28 INFO - 1498928728813 Marionette DEBUG Closed connection 390
10:05:28 INFO - 1498928728813 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49755
10:05:28 INFO - 1498928728814 Marionette DEBUG Closed connection 391
10:05:28 INFO - 1498928728815 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49756
10:05:28 INFO - 1498928728816 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728817 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728819 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728822 Marionette TRACE 392 <- [1,1,null,{"sessionId":"85a0dd5b-98a1-4f27-bd69-afb51cc44494","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728824 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:05:28 INFO - 1498928728825 Marionette TRACE 392 <- [1,2,null,{}]
10:05:28 INFO - 1498928728827 Marionette DEBUG Closed connection 392
10:05:28 INFO - 1498928728827 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49757
10:05:28 INFO - 1498928728828 Marionette DEBUG Closed connection 393
10:05:28 INFO - 1498928728829 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49758
10:05:28 INFO - 1498928728830 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:28 INFO - 1498928728831 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728833 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728837 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5f74e95a-8494-47e4-be4d-9bb344c62354","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728838 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:05:28 INFO - 1498928728839 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:05:28 INFO - 1498928728841 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:05:28 INFO - 1498928728842 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:05:28 INFO - 1498928728869 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:28 INFO - 1498928728872 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:28 INFO - 1498928728875 Marionette TRACE 394 <- [1,2,null,{}]
10:05:28 INFO - 1498928728877 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:05:28 INFO - 1498928728885 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e5b83a60-bb00-479a-94b6-2b1544afe0eb","ELEMENT":"e5b83a60-bb00-479a-94b6-2b1544afe0eb"}}]
10:05:28 INFO - 1498928728887 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"e5b83a60-bb00-479a-94b6-2b1544afe0eb"}]
10:05:28 INFO - 1498928728915 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:05:28 INFO - 1498928728917 Marionette TRACE 394 -> [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:05:28 INFO - TEST-END: test_click.py TestClick.test_input_file1498928728919 Marionette TRACE 394 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728921 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728923 Marionette TRACE 394 <- [1,6,null,{}]
10:05:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
10:05:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:05:28 INFO - 1498928728925 Marionette DEBUG Closed connection 394
10:05:28 INFO - 1498928728926 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49759
10:05:28 INFO - 1498928728926 Marionette DEBUG Closed connection 395
10:05:28 INFO - 1498928728927 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49760
10:05:28 INFO - 1498928728944 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728945 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728948 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728951 Marionette TRACE 396 <- [1,1,null,{"sessionId":"03ada504-aac7-435e-a186-bc8d0afa508b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728952 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:05:28 INFO - 1498928728952 Marionette TRACE 396 <- [1,2,null,{}]
10:05:28 INFO - 1498928728953 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:28 INFO - 1498928728954 Marionette TRACE 396 <- [1,3,null,{}]
10:05:28 INFO - 1498928728955 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:28 INFO - 1498928728955 Marionette TRACE 396 <- [1,4,null,{}]
10:05:28 INFO - 1498928728957 Marionette TRACE 396 -> [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:05:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498928728959 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:05:28 INFO - 1498928728960 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:05:28 INFO - 1498928728961 Marionette TRACE 396 <- [1,6,null,{}]
10:05:28 INFO - 1498928728963 Marionette DEBUG Closed connection 396
10:05:28 INFO - 1498928728964 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49761
10:05:28 INFO - 1498928728965 Marionette DEBUG Closed connection 397
10:05:28 INFO - 1498928728965 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49762
10:05:28 INFO - 1498928728966 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:28 INFO - 1498928728967 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728970 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728973 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e8704c2e-5191-41ed-a5eb-fe8a69838f3c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728974 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:05:28 INFO - 1498928728975 Marionette TRACE 398 <- [1,2,null,{}]
10:05:28 INFO - 1498928728977 Marionette DEBUG Closed connection 398
10:05:28 INFO - 1498928728978 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49763
10:05:28 INFO - 1498928728979 Marionette DEBUG Closed connection 399
10:05:28 INFO - 1498928728979 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49764
10:05:28 INFO - 1498928728980 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:28 INFO - 1498928728981 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:28 INFO - 1498928728984 Marionette DEBUG Register listener.js for window 7
10:05:28 INFO - 1498928728994 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6a02e078-11e4-403e-ad32-e493246bdd47","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:28 INFO - 1498928728995 Marionette TRACE 400 -> [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:05:28 INFO - 1498928728997 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:29 INFO - 1498928728999 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:29 INFO - 1498928728999 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:05:29 INFO - 1498928729024 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:05:29 INFO - 1498928729026 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:05:29 INFO - 1498928729034 Marionette TRACE 400 <- [1,2,null,{}]
10:05:29 INFO - 1498928729041 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:05:29 INFO - 1498928729044 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e791037d-1007-4160-9ed7-d08da468f8b5","ELEMENT":"e791037d-1007-4160-9ed7-d08da468f8b5"}}]
10:05:29 INFO - 1498928729055 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:05:29 INFO - 1498928729058 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c383f944-0e73-4d51-8564-3b2779ac77c1","ELEMENT":"c383f944-0e73-4d51-8564-3b2779ac77c1"}}]
10:05:29 INFO - 1498928729059 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e791037d-1007-4160-9ed7-d08da468f8b5"}]
10:05:29 INFO - 1498928729298 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:05:29 INFO - 1498928729299 Marionette TRACE 400 <- [1,5,null,{}]
10:05:29 INFO - 1498928729300 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"c383f944-0e73-4d51-8564-3b2779ac77c1"}]
10:05:29 INFO - 1498928729321 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:05:29 INFO - 1498928729324 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:05:29 INFO - 1498928729329 Marionette TRACE 400 <- [1,7,null,{"value":false}]
10:05:29 INFO - 1498928729331 Marionette TRACE 400 -> [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:05:29 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498928729335 Marionette TRACE 400 <- [1,8,null,{"value":null}]
10:05:29 INFO - 1498928729336 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
10:05:29 INFO - 1498928729339 Marionette TRACE 400 <- [1,9,null,{}]
10:05:29 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 416ms
10:05:29 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:05:29 INFO - 1498928729342 Marionette DEBUG Closed connection 400
10:05:29 INFO - 1498928729344 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49765
10:05:29 INFO - 1498928729358 Marionette DEBUG Closed connection 401
10:05:29 INFO - 1498928729359 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49766
10:05:29 INFO - 1498928729361 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:29 INFO - 1498928729364 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:29 INFO - 1498928729367 Marionette DEBUG Register listener.js for window 7
10:05:29 INFO - 1498928729373 Marionette TRACE 402 <- [1,1,null,{"sessionId":"831f268e-329b-4f5a-9155-90966a62ee40","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:29 INFO - 1498928729375 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
10:05:29 INFO - 1498928729377 Marionette TRACE 402 <- [1,2,null,{}]
10:05:29 INFO - 1498928729378 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:05:29 INFO - 1498928729379 Marionette TRACE 402 <- [1,3,null,{}]
10:05:29 INFO - 1498928729380 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
10:05:29 INFO - 1498928729381 Marionette TRACE 402 <- [1,4,null,{}]
10:05:29 INFO - 1498928729383 Marionette TRACE 402 -> [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:05:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498928729387 Marionette TRACE 402 <- [1,5,null,{"value":null}]
10:05:29 INFO - 1498928729388 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
10:05:29 INFO - 1498928729390 Marionette TRACE 402 <- [1,6,null,{}]
10:05:29 INFO - 1498928729392 Marionette DEBUG Closed connection 402
10:05:29 INFO - 1498928729394 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49767
10:05:29 INFO - 1498928729395 Marionette DEBUG Closed connection 403
10:05:29 INFO - 1498928729396 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49768
10:05:29 INFO - 1498928729398 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:05:29 INFO - 1498928729401 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:29 INFO - 1498928729404 Marionette DEBUG Register listener.js for window 7
10:05:29 INFO - 1498928729409 Marionette TRACE 404 <- [1,1,null,{"sessionId":"8dd47d08-e09b-4fb9-9c24-4ba0d739b124","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:29 INFO - 1498928729412 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
10:05:29 INFO - 1498928729414 Marionette TRACE 404 <- [1,2,null,{}]
10:05:29 INFO - 1498928729416 Marionette DEBUG Closed connection 404
10:05:29 INFO - 1498928729417 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49769
10:05:29 INFO - 1498928729418 Marionette DEBUG Closed connection 405
10:05:29 INFO - 1498928729419 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49770
10:05:29 INFO - 1498928729420 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:05:29 INFO - 1498928729421 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}
10:05:29 INFO - 1498928729424 Marionette DEBUG Register listener.js for window 7
10:05:29 INFO - 1498928729429 Marionette TRACE 406 <- [1,1,null,{"sessionId":"f75f1afa-2abb-4e4d-a672-50539654d096","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2916,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-070.001\\AppData\\Local\\Temp\\tmp9snkju.mozrunner","moz:accessibilityChecks":false}}]
10:05:29 INFO - 1498928729430 Marionette TRACE 406 -> [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:05:29 INFO - 1498928729432 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:05:29 INFO - 1498928729436 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:05:29 INFO - 1498928729436 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:05:29 INFO - 1498928729459 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:05:29 INFO - 1498928729463 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:05:29 INFO - 1498928729473 Marionette TRACE 406 <- [1,2,null,{}]
10:05:29 INFO - 1498928729474 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:05:29 INFO - 1498928729488 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f278d5ec-255c-4ee6-bc68-7c53ddb71716","ELEMENT":"f278d5ec-255c-4ee6-bc68-7c53ddb71716"}}]
10:05:29 INFO - 1498928729489 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f278d5ec-255c-4ee6-bc68-7c53ddb71716"}]
10:05:29 INFO - 1498928729519 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
10:05:29 INFO - 1498928729520 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"f278d5ec-255c-4ee6-bc68-7c53ddb71716"}]
10:05:29 INFO - 1498928729541 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element