could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:14 INFO - 1499517014400 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:30:14 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499517014402 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:30:14 INFO - 1499517014403 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:30:14 INFO - 1499517014405 Marionette TRACE 352 <- [1,6,null,{}]
05:30:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 112ms
05:30:14 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:30:14 INFO - 1499517014406 Marionette DEBUG Closed connection 352
05:30:14 INFO - 1499517014407 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
05:30:14 INFO - 1499517014408 Marionette DEBUG Closed connection 353
05:30:14 INFO - 1499517014409 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
05:30:14 INFO - 1499517014409 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014411 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014413 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014417 Marionette TRACE 354 <- [1,1,null,{"sessionId":"701ab5b8-3372-4d85-a244-d299545f663f","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014418 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:30:14 INFO - 1499517014419 Marionette TRACE 354 <- [1,2,null,{}]
05:30:14 INFO - 1499517014419 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:14 INFO - 1499517014420 Marionette TRACE 354 <- [1,3,null,{}]
05:30:14 INFO - 1499517014421 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:14 INFO - 1499517014421 Marionette TRACE 354 <- [1,4,null,{}]
05:30:14 INFO - 1499517014423 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:30:14 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499517014425 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:30:14 INFO - 1499517014426 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:30:14 INFO - 1499517014427 Marionette TRACE 354 <- [1,6,null,{}]
05:30:14 INFO - 1499517014429 Marionette DEBUG Closed connection 354
05:30:14 INFO - 1499517014429 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
05:30:14 INFO - 1499517014430 Marionette DEBUG Closed connection 355
05:30:14 INFO - 1499517014431 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
05:30:14 INFO - 1499517014432 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014441 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014450 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014453 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b405b614-ab14-4fc5-aa31-412397c3cc6a","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014454 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:30:14 INFO - 1499517014455 Marionette TRACE 356 <- [1,2,null,{}]
05:30:14 INFO - 1499517014457 Marionette DEBUG Closed connection 356
05:30:14 INFO - 1499517014458 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
05:30:14 INFO - 1499517014458 Marionette DEBUG Closed connection 357
05:30:14 INFO - 1499517014459 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
05:30:14 INFO - 1499517014460 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:14 INFO - 1499517014461 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014463 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014466 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c478dafa-9785-4148-bd7b-962093e5ce7f","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014467 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49358/clicks.html"}]
05:30:14 INFO - 1499517014469 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/hidden.html"
05:30:14 INFO - 1499517014477 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/hidden.html"
05:30:14 INFO - 1499517014477 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/hidden.html"
05:30:14 INFO - 1499517014487 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49358/clicks.html"
05:30:14 INFO - 1499517014488 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49358/clicks.html"
05:30:14 INFO - 1499517014491 Marionette TRACE 358 <- [1,2,null,{}]
05:30:14 INFO - 1499517014495 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:30:14 INFO - 1499517014500 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb91c5ad-aca6-4b26-9f5e-d98957511703","ELEMENT":"fb91c5ad-aca6-4b26-9f5e-d98957511703"}}]
05:30:14 INFO - 1499517014501 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"fb91c5ad-aca6-4b26-9f5e-d98957511703"}]
05:30:14 INFO - 1499517014538 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/clicks.html"
05:30:14 INFO - 1499517014545 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/clicks.html"
05:30:14 INFO - 1499517014546 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/clicks.html"
05:30:14 INFO - 1499517014552 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49358/test.html"
05:30:14 INFO - 1499517014556 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49358/test.html"
05:30:14 INFO - 1499517014558 Marionette TRACE 358 <- [1,4,null,{}]
05:30:14 INFO - 1499517014562 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:30:14 INFO - 1499517014567 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"742109e9-5751-4f85-a649-2d4174ec2b6e","ELEMENT":"742109e9-5751-4f85-a649-2d4174ec2b6e"}}]
05:30:14 INFO - 1499517014571 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:30:14 INFO - 1499517014572 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:30:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
05:30:14 INFO - TEST-START | test_click.py TestClick.test_container_element
05:30:14 INFO - 1499517014580 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:30:14 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499517014583 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:30:14 INFO - 1499517014584 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:30:14 INFO - 1499517014585 Marionette TRACE 358 <- [1,8,null,{}]
05:30:14 INFO - 1499517014587 Marionette DEBUG Closed connection 358
05:30:14 INFO - 1499517014588 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
05:30:14 INFO - 1499517014588 Marionette DEBUG Closed connection 359
05:30:14 INFO - 1499517014589 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
05:30:14 INFO - 1499517014590 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014591 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014593 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014596 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8c41f17d-0641-4ec5-8c61-7daadee58b96","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014597 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:30:14 INFO - 1499517014598 Marionette TRACE 360 <- [1,2,null,{}]
05:30:14 INFO - 1499517014599 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:14 INFO - 1499517014599 Marionette TRACE 360 <- [1,3,null,{}]
05:30:14 INFO - 1499517014600 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:14 INFO - 1499517014602 Marionette TRACE 360 <- [1,4,null,{}]
05:30:14 INFO - 1499517014603 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:30:14 INFO - TEST-START: test_click.py TestClick.test_container_element1499517014607 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:30:14 INFO - 1499517014608 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:30:14 INFO - 1499517014609 Marionette TRACE 360 <- [1,6,null,{}]
05:30:14 INFO - 1499517014611 Marionette DEBUG Closed connection 360
05:30:14 INFO - 1499517014611 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
05:30:14 INFO - 1499517014612 Marionette DEBUG Closed connection 361
05:30:14 INFO - 1499517014613 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
05:30:14 INFO - 1499517014614 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014614 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014616 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014619 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7ab368dc-d9aa-4489-a579-4a1eed180a4d","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014620 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:30:14 INFO - 1499517014621 Marionette TRACE 362 <- [1,2,null,{}]
05:30:14 INFO - 1499517014622 Marionette DEBUG Closed connection 362
05:30:14 INFO - 1499517014623 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
05:30:14 INFO - 1499517014624 Marionette DEBUG Closed connection 363
05:30:14 INFO - 1499517014624 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
05:30:14 INFO - 1499517014625 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:14 INFO - 1499517014626 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014628 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014630 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0232bfdd-a61b-4bf2-9cc2-3a92b9323eeb","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014632 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:30:14 INFO - 1499517014633 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49358/test.html"
05:30:14 INFO - 1499517014635 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49358/test.html"
05:30:14 INFO - 1499517014635 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49358/test.html"
05:30:14 INFO - 1499517014641 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014644 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014647 Marionette TRACE 364 <- [1,2,null,{}]
05:30:14 INFO - 1499517014655 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:30:14 INFO - 1499517014657 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"589e8d31-bba7-42ae-83e5-d1520eb53687","ELEMENT":"589e8d31-bba7-42ae-83e5-d1520eb53687"}}]
05:30:14 INFO - 1499517014658 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"589e8d31-bba7-42ae-83e5-d1520eb53687"}]
05:30:14 INFO - 1499517014897 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:14 INFO - 1499517014898 Marionette TRACE 364 <- [1,4,null,{}]
05:30:14 INFO - 1499517014899 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"589e8d31-bba7-42ae-83e5-d1520eb53687","name":"selected"}]
05:30:14 INFO - 1499517014903 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:30:14 INFO - 1499517014904 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:30:14 INFO - TEST-END: test_click.py TestClick.test_container_element1499517014907 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:30:14 INFO - 1499517014907 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:30:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
05:30:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:30:14 INFO - 1499517014909 Marionette TRACE 364 <- [1,7,null,{}]
05:30:14 INFO - 1499517014910 Marionette DEBUG Closed connection 364
05:30:14 INFO - 1499517014911 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
05:30:14 INFO - 1499517014912 Marionette DEBUG Closed connection 365
05:30:14 INFO - 1499517014913 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
05:30:14 INFO - 1499517014913 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014914 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014916 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014919 Marionette TRACE 366 <- [1,1,null,{"sessionId":"104b7aab-3419-4007-9e49-97888e6ba41c","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014920 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:30:14 INFO - 1499517014920 Marionette TRACE 366 <- [1,2,null,{}]
05:30:14 INFO - 1499517014921 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:14 INFO - 1499517014922 Marionette TRACE 366 <- [1,3,null,{}]
05:30:14 INFO - 1499517014922 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:14 INFO - 1499517014923 Marionette TRACE 366 <- [1,4,null,{}]
05:30:14 INFO - 1499517014924 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:30:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499517014927 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:30:14 INFO - 1499517014928 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:30:14 INFO - 1499517014929 Marionette TRACE 366 <- [1,6,null,{}]
05:30:14 INFO - 1499517014930 Marionette DEBUG Closed connection 366
05:30:14 INFO - 1499517014931 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
05:30:14 INFO - 1499517014931 Marionette DEBUG Closed connection 367
05:30:14 INFO - 1499517014932 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
05:30:14 INFO - 1499517014933 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:14 INFO - 1499517014934 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014935 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014938 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d611b29b-9af6-4366-9d27-76a470443bb9","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014939 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:30:14 INFO - 1499517014940 Marionette TRACE 368 <- [1,2,null,{}]
05:30:14 INFO - 1499517014942 Marionette DEBUG Closed connection 368
05:30:14 INFO - 1499517014942 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
05:30:14 INFO - 1499517014943 Marionette DEBUG Closed connection 369
05:30:14 INFO - 1499517014944 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
05:30:14 INFO - 1499517014945 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:14 INFO - 1499517014945 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:14 INFO - 1499517014947 Marionette DEBUG Register listener.js for window 4294967297
05:30:14 INFO - 1499517014950 Marionette TRACE 370 <- [1,1,null,{"sessionId":"eaa9c228-79cd-4d12-8778-05c5d2307f45","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:14 INFO - 1499517014951 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:30:14 INFO - 1499517014953 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014955 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014955 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014961 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014964 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:14 INFO - 1499517014979 Marionette TRACE 370 <- [1,2,null,{}]
05:30:15 INFO - 1499517014990 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:30:15 INFO - 1499517014993 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b49ffff-6ed4-4fbe-b1a8-12fb852d2c2d","ELEMENT":"2b49ffff-6ed4-4fbe-b1a8-12fb852d2c2d"}}]
05:30:15 INFO - 1499517014994 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"2b49ffff-6ed4-4fbe-b1a8-12fb852d2c2d"}]
05:30:15 INFO - 1499517015237 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:15 INFO - 1499517015238 Marionette TRACE 370 <- [1,4,null,{}]
05:30:15 INFO - 1499517015239 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"2b49ffff-6ed4-4fbe-b1a8-12fb852d2c2d","name":"selected"}]
05:30:15 INFO - 1499517015243 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:30:15 INFO - 1499517015245 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:30:15 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499517015247 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:30:15 INFO - 1499517015248 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:30:15 INFO - 1499517015249 Marionette TRACE 370 <- [1,7,null,{}]
05:30:15 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 340ms
05:30:15 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:30:15 INFO - 1499517015250 Marionette DEBUG Closed connection 370
05:30:15 INFO - 1499517015252 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
05:30:15 INFO - 1499517015252 Marionette DEBUG Closed connection 371
05:30:15 INFO - 1499517015253 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
05:30:15 INFO - 1499517015254 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015255 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015257 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015260 Marionette TRACE 372 <- [1,1,null,{"sessionId":"cac9d460-be64-4632-974f-b962496fd1e3","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015261 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:30:15 INFO - 1499517015261 Marionette TRACE 372 <- [1,2,null,{}]
05:30:15 INFO - 1499517015262 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:15 INFO - 1499517015262 Marionette TRACE 372 <- [1,3,null,{}]
05:30:15 INFO - 1499517015263 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:15 INFO - 1499517015264 Marionette TRACE 372 <- [1,4,null,{}]
05:30:15 INFO - 1499517015265 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:30:15 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499517015268 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015268 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:30:15 INFO - 1499517015269 Marionette TRACE 372 <- [1,6,null,{}]
05:30:15 INFO - 1499517015271 Marionette DEBUG Closed connection 372
05:30:15 INFO - 1499517015271 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
05:30:15 INFO - 1499517015272 Marionette DEBUG Closed connection 373
05:30:15 INFO - 1499517015273 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
05:30:15 INFO - 1499517015274 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015274 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015276 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015279 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0c0e799e-b8ec-4190-8dd0-cbd036fa1ebb","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015280 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:30:15 INFO - 1499517015281 Marionette TRACE 374 <- [1,2,null,{}]
05:30:15 INFO - 1499517015283 Marionette DEBUG Closed connection 374
05:30:15 INFO - 1499517015283 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
05:30:15 INFO - 1499517015284 Marionette DEBUG Closed connection 375
05:30:15 INFO - 1499517015285 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
05:30:15 INFO - 1499517015286 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:15 INFO - 1499517015286 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015288 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015291 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4b1aa083-3c73-4018-a758-37845cb9df5e","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015292 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:30:15 INFO - 1499517015294 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015296 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015297 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015309 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:30:15 INFO - 1499517015311 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:30:15 INFO - 1499517015312 Marionette TRACE 376 <- [1,2,null,{}]
05:30:15 INFO - 1499517015321 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:30:15 INFO - 1499517015324 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"53897044-f460-4002-b752-6282e7c0611e","ELEMENT":"53897044-f460-4002-b752-6282e7c0611e"}}]
05:30:15 INFO - 1499517015325 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"53897044-f460-4002-b752-6282e7c0611e"}]
05:30:15 INFO - 1499517015560 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:15 INFO - 1499517015561 Marionette TRACE 376 <- [1,4,null,{}]
05:30:15 INFO - 1499517015562 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:30:15 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499517015565 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015566 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:30:15 INFO - 1499517015567 Marionette TRACE 376 <- [1,6,null,{}]
05:30:15 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
05:30:15 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:30:15 INFO - 1499517015569 Marionette DEBUG Closed connection 376
05:30:15 INFO - 1499517015570 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
05:30:15 INFO - 1499517015570 Marionette DEBUG Closed connection 377
05:30:15 INFO - 1499517015571 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
05:30:15 INFO - 1499517015572 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015573 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015575 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015578 Marionette TRACE 378 <- [1,1,null,{"sessionId":"1259eef7-af6e-47fb-b32d-b7ee313fc838","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015579 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:30:15 INFO - 1499517015579 Marionette TRACE 378 <- [1,2,null,{}]
05:30:15 INFO - 1499517015580 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:15 INFO - 1499517015580 Marionette TRACE 378 <- [1,3,null,{}]
05:30:15 INFO - 1499517015581 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:15 INFO - 1499517015581 Marionette TRACE 378 <- [1,4,null,{}]
05:30:15 INFO - 1499517015583 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:30:15 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499517015585 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015586 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:30:15 INFO - 1499517015587 Marionette TRACE 378 <- [1,6,null,{}]
05:30:15 INFO - 1499517015589 Marionette DEBUG Closed connection 378
05:30:15 INFO - 1499517015589 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
05:30:15 INFO - 1499517015590 Marionette DEBUG Closed connection 379
05:30:15 INFO - 1499517015591 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
05:30:15 INFO - 1499517015592 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015592 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015594 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015597 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9208cff8-c157-4d04-8e6c-54730850ed58","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015599 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:30:15 INFO - 1499517015600 Marionette TRACE 380 <- [1,2,null,{}]
05:30:15 INFO - 1499517015601 Marionette DEBUG Closed connection 380
05:30:15 INFO - 1499517015602 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
05:30:15 INFO - 1499517015603 Marionette DEBUG Closed connection 381
05:30:15 INFO - 1499517015604 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
05:30:15 INFO - 1499517015604 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:15 INFO - 1499517015605 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015607 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015610 Marionette TRACE 382 <- [1,1,null,{"sessionId":"22530770-2975-4679-a8d5-7aafb551ed19","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015611 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:30:15 INFO - 1499517015613 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:30:15 INFO - 1499517015615 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:30:15 INFO - 1499517015615 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:30:15 INFO - 1499517015620 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015623 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015625 Marionette TRACE 382 <- [1,2,null,{}]
05:30:15 INFO - 1499517015627 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:30:15 INFO - 1499517015631 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f3fb4860-9a29-445f-a4fa-cac75da3eee7","ELEMENT":"f3fb4860-9a29-445f-a4fa-cac75da3eee7"}}]
05:30:15 INFO - 1499517015636 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f3fb4860-9a29-445f-a4fa-cac75da3eee7"}]
05:30:15 INFO - 1499517015875 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:15 INFO - 1499517015876 Marionette TRACE 382 <- [1,4,null,{}]
05:30:15 INFO - 1499517015877 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:30:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499517015880 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015881 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:30:15 INFO - 1499517015882 Marionette TRACE 382 <- [1,6,null,{}]
05:30:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 315ms
05:30:15 INFO - TEST-START | test_click.py TestClick.test_input_file
05:30:15 INFO - 1499517015884 Marionette DEBUG Closed connection 382
05:30:15 INFO - 1499517015885 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
05:30:15 INFO - 1499517015886 Marionette DEBUG Closed connection 383
05:30:15 INFO - 1499517015886 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
05:30:15 INFO - 1499517015887 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015888 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015890 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015893 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0fa250b2-a644-421a-8259-96ffe767485a","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015894 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:30:15 INFO - 1499517015894 Marionette TRACE 384 <- [1,2,null,{}]
05:30:15 INFO - 1499517015895 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:15 INFO - 1499517015896 Marionette TRACE 384 <- [1,3,null,{}]
05:30:15 INFO - 1499517015896 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:15 INFO - 1499517015897 Marionette TRACE 384 <- [1,4,null,{}]
05:30:15 INFO - 1499517015898 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:30:15 INFO - TEST-START: test_click.py TestClick.test_input_file1499517015901 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015902 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:30:15 INFO - 1499517015903 Marionette TRACE 384 <- [1,6,null,{}]
05:30:15 INFO - 1499517015904 Marionette DEBUG Closed connection 384
05:30:15 INFO - 1499517015905 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
05:30:15 INFO - 1499517015906 Marionette DEBUG Closed connection 385
05:30:15 INFO - 1499517015906 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
05:30:15 INFO - 1499517015907 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015908 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015910 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015912 Marionette TRACE 386 <- [1,1,null,{"sessionId":"761b9ac4-72bd-4a5a-a348-04fd91e7b544","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015913 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:30:15 INFO - 1499517015914 Marionette TRACE 386 <- [1,2,null,{}]
05:30:15 INFO - 1499517015916 Marionette DEBUG Closed connection 386
05:30:15 INFO - 1499517015917 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
05:30:15 INFO - 1499517015917 Marionette DEBUG Closed connection 387
05:30:15 INFO - 1499517015918 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
05:30:15 INFO - 1499517015919 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:15 INFO - 1499517015919 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:15 INFO - 1499517015921 Marionette DEBUG Register listener.js for window 4294967297
05:30:15 INFO - 1499517015924 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9a4d3fc4-1ab5-477c-bec4-1c7b1faf9ba7","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:15 INFO - 1499517015925 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:30:15 INFO - 1499517015927 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015929 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015930 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:30:15 INFO - 1499517015937 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:15 INFO - 1499517015943 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:15 INFO - 1499517015943 Marionette TRACE 388 <- [1,2,null,{}]
05:30:15 INFO - 1499517015946 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:30:15 INFO - 1499517015957 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c9dbf31b-a070-4bc7-8f5e-7d685d29a961","ELEMENT":"c9dbf31b-a070-4bc7-8f5e-7d685d29a961"}}]
05:30:15 INFO - 1499517015960 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c9dbf31b-a070-4bc7-8f5e-7d685d29a961"}]
05:30:15 INFO - 1499517015983 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:15 INFO - 1499517015985 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:30:15 INFO - TEST-END: test_click.py TestClick.test_input_file1499517015988 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:30:15 INFO - 1499517015988 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:30:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
05:30:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:30:15 INFO - 1499517015990 Marionette TRACE 388 <- [1,6,null,{}]
05:30:15 INFO - 1499517015991 Marionette DEBUG Closed connection 388
05:30:15 INFO - 1499517015992 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
05:30:15 INFO - 1499517015993 Marionette DEBUG Closed connection 389
05:30:15 INFO - 1499517015993 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
05:30:15 INFO - 1499517015994 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:15 INFO - 1499517015995 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517015997 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016000 Marionette TRACE 390 <- [1,1,null,{"sessionId":"551ee3be-87e5-4e56-b55a-6bd2e5b19515","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016001 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:30:16 INFO - 1499517016001 Marionette TRACE 390 <- [1,2,null,{}]
05:30:16 INFO - 1499517016002 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:16 INFO - 1499517016003 Marionette TRACE 390 <- [1,3,null,{}]
05:30:16 INFO - 1499517016003 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:16 INFO - 1499517016004 Marionette TRACE 390 <- [1,4,null,{}]
05:30:16 INFO - 1499517016005 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:30:16 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499517016008 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:30:16 INFO - 1499517016009 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:30:16 INFO - 1499517016010 Marionette TRACE 390 <- [1,6,null,{}]
05:30:16 INFO - 1499517016011 Marionette DEBUG Closed connection 390
05:30:16 INFO - 1499517016011 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
05:30:16 INFO - 1499517016012 Marionette DEBUG Closed connection 391
05:30:16 INFO - 1499517016013 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
05:30:16 INFO - 1499517016014 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:16 INFO - 1499517016014 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517016016 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016026 Marionette TRACE 392 <- [1,1,null,{"sessionId":"5820fa8e-cb14-486b-8204-e1fbae7d2189","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016027 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:30:16 INFO - 1499517016029 Marionette TRACE 392 <- [1,2,null,{}]
05:30:16 INFO - 1499517016030 Marionette DEBUG Closed connection 392
05:30:16 INFO - 1499517016031 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
05:30:16 INFO - 1499517016031 Marionette DEBUG Closed connection 393
05:30:16 INFO - 1499517016032 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
05:30:16 INFO - 1499517016033 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:16 INFO - 1499517016034 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517016036 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016039 Marionette TRACE 394 <- [1,1,null,{"sessionId":"eda4c039-3dbf-4f96-8685-4aa8ebf6cec5","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016040 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:30:16 INFO - 1499517016042 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:16 INFO - 1499517016043 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:16 INFO - 1499517016044 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:30:16 INFO - 1499517016052 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:30:16 INFO - 1499517016053 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:30:16 INFO - 1499517016058 Marionette TRACE 394 <- [1,2,null,{}]
05:30:16 INFO - 1499517016061 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:30:16 INFO - 1499517016063 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2bcc9a9a-30ca-4079-a3fb-e0b2bb9538a1","ELEMENT":"2bcc9a9a-30ca-4079-a3fb-e0b2bb9538a1"}}]
05:30:16 INFO - 1499517016068 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:30:16 INFO - 1499517016070 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59412063-3a65-456a-bca6-0b01237217b4","ELEMENT":"59412063-3a65-456a-bca6-0b01237217b4"}}]
05:30:16 INFO - 1499517016071 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2bcc9a9a-30ca-4079-a3fb-e0b2bb9538a1"}]
05:30:16 INFO - 1499517016311 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:30:16 INFO - 1499517016311 Marionette TRACE 394 <- [1,5,null,{}]
05:30:16 INFO - 1499517016312 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"59412063-3a65-456a-bca6-0b01237217b4"}]
05:30:16 INFO - 1499517016326 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:30:16 INFO - 1499517016328 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:30:16 INFO - 1499517016330 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:30:16 INFO - 1499517016332 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:30:16 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499517016334 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:30:16 INFO - 1499517016335 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:30:16 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 346ms
05:30:16 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:30:16 INFO - 1499517016336 Marionette TRACE 394 <- [1,9,null,{}]
05:30:16 INFO - 1499517016338 Marionette DEBUG Closed connection 394
05:30:16 INFO - 1499517016339 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
05:30:16 INFO - 1499517016339 Marionette DEBUG Closed connection 395
05:30:16 INFO - 1499517016340 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
05:30:16 INFO - 1499517016341 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:16 INFO - 1499517016342 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517016344 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016347 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b8ce1ec3-70af-444f-bf0c-cf59b8f794a6","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016348 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:30:16 INFO - 1499517016349 Marionette TRACE 396 <- [1,2,null,{}]
05:30:16 INFO - 1499517016350 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:30:16 INFO - 1499517016350 Marionette TRACE 396 <- [1,3,null,{}]
05:30:16 INFO - 1499517016351 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:30:16 INFO - 1499517016351 Marionette TRACE 396 <- [1,4,null,{}]
05:30:16 INFO - 1499517016353 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:30:16 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499517016355 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:30:16 INFO - 1499517016356 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:30:16 INFO - 1499517016357 Marionette TRACE 396 <- [1,6,null,{}]
05:30:16 INFO - 1499517016359 Marionette DEBUG Closed connection 396
05:30:16 INFO - 1499517016359 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
05:30:16 INFO - 1499517016360 Marionette DEBUG Closed connection 397
05:30:16 INFO - 1499517016361 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
05:30:16 INFO - 1499517016362 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:30:16 INFO - 1499517016362 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517016364 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016367 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5796dfee-a3ff-435f-9e9c-6145640f8750","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016368 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:30:16 INFO - 1499517016370 Marionette TRACE 398 <- [1,2,null,{}]
05:30:16 INFO - 1499517016371 Marionette DEBUG Closed connection 398
05:30:16 INFO - 1499517016372 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
05:30:16 INFO - 1499517016372 Marionette DEBUG Closed connection 399
05:30:16 INFO - 1499517016373 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
05:30:16 INFO - 1499517016374 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:30:16 INFO - 1499517016374 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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}
05:30:16 INFO - 1499517016376 Marionette DEBUG Register listener.js for window 4294967297
05:30:16 INFO - 1499517016379 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d3f3bc64-d604-4dab-b438-c74e31182845","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":5320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgrs6sw.mozrunner","moz:accessibilityChecks":false}}]
05:30:16 INFO - 1499517016380 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:30:16 INFO - 1499517016382 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:30:16 INFO - 1499517016385 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:30:16 INFO - 1499517016385 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:30:16 INFO - 1499517016391 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:30:16 INFO - 1499517016393 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:30:16 INFO - 1499517016395 Marionette TRACE 400 <- [1,2,null,{}]
05:30:16 INFO - 1499517016403 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:30:16 INFO - 1499517016407 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82d5222b-359e-4ef6-a801-8a08189c5d7c","ELEMENT":"82d5222b-359e-4ef6-a801-8a08189c5d7c"}}]
05:30:16 INFO - 1499517016408 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"82d5222b-359e-4ef6-a801-8a08189c5d7c"}]
05:30:16 INFO - 1499517016428 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:30:16 INFO - 1499517016429 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"82d5222b-359e-4ef6-a801-8a08189c5d7c"}]
05:30:16 INFO - 1499517016442 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element