could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:35:54 INFO - 1499132154543 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}]
18:35:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499132154545 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:35:54 INFO - 1499132154546 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:35:54 INFO - 1499132154547 Marionette TRACE 352 <- [1,6,null,{}]
18:35:54 INFO - 1499132154548 Marionette DEBUG Closed connection 352
18:35:54 INFO - 1499132154549 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49766
18:35:54 INFO - 1499132154550 Marionette DEBUG Closed connection 353
18:35:54 INFO - 1499132154550 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49767
18:35:54 INFO - 1499132154551 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:54 INFO - 1499132154552 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154554 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154556 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ece21d90-4b6b-4560-a12a-1aa3872d7e0f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154558 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:35:54 INFO - 1499132154558 Marionette TRACE 354 <- [1,2,null,{}]
18:35:54 INFO - 1499132154559 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:54 INFO - 1499132154559 Marionette TRACE 354 <- [1,3,null,{}]
18:35:54 INFO - 1499132154560 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:54 INFO - 1499132154560 Marionette TRACE 354 <- [1,4,null,{}]
18:35:54 INFO - 1499132154562 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}]
18:35:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499132154564 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:35:54 INFO - 1499132154565 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:35:54 INFO - 1499132154566 Marionette TRACE 354 <- [1,6,null,{}]
18:35:54 INFO - 1499132154568 Marionette DEBUG Closed connection 354
18:35:54 INFO - 1499132154568 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49768
18:35:54 INFO - 1499132154569 Marionette DEBUG Closed connection 355
18:35:54 INFO - 1499132154569 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49769
18:35:54 INFO - 1499132154570 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:54 INFO - 1499132154571 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154573 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154576 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e69e75f4-0444-44c2-aebc-74b5cbfada14","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154577 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:35:54 INFO - 1499132154578 Marionette TRACE 356 <- [1,2,null,{}]
18:35:54 INFO - 1499132154579 Marionette DEBUG Closed connection 356
18:35:54 INFO - 1499132154580 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49770
18:35:54 INFO - 1499132154581 Marionette DEBUG Closed connection 357
18:35:54 INFO - 1499132154581 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49771
18:35:54 INFO - 1499132154582 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:54 INFO - 1499132154583 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154585 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154595 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1518ad32-49d9-4837-8c5b-3fb738b1f63d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154596 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49328/clicks.html"}]
18:35:54 INFO - 1499132154598 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/hidden.html"
18:35:54 INFO - 1499132154604 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/hidden.html"
18:35:54 INFO - 1499132154605 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/hidden.html"
18:35:54 INFO - 1499132154610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/clicks.html"
18:35:54 INFO - 1499132154616 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/clicks.html"
18:35:54 INFO - 1499132154619 Marionette TRACE 358 <- [1,2,null,{}]
18:35:54 INFO - 1499132154622 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:35:54 INFO - 1499132154624 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db4cffef-bf4f-410d-96c7-2680506b97bd","ELEMENT":"db4cffef-bf4f-410d-96c7-2680506b97bd"}}]
18:35:54 INFO - 1499132154625 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"db4cffef-bf4f-410d-96c7-2680506b97bd"}]
18:35:54 INFO - 1499132154662 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/clicks.html"
18:35:54 INFO - 1499132154669 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/clicks.html"
18:35:54 INFO - 1499132154669 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/clicks.html"
18:35:54 INFO - 1499132154677 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/test.html"
18:35:54 INFO - 1499132154681 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/test.html"
18:35:54 INFO - 1499132154685 Marionette TRACE 358 <- [1,4,null,{}]
18:35:54 INFO - 1499132154688 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:35:54 INFO - 1499132154691 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c88ee964-e218-4bc8-9dee-ee716f8eb1c8","ELEMENT":"c88ee964-e218-4bc8-9dee-ee716f8eb1c8"}}]
18:35:54 INFO - 1499132154691 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:35:54 INFO - 1499132154693 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:35:54 INFO - 1499132154695 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}]
18:35:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499132154697 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:35:54 INFO - 1499132154702 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:35:54 INFO - 1499132154703 Marionette TRACE 358 <- [1,8,null,{}]
18:35:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 156ms
18:35:54 INFO - TEST-START | test_click.py TestClick.test_container_element
18:35:54 INFO - 1499132154704 Marionette DEBUG Closed connection 358
18:35:54 INFO - 1499132154705 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49774
18:35:54 INFO - 1499132154706 Marionette DEBUG Closed connection 359
18:35:54 INFO - 1499132154706 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49775
18:35:54 INFO - 1499132154707 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:54 INFO - 1499132154708 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154710 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154713 Marionette TRACE 360 <- [1,1,null,{"sessionId":"dd21175c-88dd-4139-9b55-d5888b3c8862","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154714 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:35:54 INFO - 1499132154715 Marionette TRACE 360 <- [1,2,null,{}]
18:35:54 INFO - 1499132154716 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:54 INFO - 1499132154716 Marionette TRACE 360 <- [1,3,null,{}]
18:35:54 INFO - 1499132154717 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:54 INFO - 1499132154717 Marionette TRACE 360 <- [1,4,null,{}]
18:35:54 INFO - 1499132154719 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}]
18:35:54 INFO - TEST-START: test_click.py TestClick.test_container_element1499132154721 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:35:54 INFO - 1499132154722 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:35:54 INFO - 1499132154723 Marionette TRACE 360 <- [1,6,null,{}]
18:35:54 INFO - 1499132154725 Marionette DEBUG Closed connection 360
18:35:54 INFO - 1499132154725 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49776
18:35:54 INFO - 1499132154726 Marionette DEBUG Closed connection 361
18:35:54 INFO - 1499132154727 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49777
18:35:54 INFO - 1499132154727 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:54 INFO - 1499132154728 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154730 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154733 Marionette TRACE 362 <- [1,1,null,{"sessionId":"21b2b6ce-f7be-47eb-a7ad-f3519c18edd7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154734 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:35:54 INFO - 1499132154735 Marionette TRACE 362 <- [1,2,null,{}]
18:35:54 INFO - 1499132154736 Marionette DEBUG Closed connection 362
18:35:54 INFO - 1499132154737 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49778
18:35:54 INFO - 1499132154737 Marionette DEBUG Closed connection 363
18:35:54 INFO - 1499132154738 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49779
18:35:54 INFO - 1499132154739 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:54 INFO - 1499132154739 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:54 INFO - 1499132154741 Marionette DEBUG Register listener.js for window 4294967297
18:35:54 INFO - 1499132154744 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8b638eb4-d20e-4e25-96e0-d28e484afaca","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:54 INFO - 1499132154745 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"}]
18:35:54 INFO - 1499132154746 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/test.html"
18:35:54 INFO - 1499132154748 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/test.html"
18:35:54 INFO - 1499132154749 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/test.html"
18:35:54 INFO - 1499132154754 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"
18:35:54 INFO - 1499132154757 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"
18:35:54 INFO - 1499132154762 Marionette TRACE 364 <- [1,2,null,{}]
18:35:54 INFO - 1499132154769 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:35:54 INFO - 1499132154772 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0772b24e-f323-4d12-a0c7-2d22cc7102f3","ELEMENT":"0772b24e-f323-4d12-a0c7-2d22cc7102f3"}}]
18:35:54 INFO - 1499132154772 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0772b24e-f323-4d12-a0c7-2d22cc7102f3"}]
18:35:55 INFO - 1499132155014 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:35:55 INFO - 1499132155015 Marionette TRACE 364 <- [1,4,null,{}]
18:35:55 INFO - 1499132155016 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0772b24e-f323-4d12-a0c7-2d22cc7102f3","name":"selected"}]
18:35:55 INFO - 1499132155021 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:35:55 INFO - 1499132155022 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}]
18:35:55 INFO - TEST-END: test_click.py TestClick.test_container_element1499132155025 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:35:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
18:35:55 INFO - 1499132155025 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:35:55 INFO - 1499132155027 Marionette TRACE 364 <- [1,7,null,{}]
18:35:55 INFO - 1499132155028 Marionette DEBUG Closed connection 364
18:35:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:35:55 INFO - 1499132155030 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49780
18:35:55 INFO - 1499132155031 Marionette DEBUG Closed connection 365
18:35:55 INFO - 1499132155033 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49781
18:35:55 INFO - 1499132155033 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155034 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155036 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155039 Marionette TRACE 366 <- [1,1,null,{"sessionId":"2fecb2b0-54f3-49e5-b9b1-05a1d1abe8fa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155040 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:35:55 INFO - 1499132155040 Marionette TRACE 366 <- [1,2,null,{}]
18:35:55 INFO - 1499132155041 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:55 INFO - 1499132155041 Marionette TRACE 366 <- [1,3,null,{}]
18:35:55 INFO - 1499132155042 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:55 INFO - 1499132155042 Marionette TRACE 366 <- [1,4,null,{}]
18:35:55 INFO - 1499132155044 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}]
18:35:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499132155047 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155048 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:35:55 INFO - 1499132155049 Marionette TRACE 366 <- [1,6,null,{}]
18:35:55 INFO - 1499132155050 Marionette DEBUG Closed connection 366
18:35:55 INFO - 1499132155051 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49782
18:35:55 INFO - 1499132155051 Marionette DEBUG Closed connection 367
18:35:55 INFO - 1499132155052 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49783
18:35:55 INFO - 1499132155053 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155053 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155055 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155058 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cd1acefc-0f41-4c8b-919d-83dea379be63","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155059 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:35:55 INFO - 1499132155060 Marionette TRACE 368 <- [1,2,null,{}]
18:35:55 INFO - 1499132155061 Marionette DEBUG Closed connection 368
18:35:55 INFO - 1499132155062 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49784
18:35:55 INFO - 1499132155063 Marionette DEBUG Closed connection 369
18:35:55 INFO - 1499132155063 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49785
18:35:55 INFO - 1499132155064 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:55 INFO - 1499132155065 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155067 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155070 Marionette TRACE 370 <- [1,1,null,{"sessionId":"20bdf77f-90ad-4dbf-aad1-0da6178945f8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155071 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"}]
18:35:55 INFO - 1499132155072 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"
18:35:55 INFO - 1499132155074 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"
18:35:55 INFO - 1499132155074 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"
18:35:55 INFO - 1499132155080 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"
18:35:55 INFO - 1499132155083 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"
18:35:55 INFO - 1499132155088 Marionette TRACE 370 <- [1,2,null,{}]
18:35:55 INFO - 1499132155090 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:35:55 INFO - 1499132155092 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ea4744b2-7f7d-4430-834d-1fecca5275cd","ELEMENT":"ea4744b2-7f7d-4430-834d-1fecca5275cd"}}]
18:35:55 INFO - 1499132155093 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ea4744b2-7f7d-4430-834d-1fecca5275cd"}]
18:35:55 INFO - 1499132155332 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:35:55 INFO - 1499132155332 Marionette TRACE 370 <- [1,4,null,{}]
18:35:55 INFO - 1499132155333 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ea4744b2-7f7d-4430-834d-1fecca5275cd","name":"selected"}]
18:35:55 INFO - 1499132155338 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:35:55 INFO - 1499132155339 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}]
18:35:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499132155341 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:35:55 INFO - 1499132155342 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:35:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 314ms
18:35:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:35:55 INFO - 1499132155343 Marionette TRACE 370 <- [1,7,null,{}]
18:35:55 INFO - 1499132155345 Marionette DEBUG Closed connection 370
18:35:55 INFO - 1499132155346 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49786
18:35:55 INFO - 1499132155346 Marionette DEBUG Closed connection 371
18:35:55 INFO - 1499132155347 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49787
18:35:55 INFO - 1499132155348 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155348 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155350 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155353 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ebde0723-7ff7-47c6-bfa8-9b3f6dd47b3c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155354 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:35:55 INFO - 1499132155354 Marionette TRACE 372 <- [1,2,null,{}]
18:35:55 INFO - 1499132155355 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:55 INFO - 1499132155356 Marionette TRACE 372 <- [1,3,null,{}]
18:35:55 INFO - 1499132155356 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:55 INFO - 1499132155357 Marionette TRACE 372 <- [1,4,null,{}]
18:35:55 INFO - 1499132155359 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}]
18:35:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499132155361 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155362 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:35:55 INFO - 1499132155363 Marionette TRACE 372 <- [1,6,null,{}]
18:35:55 INFO - 1499132155364 Marionette DEBUG Closed connection 372
18:35:55 INFO - 1499132155365 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49788
18:35:55 INFO - 1499132155366 Marionette DEBUG Closed connection 373
18:35:55 INFO - 1499132155366 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49789
18:35:55 INFO - 1499132155367 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155368 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155370 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155373 Marionette TRACE 374 <- [1,1,null,{"sessionId":"60162db6-f8f1-4073-853e-0ad119d9d271","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155374 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:35:55 INFO - 1499132155375 Marionette TRACE 374 <- [1,2,null,{}]
18:35:55 INFO - 1499132155377 Marionette DEBUG Closed connection 374
18:35:55 INFO - 1499132155377 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49790
18:35:55 INFO - 1499132155378 Marionette DEBUG Closed connection 375
18:35:55 INFO - 1499132155379 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49791
18:35:55 INFO - 1499132155379 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:55 INFO - 1499132155380 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155382 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155385 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8f86d3c6-1789-48a1-a2d6-16b7ef200d86","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155386 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"}]
18:35:55 INFO - 1499132155387 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"
18:35:55 INFO - 1499132155389 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"
18:35:55 INFO - 1499132155389 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"
18:35:55 INFO - 1499132155395 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"
18:35:55 INFO - 1499132155397 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"
18:35:55 INFO - 1499132155404 Marionette TRACE 376 <- [1,2,null,{}]
18:35:55 INFO - 1499132155407 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:35:55 INFO - 1499132155410 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8aa9a1f3-b48f-4a80-84a2-98ec8f6ae488","ELEMENT":"8aa9a1f3-b48f-4a80-84a2-98ec8f6ae488"}}]
18:35:55 INFO - 1499132155411 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8aa9a1f3-b48f-4a80-84a2-98ec8f6ae488"}]
18:35:55 INFO - 1499132155650 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:35:55 INFO - 1499132155651 Marionette TRACE 376 <- [1,4,null,{}]
18:35:55 INFO - 1499132155652 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}]
18:35:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 313ms
18:35:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499132155655 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155656 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:35:55 INFO - 1499132155657 Marionette TRACE 376 <- [1,6,null,{}]
18:35:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:35:55 INFO - 1499132155658 Marionette DEBUG Closed connection 376
18:35:55 INFO - 1499132155660 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49792
18:35:55 INFO - 1499132155661 Marionette DEBUG Closed connection 377
18:35:55 INFO - 1499132155661 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49793
18:35:55 INFO - 1499132155662 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155663 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155665 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155668 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c858e218-7b75-484f-a6ba-28f1841560a0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155669 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:35:55 INFO - 1499132155669 Marionette TRACE 378 <- [1,2,null,{}]
18:35:55 INFO - 1499132155671 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:55 INFO - 1499132155671 Marionette TRACE 378 <- [1,3,null,{}]
18:35:55 INFO - 1499132155672 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:55 INFO - 1499132155672 Marionette TRACE 378 <- [1,4,null,{}]
18:35:55 INFO - 1499132155674 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}]
18:35:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499132155676 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155678 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:35:55 INFO - 1499132155679 Marionette TRACE 378 <- [1,6,null,{}]
18:35:55 INFO - 1499132155680 Marionette DEBUG Closed connection 378
18:35:55 INFO - 1499132155680 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49794
18:35:55 INFO - 1499132155681 Marionette DEBUG Closed connection 379
18:35:55 INFO - 1499132155682 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49795
18:35:55 INFO - 1499132155683 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155683 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155685 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155688 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3db44ae3-9c7d-4465-84c7-ddf5c8887c0b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155689 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:35:55 INFO - 1499132155690 Marionette TRACE 380 <- [1,2,null,{}]
18:35:55 INFO - 1499132155691 Marionette DEBUG Closed connection 380
18:35:55 INFO - 1499132155692 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49796
18:35:55 INFO - 1499132155692 Marionette DEBUG Closed connection 381
18:35:55 INFO - 1499132155693 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49797
18:35:55 INFO - 1499132155694 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:55 INFO - 1499132155694 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155696 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155699 Marionette TRACE 382 <- [1,1,null,{"sessionId":"347df65e-5aaa-4001-b0f3-8007dfd7f7e7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155700 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"}]
18:35:55 INFO - 1499132155702 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"
18:35:55 INFO - 1499132155703 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"
18:35:55 INFO - 1499132155704 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"
18:35:55 INFO - 1499132155709 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"
18:35:55 INFO - 1499132155712 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"
18:35:55 INFO - 1499132155714 Marionette TRACE 382 <- [1,2,null,{}]
18:35:55 INFO - 1499132155720 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:35:55 INFO - 1499132155722 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0510beb4-7ee0-4988-9ed8-bb4afa4943fd","ELEMENT":"0510beb4-7ee0-4988-9ed8-bb4afa4943fd"}}]
18:35:55 INFO - 1499132155723 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0510beb4-7ee0-4988-9ed8-bb4afa4943fd"}]
18:35:55 INFO - 1499132155959 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:35:55 INFO - 1499132155960 Marionette TRACE 382 <- [1,4,null,{}]
18:35:55 INFO - 1499132155961 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}]
18:35:55 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499132155964 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155965 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:35:55 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 310ms
18:35:55 INFO - TEST-START | test_click.py TestClick.test_input_file
18:35:55 INFO - 1499132155966 Marionette TRACE 382 <- [1,6,null,{}]
18:35:55 INFO - 1499132155967 Marionette DEBUG Closed connection 382
18:35:55 INFO - 1499132155968 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49798
18:35:55 INFO - 1499132155969 Marionette DEBUG Closed connection 383
18:35:55 INFO - 1499132155969 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49799
18:35:55 INFO - 1499132155970 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155971 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155973 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155976 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ac4d2a4c-05b8-48ac-bdf8-c69251d20ace","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155977 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:35:55 INFO - 1499132155977 Marionette TRACE 384 <- [1,2,null,{}]
18:35:55 INFO - 1499132155978 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:55 INFO - 1499132155978 Marionette TRACE 384 <- [1,3,null,{}]
18:35:55 INFO - 1499132155979 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:55 INFO - 1499132155980 Marionette TRACE 384 <- [1,4,null,{}]
18:35:55 INFO - 1499132155982 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}]
18:35:55 INFO - TEST-START: test_click.py TestClick.test_input_file1499132155984 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:35:55 INFO - 1499132155984 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:35:55 INFO - 1499132155986 Marionette TRACE 384 <- [1,6,null,{}]
18:35:55 INFO - 1499132155987 Marionette DEBUG Closed connection 384
18:35:55 INFO - 1499132155987 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49800
18:35:55 INFO - 1499132155988 Marionette DEBUG Closed connection 385
18:35:55 INFO - 1499132155989 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49801
18:35:55 INFO - 1499132155990 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:55 INFO - 1499132155990 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:55 INFO - 1499132155992 Marionette DEBUG Register listener.js for window 4294967297
18:35:55 INFO - 1499132155995 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1235b84f-da60-4736-bcbe-7d6904cc8f1f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:55 INFO - 1499132155996 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:35:55 INFO - 1499132155997 Marionette TRACE 386 <- [1,2,null,{}]
18:35:56 INFO - 1499132155998 Marionette DEBUG Closed connection 386
18:35:56 INFO - 1499132155998 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49802
18:35:56 INFO - 1499132155999 Marionette DEBUG Closed connection 387
18:35:56 INFO - 1499132156000 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49803
18:35:56 INFO - 1499132156000 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:56 INFO - 1499132156001 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156003 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156006 Marionette TRACE 388 <- [1,1,null,{"sessionId":"11c04e71-a48a-4152-abb4-29a9ceece2d5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156007 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:35:56 INFO - 1499132156008 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"
18:35:56 INFO - 1499132156010 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"
18:35:56 INFO - 1499132156010 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"
18:35:56 INFO - 1499132156018 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:35:56 INFO - 1499132156019 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:35:56 INFO - 1499132156024 Marionette TRACE 388 <- [1,2,null,{}]
18:35:56 INFO - 1499132156026 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:35:56 INFO - 1499132156029 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e216f02-e5c7-4a36-acee-8b99d22fab25","ELEMENT":"2e216f02-e5c7-4a36-acee-8b99d22fab25"}}]
18:35:56 INFO - 1499132156030 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2e216f02-e5c7-4a36-acee-8b99d22fab25"}]
18:35:56 INFO - 1499132156060 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:35:56 INFO - 1499132156062 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}]
18:35:56 INFO - TEST-END: test_click.py TestClick.test_input_file1499132156065 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:35:56 INFO - 1499132156066 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:35:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 100ms
18:35:56 INFO - 1499132156067 Marionette TRACE 388 <- [1,6,null,{}]
18:35:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:35:56 INFO - 1499132156068 Marionette DEBUG Closed connection 388
18:35:56 INFO - 1499132156070 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49804
18:35:56 INFO - 1499132156070 Marionette DEBUG Closed connection 389
18:35:56 INFO - 1499132156071 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49805
18:35:56 INFO - 1499132156072 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:56 INFO - 1499132156072 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156081 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156084 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9b538c4a-cdec-4646-ba5f-3a67b3342fc9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156093 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:35:56 INFO - 1499132156094 Marionette TRACE 390 <- [1,2,null,{}]
18:35:56 INFO - 1499132156094 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:56 INFO - 1499132156095 Marionette TRACE 390 <- [1,3,null,{}]
18:35:56 INFO - 1499132156095 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:56 INFO - 1499132156096 Marionette TRACE 390 <- [1,4,null,{}]
18:35:56 INFO - 1499132156098 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}]
18:35:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499132156100 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:35:56 INFO - 1499132156101 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:35:56 INFO - 1499132156102 Marionette TRACE 390 <- [1,6,null,{}]
18:35:56 INFO - 1499132156103 Marionette DEBUG Closed connection 390
18:35:56 INFO - 1499132156104 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49806
18:35:56 INFO - 1499132156105 Marionette DEBUG Closed connection 391
18:35:56 INFO - 1499132156105 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49807
18:35:56 INFO - 1499132156106 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:56 INFO - 1499132156106 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156109 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156111 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f0362656-c0c6-40d4-8083-7407f35f0156","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156112 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:35:56 INFO - 1499132156113 Marionette TRACE 392 <- [1,2,null,{}]
18:35:56 INFO - 1499132156115 Marionette DEBUG Closed connection 392
18:35:56 INFO - 1499132156115 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49808
18:35:56 INFO - 1499132156116 Marionette DEBUG Closed connection 393
18:35:56 INFO - 1499132156117 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49809
18:35:56 INFO - 1499132156118 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:56 INFO - 1499132156118 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156120 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156123 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a8a2ecde-5b55-4a37-b068-74c7f9d63f05","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156124 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"}]
18:35:56 INFO - 1499132156125 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:35:56 INFO - 1499132156127 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:35:56 INFO - 1499132156127 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:35:56 INFO - 1499132156135 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"
18:35:56 INFO - 1499132156136 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"
18:35:56 INFO - 1499132156140 Marionette TRACE 394 <- [1,2,null,{}]
18:35:56 INFO - 1499132156143 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:35:56 INFO - 1499132156145 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"374673fd-7c91-46dd-9efa-6d91f7699cfb","ELEMENT":"374673fd-7c91-46dd-9efa-6d91f7699cfb"}}]
18:35:56 INFO - 1499132156146 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:35:56 INFO - 1499132156148 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b35fba1-ca1f-4585-bca5-23654270d272","ELEMENT":"2b35fba1-ca1f-4585-bca5-23654270d272"}}]
18:35:56 INFO - 1499132156152 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"374673fd-7c91-46dd-9efa-6d91f7699cfb"}]
18:35:56 INFO - 1499132156389 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:35:56 INFO - 1499132156390 Marionette TRACE 394 <- [1,5,null,{}]
18:35:56 INFO - 1499132156391 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"2b35fba1-ca1f-4585-bca5-23654270d272"}]
18:35:56 INFO - 1499132156406 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:35:56 INFO - 1499132156408 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:35:56 INFO - 1499132156410 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:35:56 INFO - 1499132156411 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}]
18:35:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499132156413 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:35:56 INFO - 1499132156414 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:35:56 INFO - 1499132156416 Marionette TRACE 394 <- [1,9,null,{}]
18:35:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
18:35:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:35:56 INFO - 1499132156418 Marionette DEBUG Closed connection 394
18:35:56 INFO - 1499132156419 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49810
18:35:56 INFO - 1499132156420 Marionette DEBUG Closed connection 395
18:35:56 INFO - 1499132156420 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49811
18:35:56 INFO - 1499132156421 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:56 INFO - 1499132156422 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156424 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156426 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fab5e380-2198-4406-8a52-9b3dc6daf5ae","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156427 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:35:56 INFO - 1499132156428 Marionette TRACE 396 <- [1,2,null,{}]
18:35:56 INFO - 1499132156429 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:35:56 INFO - 1499132156429 Marionette TRACE 396 <- [1,3,null,{}]
18:35:56 INFO - 1499132156430 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:35:56 INFO - 1499132156430 Marionette TRACE 396 <- [1,4,null,{}]
18:35:56 INFO - 1499132156432 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}]
18:35:56 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499132156434 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:35:56 INFO - 1499132156435 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:35:56 INFO - 1499132156436 Marionette TRACE 396 <- [1,6,null,{}]
18:35:56 INFO - 1499132156437 Marionette DEBUG Closed connection 396
18:35:56 INFO - 1499132156437 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49812
18:35:56 INFO - 1499132156438 Marionette DEBUG Closed connection 397
18:35:56 INFO - 1499132156439 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49813
18:35:56 INFO - 1499132156440 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:35:56 INFO - 1499132156441 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156443 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156445 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1f780320-a679-4016-9dbb-0a0c29a959b6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156446 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:35:56 INFO - 1499132156447 Marionette TRACE 398 <- [1,2,null,{}]
18:35:56 INFO - 1499132156449 Marionette DEBUG Closed connection 398
18:35:56 INFO - 1499132156449 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49814
18:35:56 INFO - 1499132156450 Marionette DEBUG Closed connection 399
18:35:56 INFO - 1499132156451 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49815
18:35:56 INFO - 1499132156452 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:35:56 INFO - 1499132156452 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}
18:35:56 INFO - 1499132156454 Marionette DEBUG Register listener.js for window 4294967297
18:35:56 INFO - 1499132156457 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4ef1f048-064e-4801-8755-570b6d92c9e3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":892,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-117\\AppData\\Local\\Temp\\tmpoi7kum.mozrunner","moz:accessibilityChecks":false}}]
18:35:56 INFO - 1499132156458 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"}]
18:35:56 INFO - 1499132156460 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"
18:35:56 INFO - 1499132156461 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"
18:35:56 INFO - 1499132156461 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"
18:35:56 INFO - 1499132156469 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"
18:35:56 INFO - 1499132156470 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"
18:35:56 INFO - 1499132156475 Marionette TRACE 400 <- [1,2,null,{}]
18:35:56 INFO - 1499132156478 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:35:56 INFO - 1499132156480 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e1c1b959-74fd-41be-83d9-66afb287a799","ELEMENT":"e1c1b959-74fd-41be-83d9-66afb287a799"}}]
18:35:56 INFO - 1499132156481 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e1c1b959-74fd-41be-83d9-66afb287a799"}]
18:35:56 INFO - 1499132156503 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:35:56 INFO - 1499132156504 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e1c1b959-74fd-41be-83d9-66afb287a799"}]
18:35:56 INFO - 1499132156518 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element