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]
17:20:08 INFO - 1499818808420 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}]
17:20:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499818808423 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:20:08 INFO - 1499818808424 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:20:08 INFO - 1499818808425 Marionette TRACE 352 <- [1,6,null,{}]
17:20:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 121ms
17:20:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:20:08 INFO - 1499818808427 Marionette DEBUG Closed connection 352
17:20:08 INFO - 1499818808428 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50167
17:20:08 INFO - 1499818808429 Marionette DEBUG Closed connection 353
17:20:08 INFO - 1499818808429 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50168
17:20:08 INFO - 1499818808430 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808432 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808434 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808437 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2d0ca166-2244-4b37-a589-4606c75b0cc1","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808438 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:20:08 INFO - 1499818808439 Marionette TRACE 354 <- [1,2,null,{}]
17:20:08 INFO - 1499818808440 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:08 INFO - 1499818808440 Marionette TRACE 354 <- [1,3,null,{}]
17:20:08 INFO - 1499818808441 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:08 INFO - 1499818808442 Marionette TRACE 354 <- [1,4,null,{}]
17:20:08 INFO - 1499818808443 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}]
17:20:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499818808447 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:20:08 INFO - 1499818808448 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:20:08 INFO - 1499818808449 Marionette TRACE 354 <- [1,6,null,{}]
17:20:08 INFO - 1499818808450 Marionette DEBUG Closed connection 354
17:20:08 INFO - 1499818808451 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50169
17:20:08 INFO - 1499818808451 Marionette DEBUG Closed connection 355
17:20:08 INFO - 1499818808452 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50170
17:20:08 INFO - 1499818808453 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808454 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808456 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808458 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a694159b-730e-447f-b4d7-07202c7cb796","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808459 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:20:08 INFO - 1499818808460 Marionette TRACE 356 <- [1,2,null,{}]
17:20:08 INFO - 1499818808462 Marionette DEBUG Closed connection 356
17:20:08 INFO - 1499818808462 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50171
17:20:08 INFO - 1499818808463 Marionette DEBUG Closed connection 357
17:20:08 INFO - 1499818808464 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50172
17:20:08 INFO - 1499818808465 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:08 INFO - 1499818808465 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808467 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808478 Marionette TRACE 358 <- [1,1,null,{"sessionId":"6808925f-5f8e-485c-be58-80c5e44bd48b","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808479 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49725/clicks.html"}]
17:20:08 INFO - 1499818808481 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49725/hidden.html"
17:20:08 INFO - 1499818808489 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49725/hidden.html"
17:20:08 INFO - 1499818808489 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49725/hidden.html"
17:20:08 INFO - 1499818808496 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49725/clicks.html"
17:20:08 INFO - 1499818808500 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49725/clicks.html"
17:20:08 INFO - 1499818808503 Marionette TRACE 358 <- [1,2,null,{}]
17:20:08 INFO - 1499818808508 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:20:08 INFO - 1499818808516 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"142f1b22-3af8-4b8e-afc5-f44a27fbb3db","ELEMENT":"142f1b22-3af8-4b8e-afc5-f44a27fbb3db"}}]
17:20:08 INFO - 1499818808517 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"142f1b22-3af8-4b8e-afc5-f44a27fbb3db"}]
17:20:08 INFO - 1499818808555 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49725/clicks.html"
17:20:08 INFO - 1499818808566 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49725/clicks.html"
17:20:08 INFO - 1499818808566 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49725/clicks.html"
17:20:08 INFO - 1499818808573 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49725/test.html"
17:20:08 INFO - 1499818808575 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49725/test.html"
17:20:08 INFO - 1499818808579 Marionette TRACE 358 <- [1,4,null,{}]
17:20:08 INFO - 1499818808584 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:20:08 INFO - 1499818808587 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a0634fe0-5030-439b-b6cb-7b735a2b91da","ELEMENT":"a0634fe0-5030-439b-b6cb-7b735a2b91da"}}]
17:20:08 INFO - 1499818808588 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:20:08 INFO - 1499818808589 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:20:08 INFO - 1499818808590 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}]
17:20:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 173ms
17:20:08 INFO - TEST-START | test_click.py TestClick.test_container_element
17:20:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499818808596 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:20:08 INFO - 1499818808597 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:20:08 INFO - 1499818808598 Marionette TRACE 358 <- [1,8,null,{}]
17:20:08 INFO - 1499818808600 Marionette DEBUG Closed connection 358
17:20:08 INFO - 1499818808618 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50175
17:20:08 INFO - 1499818808619 Marionette DEBUG Closed connection 359
17:20:08 INFO - 1499818808620 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50176
17:20:08 INFO - 1499818808621 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808621 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808624 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808627 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0a0b59f3-4cf8-4228-a241-6e57f7ffb806","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808628 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:20:08 INFO - 1499818808629 Marionette TRACE 360 <- [1,2,null,{}]
17:20:08 INFO - 1499818808631 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:08 INFO - 1499818808631 Marionette TRACE 360 <- [1,3,null,{}]
17:20:08 INFO - 1499818808632 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:08 INFO - 1499818808632 Marionette TRACE 360 <- [1,4,null,{}]
17:20:08 INFO - 1499818808634 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}]
17:20:08 INFO - TEST-START: test_click.py TestClick.test_container_element1499818808637 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:20:08 INFO - 1499818808638 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:20:08 INFO - 1499818808639 Marionette TRACE 360 <- [1,6,null,{}]
17:20:08 INFO - 1499818808640 Marionette DEBUG Closed connection 360
17:20:08 INFO - 1499818808641 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50177
17:20:08 INFO - 1499818808641 Marionette DEBUG Closed connection 361
17:20:08 INFO - 1499818808642 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50178
17:20:08 INFO - 1499818808643 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808643 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808645 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808648 Marionette TRACE 362 <- [1,1,null,{"sessionId":"87c62892-d951-4d41-a2e1-baa2e6bfeab8","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808649 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:20:08 INFO - 1499818808650 Marionette TRACE 362 <- [1,2,null,{}]
17:20:08 INFO - 1499818808652 Marionette DEBUG Closed connection 362
17:20:08 INFO - 1499818808652 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50179
17:20:08 INFO - 1499818808653 Marionette DEBUG Closed connection 363
17:20:08 INFO - 1499818808654 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50180
17:20:08 INFO - 1499818808655 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:08 INFO - 1499818808655 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808657 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808660 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f823e09f-090a-42e2-ad23-fe092a88bd8a","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808661 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"}]
17:20:08 INFO - 1499818808663 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49725/test.html"
17:20:08 INFO - 1499818808664 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49725/test.html"
17:20:08 INFO - 1499818808665 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49725/test.html"
17:20:08 INFO - 1499818808670 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"
17:20:08 INFO - 1499818808673 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"
17:20:08 INFO - 1499818808682 Marionette TRACE 364 <- [1,2,null,{}]
17:20:08 INFO - 1499818808685 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:20:08 INFO - 1499818808690 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59d8910c-8f07-4825-900d-d20ea914ab3a","ELEMENT":"59d8910c-8f07-4825-900d-d20ea914ab3a"}}]
17:20:08 INFO - 1499818808691 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"59d8910c-8f07-4825-900d-d20ea914ab3a"}]
17:20:08 INFO - 1499818808930 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:20:08 INFO - 1499818808931 Marionette TRACE 364 <- [1,4,null,{}]
17:20:08 INFO - 1499818808932 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"59d8910c-8f07-4825-900d-d20ea914ab3a","name":"selected"}]
17:20:08 INFO - 1499818808936 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:20:08 INFO - 1499818808938 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}]
17:20:08 INFO - TEST-END: test_click.py TestClick.test_container_element1499818808940 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:20:08 INFO - 1499818808941 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:20:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 344ms
17:20:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:20:08 INFO - 1499818808943 Marionette TRACE 364 <- [1,7,null,{}]
17:20:08 INFO - 1499818808944 Marionette DEBUG Closed connection 364
17:20:08 INFO - 1499818808945 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50181
17:20:08 INFO - 1499818808946 Marionette DEBUG Closed connection 365
17:20:08 INFO - 1499818808947 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50182
17:20:08 INFO - 1499818808948 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808948 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808950 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808953 Marionette TRACE 366 <- [1,1,null,{"sessionId":"80ce21d3-3b3f-4d48-8bd3-4e39aa116dc7","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808954 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:20:08 INFO - 1499818808955 Marionette TRACE 366 <- [1,2,null,{}]
17:20:08 INFO - 1499818808955 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:08 INFO - 1499818808956 Marionette TRACE 366 <- [1,3,null,{}]
17:20:08 INFO - 1499818808957 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:08 INFO - 1499818808957 Marionette TRACE 366 <- [1,4,null,{}]
17:20:08 INFO - 1499818808959 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}]
17:20:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499818808961 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:20:08 INFO - 1499818808962 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:20:08 INFO - 1499818808963 Marionette TRACE 366 <- [1,6,null,{}]
17:20:08 INFO - 1499818808964 Marionette DEBUG Closed connection 366
17:20:08 INFO - 1499818808965 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50183
17:20:08 INFO - 1499818808966 Marionette DEBUG Closed connection 367
17:20:08 INFO - 1499818808966 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50184
17:20:08 INFO - 1499818808967 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:08 INFO - 1499818808968 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808970 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808973 Marionette TRACE 368 <- [1,1,null,{"sessionId":"571257d9-74ff-4464-88b5-59d4325c57da","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808974 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:20:08 INFO - 1499818808975 Marionette TRACE 368 <- [1,2,null,{}]
17:20:08 INFO - 1499818808977 Marionette DEBUG Closed connection 368
17:20:08 INFO - 1499818808978 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50185
17:20:08 INFO - 1499818808979 Marionette DEBUG Closed connection 369
17:20:08 INFO - 1499818808980 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50186
17:20:08 INFO - 1499818808981 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:08 INFO - 1499818808981 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:08 INFO - 1499818808983 Marionette DEBUG Register listener.js for window 4294967297
17:20:08 INFO - 1499818808986 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e6fbc783-d52c-45bc-ab83-8364c9060d0c","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:08 INFO - 1499818808987 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"}]
17:20:08 INFO - 1499818808989 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"
17:20:08 INFO - 1499818808991 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"
17:20:08 INFO - 1499818808991 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"
17:20:09 INFO - 1499818808997 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"
17:20:09 INFO - 1499818808999 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"
17:20:09 INFO - 1499818809015 Marionette TRACE 370 <- [1,2,null,{}]
17:20:09 INFO - 1499818809023 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:20:09 INFO - 1499818809030 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a44034f4-c8e9-4eb5-a39e-f3f3f51105f2","ELEMENT":"a44034f4-c8e9-4eb5-a39e-f3f3f51105f2"}}]
17:20:09 INFO - 1499818809031 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a44034f4-c8e9-4eb5-a39e-f3f3f51105f2"}]
17:20:09 INFO - 1499818809281 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:20:09 INFO - 1499818809282 Marionette TRACE 370 <- [1,4,null,{}]
17:20:09 INFO - 1499818809283 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a44034f4-c8e9-4eb5-a39e-f3f3f51105f2","name":"selected"}]
17:20:09 INFO - 1499818809287 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:20:09 INFO - 1499818809289 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}]
17:20:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499818809291 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:20:09 INFO - 1499818809292 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:20:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 350ms
17:20:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:20:09 INFO - 1499818809294 Marionette TRACE 370 <- [1,7,null,{}]
17:20:09 INFO - 1499818809295 Marionette DEBUG Closed connection 370
17:20:09 INFO - 1499818809296 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50187
17:20:09 INFO - 1499818809297 Marionette DEBUG Closed connection 371
17:20:09 INFO - 1499818809298 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50188
17:20:09 INFO - 1499818809298 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809299 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809301 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809304 Marionette TRACE 372 <- [1,1,null,{"sessionId":"22d08527-dcbd-4652-a7a9-2e18f3ff7628","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809305 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:20:09 INFO - 1499818809305 Marionette TRACE 372 <- [1,2,null,{}]
17:20:09 INFO - 1499818809306 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:09 INFO - 1499818809307 Marionette TRACE 372 <- [1,3,null,{}]
17:20:09 INFO - 1499818809307 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:09 INFO - 1499818809308 Marionette TRACE 372 <- [1,4,null,{}]
17:20:09 INFO - 1499818809310 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}]
17:20:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499818809312 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:20:09 INFO - 1499818809313 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:20:09 INFO - 1499818809314 Marionette TRACE 372 <- [1,6,null,{}]
17:20:09 INFO - 1499818809316 Marionette DEBUG Closed connection 372
17:20:09 INFO - 1499818809317 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50189
17:20:09 INFO - 1499818809318 Marionette DEBUG Closed connection 373
17:20:09 INFO - 1499818809318 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50190
17:20:09 INFO - 1499818809319 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809320 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809322 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809324 Marionette TRACE 374 <- [1,1,null,{"sessionId":"38d5b79f-f3b2-4154-ae89-bb9066b2eb7d","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809325 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:20:09 INFO - 1499818809327 Marionette TRACE 374 <- [1,2,null,{}]
17:20:09 INFO - 1499818809328 Marionette DEBUG Closed connection 374
17:20:09 INFO - 1499818809329 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50191
17:20:09 INFO - 1499818809329 Marionette DEBUG Closed connection 375
17:20:09 INFO - 1499818809330 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50192
17:20:09 INFO - 1499818809331 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:09 INFO - 1499818809331 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809333 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809337 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f8e6fe0c-e135-4421-ad96-83559878ee77","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809338 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"}]
17:20:09 INFO - 1499818809339 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"
17:20:09 INFO - 1499818809341 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"
17:20:09 INFO - 1499818809342 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"
17:20:09 INFO - 1499818809347 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"
17:20:09 INFO - 1499818809349 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"
17:20:09 INFO - 1499818809354 Marionette TRACE 376 <- [1,2,null,{}]
17:20:09 INFO - 1499818809358 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:20:09 INFO - 1499818809365 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"597d9e9e-995b-4edd-939e-35fde0ba40d3","ELEMENT":"597d9e9e-995b-4edd-939e-35fde0ba40d3"}}]
17:20:09 INFO - 1499818809367 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"597d9e9e-995b-4edd-939e-35fde0ba40d3"}]
17:20:09 INFO - 1499818809606 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:20:09 INFO - 1499818809607 Marionette TRACE 376 <- [1,4,null,{}]
17:20:09 INFO - 1499818809609 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}]
17:20:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499818809612 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:20:09 INFO - 1499818809613 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:20:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
17:20:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:20:09 INFO - 1499818809614 Marionette TRACE 376 <- [1,6,null,{}]
17:20:09 INFO - 1499818809615 Marionette DEBUG Closed connection 376
17:20:09 INFO - 1499818809616 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50193
17:20:09 INFO - 1499818809617 Marionette DEBUG Closed connection 377
17:20:09 INFO - 1499818809618 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50194
17:20:09 INFO - 1499818809619 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809619 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809621 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809624 Marionette TRACE 378 <- [1,1,null,{"sessionId":"65ba5d84-e25c-4287-8d0b-d766fdbe11e5","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809625 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:20:09 INFO - 1499818809626 Marionette TRACE 378 <- [1,2,null,{}]
17:20:09 INFO - 1499818809626 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:09 INFO - 1499818809627 Marionette TRACE 378 <- [1,3,null,{}]
17:20:09 INFO - 1499818809628 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:09 INFO - 1499818809628 Marionette TRACE 378 <- [1,4,null,{}]
17:20:09 INFO - 1499818809630 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}]
17:20:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499818809633 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:20:09 INFO - 1499818809634 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:20:09 INFO - 1499818809635 Marionette TRACE 378 <- [1,6,null,{}]
17:20:09 INFO - 1499818809636 Marionette DEBUG Closed connection 378
17:20:09 INFO - 1499818809636 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50195
17:20:09 INFO - 1499818809637 Marionette DEBUG Closed connection 379
17:20:09 INFO - 1499818809638 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50196
17:20:09 INFO - 1499818809639 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809639 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809641 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809644 Marionette TRACE 380 <- [1,1,null,{"sessionId":"2c51ddb3-b1e0-446f-a808-e81569687dfd","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809645 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:20:09 INFO - 1499818809646 Marionette TRACE 380 <- [1,2,null,{}]
17:20:09 INFO - 1499818809647 Marionette DEBUG Closed connection 380
17:20:09 INFO - 1499818809648 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50197
17:20:09 INFO - 1499818809649 Marionette DEBUG Closed connection 381
17:20:09 INFO - 1499818809649 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50198
17:20:09 INFO - 1499818809650 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:09 INFO - 1499818809651 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809653 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809655 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e54bdd73-436f-4479-a70a-12ae867c5b7c","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809657 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"}]
17:20:09 INFO - 1499818809658 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"
17:20:09 INFO - 1499818809661 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"
17:20:09 INFO - 1499818809662 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"
17:20:09 INFO - 1499818809669 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"
17:20:09 INFO - 1499818809673 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"
17:20:09 INFO - 1499818809675 Marionette TRACE 382 <- [1,2,null,{}]
17:20:09 INFO - 1499818809683 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:20:09 INFO - 1499818809686 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c8202e13-f281-4f16-9fd3-da89332fea2f","ELEMENT":"c8202e13-f281-4f16-9fd3-da89332fea2f"}}]
17:20:09 INFO - 1499818809687 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c8202e13-f281-4f16-9fd3-da89332fea2f"}]
17:20:09 INFO - 1499818809924 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:20:09 INFO - 1499818809924 Marionette TRACE 382 <- [1,4,null,{}]
17:20:09 INFO - 1499818809926 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}]
17:20:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499818809929 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:20:09 INFO - 1499818809930 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:20:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 317ms
17:20:09 INFO - TEST-START | test_click.py TestClick.test_input_file
17:20:09 INFO - 1499818809932 Marionette TRACE 382 <- [1,6,null,{}]
17:20:09 INFO - 1499818809933 Marionette DEBUG Closed connection 382
17:20:09 INFO - 1499818809934 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50199
17:20:09 INFO - 1499818809935 Marionette DEBUG Closed connection 383
17:20:09 INFO - 1499818809935 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50200
17:20:09 INFO - 1499818809936 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809937 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809939 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809942 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8683e34a-d0aa-4df4-9d6a-99ed0d5d6af5","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809943 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:20:09 INFO - 1499818809943 Marionette TRACE 384 <- [1,2,null,{}]
17:20:09 INFO - 1499818809944 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:09 INFO - 1499818809944 Marionette TRACE 384 <- [1,3,null,{}]
17:20:09 INFO - 1499818809945 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:09 INFO - 1499818809946 Marionette TRACE 384 <- [1,4,null,{}]
17:20:09 INFO - 1499818809947 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}]
17:20:09 INFO - TEST-START: test_click.py TestClick.test_input_file1499818809950 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:20:09 INFO - 1499818809950 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:20:09 INFO - 1499818809952 Marionette TRACE 384 <- [1,6,null,{}]
17:20:09 INFO - 1499818809953 Marionette DEBUG Closed connection 384
17:20:09 INFO - 1499818809953 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50201
17:20:09 INFO - 1499818809954 Marionette DEBUG Closed connection 385
17:20:09 INFO - 1499818809955 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50202
17:20:09 INFO - 1499818809956 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:09 INFO - 1499818809956 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809958 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809961 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c9b5e0ad-584c-445e-a02a-dc644282dd4c","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809962 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:20:09 INFO - 1499818809963 Marionette TRACE 386 <- [1,2,null,{}]
17:20:09 INFO - 1499818809964 Marionette DEBUG Closed connection 386
17:20:09 INFO - 1499818809965 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50203
17:20:09 INFO - 1499818809966 Marionette DEBUG Closed connection 387
17:20:09 INFO - 1499818809966 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50204
17:20:09 INFO - 1499818809967 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:09 INFO - 1499818809968 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:09 INFO - 1499818809970 Marionette DEBUG Register listener.js for window 4294967297
17:20:09 INFO - 1499818809972 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e3c26148-8d0b-4cc6-b459-05cd65a071c9","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:09 INFO - 1499818809973 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:20:09 INFO - 1499818809975 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"
17:20:09 INFO - 1499818809977 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"
17:20:09 INFO - 1499818809977 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"
17:20:09 INFO - 1499818809983 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:20:09 INFO - 1499818809987 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:20:09 INFO - 1499818809988 Marionette TRACE 388 <- [1,2,null,{}]
17:20:09 INFO - 1499818809991 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:20:10 INFO - 1499818810004 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dae0a80e-b068-41fc-83be-883205a78e1c","ELEMENT":"dae0a80e-b068-41fc-83be-883205a78e1c"}}]
17:20:10 INFO - 1499818810005 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"dae0a80e-b068-41fc-83be-883205a78e1c"}]
17:20:10 INFO - 1499818810035 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]
17:20:10 INFO - 1499818810037 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}]
17:20:10 INFO - TEST-END: test_click.py TestClick.test_input_file1499818810040 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:20:10 INFO - 1499818810040 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:20:10 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 109ms
17:20:10 INFO - 1499818810042 Marionette TRACE 388 <- [1,6,null,{}]
17:20:10 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:20:10 INFO - 1499818810043 Marionette DEBUG Closed connection 388
17:20:10 INFO - 1499818810045 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50205
17:20:10 INFO - 1499818810046 Marionette DEBUG Closed connection 389
17:20:10 INFO - 1499818810047 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50206
17:20:10 INFO - 1499818810048 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:10 INFO - 1499818810048 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810050 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810053 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c4087724-92c6-4c85-bdcc-f229effa57ea","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810054 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:20:10 INFO - 1499818810054 Marionette TRACE 390 <- [1,2,null,{}]
17:20:10 INFO - 1499818810055 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:10 INFO - 1499818810056 Marionette TRACE 390 <- [1,3,null,{}]
17:20:10 INFO - 1499818810057 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:10 INFO - 1499818810057 Marionette TRACE 390 <- [1,4,null,{}]
17:20:10 INFO - 1499818810059 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}]
17:20:10 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499818810061 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:20:10 INFO - 1499818810062 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:20:10 INFO - 1499818810063 Marionette TRACE 390 <- [1,6,null,{}]
17:20:10 INFO - 1499818810064 Marionette DEBUG Closed connection 390
17:20:10 INFO - 1499818810065 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50207
17:20:10 INFO - 1499818810066 Marionette DEBUG Closed connection 391
17:20:10 INFO - 1499818810066 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50208
17:20:10 INFO - 1499818810067 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:10 INFO - 1499818810068 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810070 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810073 Marionette TRACE 392 <- [1,1,null,{"sessionId":"696cfe1f-0774-43d7-ae9f-6975a949e0fb","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810074 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:20:10 INFO - 1499818810075 Marionette TRACE 392 <- [1,2,null,{}]
17:20:10 INFO - 1499818810076 Marionette DEBUG Closed connection 392
17:20:10 INFO - 1499818810077 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50209
17:20:10 INFO - 1499818810077 Marionette DEBUG Closed connection 393
17:20:10 INFO - 1499818810078 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50210
17:20:10 INFO - 1499818810079 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:10 INFO - 1499818810080 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810081 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810084 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fb9f8501-36c6-4274-a89e-7f6ec1cdd955","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810085 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"}]
17:20:10 INFO - 1499818810087 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:20:10 INFO - 1499818810089 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:20:10 INFO - 1499818810089 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:20:10 INFO - 1499818810096 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"
17:20:10 INFO - 1499818810097 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"
17:20:10 INFO - 1499818810103 Marionette TRACE 394 <- [1,2,null,{}]
17:20:10 INFO - 1499818810106 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:20:10 INFO - 1499818810108 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83d30f08-db1e-4774-91d4-94ce82d72e73","ELEMENT":"83d30f08-db1e-4774-91d4-94ce82d72e73"}}]
17:20:10 INFO - 1499818810115 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:20:10 INFO - 1499818810118 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"700d2802-f74b-438f-adcf-00b3dcd19bb6","ELEMENT":"700d2802-f74b-438f-adcf-00b3dcd19bb6"}}]
17:20:10 INFO - 1499818810119 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"83d30f08-db1e-4774-91d4-94ce82d72e73"}]
17:20:10 INFO - 1499818810355 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:20:10 INFO - 1499818810355 Marionette TRACE 394 <- [1,5,null,{}]
17:20:10 INFO - 1499818810357 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"700d2802-f74b-438f-adcf-00b3dcd19bb6"}]
17:20:10 INFO - 1499818810370 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:20:10 INFO - 1499818810372 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:20:10 INFO - 1499818810375 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:20:10 INFO - 1499818810377 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}]
17:20:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499818810379 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:20:10 INFO - 1499818810380 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:20:10 INFO - 1499818810381 Marionette TRACE 394 <- [1,9,null,{}]
17:20:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
17:20:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:20:10 INFO - 1499818810383 Marionette DEBUG Closed connection 394
17:20:10 INFO - 1499818810384 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50211
17:20:10 INFO - 1499818810384 Marionette DEBUG Closed connection 395
17:20:10 INFO - 1499818810385 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50212
17:20:10 INFO - 1499818810386 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:10 INFO - 1499818810387 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810389 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810399 Marionette TRACE 396 <- [1,1,null,{"sessionId":"2573bfe6-1229-4d8a-863a-a7552f108d03","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810400 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:20:10 INFO - 1499818810401 Marionette TRACE 396 <- [1,2,null,{}]
17:20:10 INFO - 1499818810401 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:20:10 INFO - 1499818810402 Marionette TRACE 396 <- [1,3,null,{}]
17:20:10 INFO - 1499818810403 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:20:10 INFO - 1499818810403 Marionette TRACE 396 <- [1,4,null,{}]
17:20:10 INFO - 1499818810405 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}]
17:20:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499818810407 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:20:10 INFO - 1499818810408 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:20:10 INFO - 1499818810409 Marionette TRACE 396 <- [1,6,null,{}]
17:20:10 INFO - 1499818810411 Marionette DEBUG Closed connection 396
17:20:10 INFO - 1499818810412 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50213
17:20:10 INFO - 1499818810412 Marionette DEBUG Closed connection 397
17:20:10 INFO - 1499818810413 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50214
17:20:10 INFO - 1499818810414 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:20:10 INFO - 1499818810415 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810417 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810420 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e6bfb91c-4365-4f04-8963-398f4d6568a1","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810421 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:20:10 INFO - 1499818810422 Marionette TRACE 398 <- [1,2,null,{}]
17:20:10 INFO - 1499818810423 Marionette DEBUG Closed connection 398
17:20:10 INFO - 1499818810423 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50215
17:20:10 INFO - 1499818810424 Marionette DEBUG Closed connection 399
17:20:10 INFO - 1499818810425 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50216
17:20:10 INFO - 1499818810426 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:20:10 INFO - 1499818810427 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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}
17:20:10 INFO - 1499818810428 Marionette DEBUG Register listener.js for window 4294967297
17:20:10 INFO - 1499818810431 Marionette TRACE 400 <- [1,1,null,{"sessionId":"78b3b817-0c3e-4866-9c35-31c613dfc574","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":5624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dxjac.mozrunner","moz:accessibilityChecks":false}}]
17:20:10 INFO - 1499818810432 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"}]
17:20:10 INFO - 1499818810434 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"
17:20:10 INFO - 1499818810436 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"
17:20:10 INFO - 1499818810436 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"
17:20:10 INFO - 1499818810442 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"
17:20:10 INFO - 1499818810444 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"
17:20:10 INFO - 1499818810452 Marionette TRACE 400 <- [1,2,null,{}]
17:20:10 INFO - 1499818810455 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:20:10 INFO - 1499818810458 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1ef4126-7953-4ea9-afd3-eb2501c6d92b","ELEMENT":"a1ef4126-7953-4ea9-afd3-eb2501c6d92b"}}]
17:20:10 INFO - 1499818810459 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a1ef4126-7953-4ea9-afd3-eb2501c6d92b"}]
17:20:10 INFO - 1499818810480 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:20:10 INFO - 1499818810481 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a1ef4126-7953-4ea9-afd3-eb2501c6d92b"}]
17:20:10 INFO - 1499818810495 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element