could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:13:34 INFO - 1500322414546 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}]
13:13:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500322414548 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500322414549 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:13:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 130ms
13:13:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:13:34 INFO - 1500322414550 Marionette TRACE 352 <- [1,6,null,{}]
13:13:34 INFO - 1500322414552 Marionette DEBUG Closed connection 352
13:13:34 INFO - 1500322414552 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49719
13:13:34 INFO - 1500322414553 Marionette DEBUG Closed connection 353
13:13:34 INFO - 1500322414553 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49720
13:13:34 INFO - 1500322414554 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500322414555 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414557 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414560 Marionette TRACE 354 <- [1,1,null,{"sessionId":"16066bd8-3bdf-4134-9565-5bdf11550282","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414562 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:13:34 INFO - 1500322414562 Marionette TRACE 354 <- [1,2,null,{}]
13:13:34 INFO - 1500322414563 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:34 INFO - 1500322414564 Marionette TRACE 354 <- [1,3,null,{}]
13:13:34 INFO - 1500322414564 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:34 INFO - 1500322414565 Marionette TRACE 354 <- [1,4,null,{}]
13:13:34 INFO - 1500322414566 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}]
13:13:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500322414568 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500322414569 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500322414570 Marionette TRACE 354 <- [1,6,null,{}]
13:13:34 INFO - 1500322414572 Marionette DEBUG Closed connection 354
13:13:34 INFO - 1500322414573 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49721
13:13:34 INFO - 1500322414573 Marionette DEBUG Closed connection 355
13:13:34 INFO - 1500322414574 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49722
13:13:34 INFO - 1500322414575 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500322414575 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414577 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414580 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d76f5850-04c1-4b8b-8303-0c8efab0aad9","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414582 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:13:34 INFO - 1500322414583 Marionette TRACE 356 <- [1,2,null,{}]
13:13:34 INFO - 1500322414585 Marionette DEBUG Closed connection 356
13:13:34 INFO - 1500322414586 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49723
13:13:34 INFO - 1500322414586 Marionette DEBUG Closed connection 357
13:13:34 INFO - 1500322414587 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49724
13:13:34 INFO - 1500322414588 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:34 INFO - 1500322414588 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414590 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414593 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d8df22b3-82a4-4f9e-90ea-b9b32f3ba079","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414595 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49288/clicks.html"}]
13:13:34 INFO - 1500322414596 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49288/hidden.html"
13:13:34 INFO - 1500322414601 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49288/hidden.html"
13:13:34 INFO - 1500322414601 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49288/hidden.html"
13:13:34 INFO - 1500322414615 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49288/clicks.html"
13:13:34 INFO - 1500322414619 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49288/clicks.html"
13:13:34 INFO - 1500322414622 Marionette TRACE 358 <- [1,2,null,{}]
13:13:34 INFO - 1500322414628 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:13:34 INFO - 1500322414630 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99b187b2-4fcf-4566-bef3-6306ae20018b","ELEMENT":"99b187b2-4fcf-4566-bef3-6306ae20018b"}}]
13:13:34 INFO - 1500322414631 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"99b187b2-4fcf-4566-bef3-6306ae20018b"}]
13:13:34 INFO - 1500322414676 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49288/clicks.html"
13:13:34 INFO - 1500322414683 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49288/clicks.html"
13:13:34 INFO - 1500322414684 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49288/clicks.html"
13:13:34 INFO - 1500322414698 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49288/test.html"
13:13:34 INFO - 1500322414701 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49288/test.html"
13:13:34 INFO - 1500322414703 Marionette TRACE 358 <- [1,4,null,{}]
13:13:34 INFO - 1500322414704 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:13:34 INFO - 1500322414705 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"69fc53e1-7d1e-4604-97a0-82b2bac3ae6a","ELEMENT":"69fc53e1-7d1e-4604-97a0-82b2bac3ae6a"}}]
13:13:34 INFO - 1500322414712 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:13:34 INFO - 1500322414712 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:13:34 INFO - 1500322414714 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}]
13:13:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500322414716 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:13:34 INFO - 1500322414717 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:13:34 INFO - 1500322414718 Marionette TRACE 358 <- [1,8,null,{}]
13:13:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 168ms
13:13:34 INFO - TEST-START | test_click.py TestClick.test_container_element
13:13:34 INFO - 1500322414720 Marionette DEBUG Closed connection 358
13:13:34 INFO - 1500322414721 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49727
13:13:34 INFO - 1500322414721 Marionette DEBUG Closed connection 359
13:13:34 INFO - 1500322414722 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49728
13:13:34 INFO - 1500322414723 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500322414723 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414726 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414729 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4a294c42-2c97-43e5-9cc2-e8d8f22f798a","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414731 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:13:34 INFO - 1500322414731 Marionette TRACE 360 <- [1,2,null,{}]
13:13:34 INFO - 1500322414732 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:34 INFO - 1500322414733 Marionette TRACE 360 <- [1,3,null,{}]
13:13:34 INFO - 1500322414733 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:34 INFO - 1500322414734 Marionette TRACE 360 <- [1,4,null,{}]
13:13:34 INFO - 1500322414735 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}]
13:13:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500322414737 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:13:34 INFO - 1500322414738 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:13:34 INFO - 1500322414739 Marionette TRACE 360 <- [1,6,null,{}]
13:13:34 INFO - 1500322414741 Marionette DEBUG Closed connection 360
13:13:34 INFO - 1500322414742 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49729
13:13:34 INFO - 1500322414742 Marionette DEBUG Closed connection 361
13:13:34 INFO - 1500322414743 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49730
13:13:34 INFO - 1500322414744 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:34 INFO - 1500322414744 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414746 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414749 Marionette TRACE 362 <- [1,1,null,{"sessionId":"69e8f0b8-0a2c-4603-b25d-3a06f7d2f42b","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414751 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:13:34 INFO - 1500322414752 Marionette TRACE 362 <- [1,2,null,{}]
13:13:34 INFO - 1500322414753 Marionette DEBUG Closed connection 362
13:13:34 INFO - 1500322414754 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49731
13:13:34 INFO - 1500322414754 Marionette DEBUG Closed connection 363
13:13:34 INFO - 1500322414755 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49732
13:13:34 INFO - 1500322414756 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:34 INFO - 1500322414756 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:34 INFO - 1500322414758 Marionette DEBUG Register listener.js for window 15
13:13:34 INFO - 1500322414761 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8795fae6-750d-43bf-b9b4-3b4c61ef3d0d","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:34 INFO - 1500322414763 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"}]
13:13:34 INFO - 1500322414764 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49288/test.html"
13:13:34 INFO - 1500322414766 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49288/test.html"
13:13:34 INFO - 1500322414766 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49288/test.html"
13:13:34 INFO - 1500322414784 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"
13:13:34 INFO - 1500322414787 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"
13:13:34 INFO - 1500322414790 Marionette TRACE 364 <- [1,2,null,{}]
13:13:34 INFO - 1500322414796 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:13:34 INFO - 1500322414798 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"32f5be0b-0896-4a80-958c-483f4cb7993a","ELEMENT":"32f5be0b-0896-4a80-958c-483f4cb7993a"}}]
13:13:34 INFO - 1500322414799 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"32f5be0b-0896-4a80-958c-483f4cb7993a"}]
13:13:35 INFO - 1500322415036 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:35 INFO - 1500322415037 Marionette TRACE 364 <- [1,4,null,{}]
13:13:35 INFO - 1500322415038 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"32f5be0b-0896-4a80-958c-483f4cb7993a","name":"selected"}]
13:13:35 INFO - 1500322415043 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:13:35 INFO - 1500322415044 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_container_element1500322415046 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:13:35 INFO - 1500322415047 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 332ms
13:13:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:13:35 INFO - 1500322415049 Marionette TRACE 364 <- [1,7,null,{}]
13:13:35 INFO - 1500322415051 Marionette DEBUG Closed connection 364
13:13:35 INFO - 1500322415051 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49733
13:13:35 INFO - 1500322415052 Marionette DEBUG Closed connection 365
13:13:35 INFO - 1500322415053 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49734
13:13:35 INFO - 1500322415054 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415054 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415056 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415059 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9b74c99b-835a-4731-b5cf-a5edb201ab56","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415061 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500322415061 Marionette TRACE 366 <- [1,2,null,{}]
13:13:35 INFO - 1500322415062 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500322415062 Marionette TRACE 366 <- [1,3,null,{}]
13:13:35 INFO - 1500322415063 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500322415063 Marionette TRACE 366 <- [1,4,null,{}]
13:13:35 INFO - 1500322415065 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500322415067 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500322415068 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500322415069 Marionette TRACE 366 <- [1,6,null,{}]
13:13:35 INFO - 1500322415071 Marionette DEBUG Closed connection 366
13:13:35 INFO - 1500322415071 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49735
13:13:35 INFO - 1500322415072 Marionette DEBUG Closed connection 367
13:13:35 INFO - 1500322415073 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49736
13:13:35 INFO - 1500322415073 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415074 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415076 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415078 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e574459c-71a0-4b68-bff1-6a471d9abb2e","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415081 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500322415082 Marionette TRACE 368 <- [1,2,null,{}]
13:13:35 INFO - 1500322415084 Marionette DEBUG Closed connection 368
13:13:35 INFO - 1500322415084 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49737
13:13:35 INFO - 1500322415085 Marionette DEBUG Closed connection 369
13:13:35 INFO - 1500322415085 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49738
13:13:35 INFO - 1500322415086 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500322415087 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415089 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415092 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0860ecf3-76b3-418e-b4f6-47d0c62fdf81","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415093 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"}]
13:13:35 INFO - 1500322415095 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"
13:13:35 INFO - 1500322415096 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"
13:13:35 INFO - 1500322415097 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"
13:13:35 INFO - 1500322415105 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"
13:13:35 INFO - 1500322415112 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"
13:13:35 INFO - 1500322415116 Marionette TRACE 370 <- [1,2,null,{}]
13:13:35 INFO - 1500322415117 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:13:35 INFO - 1500322415119 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b00c9c5d-0224-4873-ad6a-eb8936cd993c","ELEMENT":"b00c9c5d-0224-4873-ad6a-eb8936cd993c"}}]
13:13:35 INFO - 1500322415120 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b00c9c5d-0224-4873-ad6a-eb8936cd993c"}]
13:13:35 INFO - 1500322415373 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:35 INFO - 1500322415374 Marionette TRACE 370 <- [1,4,null,{}]
13:13:35 INFO - 1500322415375 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b00c9c5d-0224-4873-ad6a-eb8936cd993c","name":"selected"}]
13:13:35 INFO - 1500322415378 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:13:35 INFO - 1500322415380 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500322415382 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:13:35 INFO - 1500322415383 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
13:13:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:13:35 INFO - 1500322415384 Marionette TRACE 370 <- [1,7,null,{}]
13:13:35 INFO - 1500322415386 Marionette DEBUG Closed connection 370
13:13:35 INFO - 1500322415386 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49739
13:13:35 INFO - 1500322415387 Marionette DEBUG Closed connection 371
13:13:35 INFO - 1500322415388 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49740
13:13:35 INFO - 1500322415389 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415389 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415391 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415394 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4ee67907-a607-46de-892d-a2c69cb87e75","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415396 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500322415396 Marionette TRACE 372 <- [1,2,null,{}]
13:13:35 INFO - 1500322415397 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500322415397 Marionette TRACE 372 <- [1,3,null,{}]
13:13:35 INFO - 1500322415398 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500322415398 Marionette TRACE 372 <- [1,4,null,{}]
13:13:35 INFO - 1500322415400 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500322415402 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500322415402 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500322415403 Marionette TRACE 372 <- [1,6,null,{}]
13:13:35 INFO - 1500322415405 Marionette DEBUG Closed connection 372
13:13:35 INFO - 1500322415406 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49741
13:13:35 INFO - 1500322415406 Marionette DEBUG Closed connection 373
13:13:35 INFO - 1500322415407 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49742
13:13:35 INFO - 1500322415408 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415409 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415411 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415413 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a62c81a1-16b1-4e61-be04-a0577ec1b51e","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415415 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500322415416 Marionette TRACE 374 <- [1,2,null,{}]
13:13:35 INFO - 1500322415418 Marionette DEBUG Closed connection 374
13:13:35 INFO - 1500322415418 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49743
13:13:35 INFO - 1500322415419 Marionette DEBUG Closed connection 375
13:13:35 INFO - 1500322415420 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49744
13:13:35 INFO - 1500322415421 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500322415421 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415423 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415425 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ee393309-7c6a-40f4-844e-e3e5b3749525","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415427 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"}]
13:13:35 INFO - 1500322415428 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"
13:13:35 INFO - 1500322415430 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"
13:13:35 INFO - 1500322415431 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"
13:13:35 INFO - 1500322415448 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"
13:13:35 INFO - 1500322415450 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"
13:13:35 INFO - 1500322415453 Marionette TRACE 376 <- [1,2,null,{}]
13:13:35 INFO - 1500322415454 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:13:35 INFO - 1500322415461 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d787db66-5509-48c9-b99c-c7a80b179a7c","ELEMENT":"d787db66-5509-48c9-b99c-c7a80b179a7c"}}]
13:13:35 INFO - 1500322415463 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d787db66-5509-48c9-b99c-c7a80b179a7c"}]
13:13:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 321ms
13:13:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:13:35 INFO - 1500322415699 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:35 INFO - 1500322415700 Marionette TRACE 376 <- [1,4,null,{}]
13:13:35 INFO - 1500322415701 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}]
13:13:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500322415703 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500322415704 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500322415705 Marionette TRACE 376 <- [1,6,null,{}]
13:13:35 INFO - 1500322415707 Marionette DEBUG Closed connection 376
13:13:35 INFO - 1500322415708 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49745
13:13:35 INFO - 1500322415708 Marionette DEBUG Closed connection 377
13:13:35 INFO - 1500322415709 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49746
13:13:35 INFO - 1500322415710 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415710 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415712 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415721 Marionette TRACE 378 <- [1,1,null,{"sessionId":"19011c13-c0b7-4688-a5a7-a2de295bcdc9","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415723 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:13:35 INFO - 1500322415723 Marionette TRACE 378 <- [1,2,null,{}]
13:13:35 INFO - 1500322415724 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:35 INFO - 1500322415725 Marionette TRACE 378 <- [1,3,null,{}]
13:13:35 INFO - 1500322415726 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:35 INFO - 1500322415726 Marionette TRACE 378 <- [1,4,null,{}]
13:13:35 INFO - 1500322415728 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}]
13:13:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500322415730 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:13:35 INFO - 1500322415731 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:13:35 INFO - 1500322415732 Marionette TRACE 378 <- [1,6,null,{}]
13:13:35 INFO - 1500322415734 Marionette DEBUG Closed connection 378
13:13:35 INFO - 1500322415734 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49747
13:13:35 INFO - 1500322415735 Marionette DEBUG Closed connection 379
13:13:35 INFO - 1500322415735 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49748
13:13:35 INFO - 1500322415736 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:35 INFO - 1500322415737 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415739 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415741 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c628571f-a164-4ddf-95f9-d5aa49d9d2c5","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415743 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:13:35 INFO - 1500322415743 Marionette TRACE 380 <- [1,2,null,{}]
13:13:35 INFO - 1500322415745 Marionette DEBUG Closed connection 380
13:13:35 INFO - 1500322415746 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49749
13:13:35 INFO - 1500322415746 Marionette DEBUG Closed connection 381
13:13:35 INFO - 1500322415747 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49750
13:13:35 INFO - 1500322415748 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:35 INFO - 1500322415748 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:35 INFO - 1500322415750 Marionette DEBUG Register listener.js for window 15
13:13:35 INFO - 1500322415752 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d4d8f5b3-d2cf-447a-b44a-eb13769a0c1b","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:35 INFO - 1500322415754 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"}]
13:13:35 INFO - 1500322415755 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"
13:13:35 INFO - 1500322415757 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"
13:13:35 INFO - 1500322415757 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"
13:13:35 INFO - 1500322415766 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"
13:13:35 INFO - 1500322415769 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"
13:13:35 INFO - 1500322415771 Marionette TRACE 382 <- [1,2,null,{}]
13:13:35 INFO - 1500322415772 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:13:35 INFO - 1500322415782 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5708c82d-2adf-4e68-b033-c7a392e7f9f0","ELEMENT":"5708c82d-2adf-4e68-b033-c7a392e7f9f0"}}]
13:13:35 INFO - 1500322415783 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5708c82d-2adf-4e68-b033-c7a392e7f9f0"}]
13:13:36 INFO - 1500322416020 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:36 INFO - 1500322416021 Marionette TRACE 382 <- [1,4,null,{}]
13:13:36 INFO - 1500322416022 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}]
13:13:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500322416024 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:13:36 INFO - 1500322416025 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:13:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 321ms
13:13:36 INFO - 1500322416027 Marionette TRACE 382 <- [1,6,null,{}]
13:13:36 INFO - TEST-START | test_click.py TestClick.test_input_file
13:13:36 INFO - 1500322416028 Marionette DEBUG Closed connection 382
13:13:36 INFO - 1500322416030 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49751
13:13:36 INFO - 1500322416030 Marionette DEBUG Closed connection 383
13:13:36 INFO - 1500322416031 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49752
13:13:36 INFO - 1500322416032 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416032 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416035 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416037 Marionette TRACE 384 <- [1,1,null,{"sessionId":"11f73b99-d6f5-4e8b-b716-6ae6e4183aed","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416039 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:13:36 INFO - 1500322416040 Marionette TRACE 384 <- [1,2,null,{}]
13:13:36 INFO - 1500322416040 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:36 INFO - 1500322416041 Marionette TRACE 384 <- [1,3,null,{}]
13:13:36 INFO - 1500322416041 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:36 INFO - 1500322416042 Marionette TRACE 384 <- [1,4,null,{}]
13:13:36 INFO - 1500322416043 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}]
13:13:36 INFO - TEST-START: test_click.py TestClick.test_input_file1500322416045 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:13:36 INFO - 1500322416046 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:13:36 INFO - 1500322416047 Marionette TRACE 384 <- [1,6,null,{}]
13:13:36 INFO - 1500322416049 Marionette DEBUG Closed connection 384
13:13:36 INFO - 1500322416050 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49753
13:13:36 INFO - 1500322416050 Marionette DEBUG Closed connection 385
13:13:36 INFO - 1500322416051 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49754
13:13:36 INFO - 1500322416052 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416052 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416054 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416057 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3df6d223-e180-421f-833d-12f42c59d135","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416059 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:13:36 INFO - 1500322416060 Marionette TRACE 386 <- [1,2,null,{}]
13:13:36 INFO - 1500322416061 Marionette DEBUG Closed connection 386
13:13:36 INFO - 1500322416062 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49755
13:13:36 INFO - 1500322416063 Marionette DEBUG Closed connection 387
13:13:36 INFO - 1500322416063 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49756
13:13:36 INFO - 1500322416064 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:36 INFO - 1500322416064 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416066 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416069 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ae3f6c3a-e6eb-42b4-a565-af2291e36b96","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416070 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:13:36 INFO - 1500322416072 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"
13:13:36 INFO - 1500322416073 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"
13:13:36 INFO - 1500322416074 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"
13:13:36 INFO - 1500322416083 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:36 INFO - 1500322416086 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:36 INFO - 1500322416089 Marionette TRACE 388 <- [1,2,null,{}]
13:13:36 INFO - 1500322416096 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:13:36 INFO - 1500322416098 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6a9dfe98-282d-488b-a440-02a7b5505624","ELEMENT":"6a9dfe98-282d-488b-a440-02a7b5505624"}}]
13:13:36 INFO - 1500322416099 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6a9dfe98-282d-488b-a440-02a7b5505624"}]
13:13:36 INFO - 1500322416130 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:13:36 INFO - 1500322416132 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}]
13:13:36 INFO - TEST-END: test_click.py TestClick.test_input_file1500322416134 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:13:36 INFO - 1500322416135 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:13:36 INFO - 1500322416136 Marionette TRACE 388 <- [1,6,null,{}]
13:13:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 108ms
13:13:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:13:36 INFO - 1500322416137 Marionette DEBUG Closed connection 388
13:13:36 INFO - 1500322416138 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49757
13:13:36 INFO - 1500322416139 Marionette DEBUG Closed connection 389
13:13:36 INFO - 1500322416140 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49758
13:13:36 INFO - 1500322416141 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416141 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416143 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416146 Marionette TRACE 390 <- [1,1,null,{"sessionId":"30ec3445-2da7-4e8b-bd9a-1fd469f12ccd","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416148 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:13:36 INFO - 1500322416148 Marionette TRACE 390 <- [1,2,null,{}]
13:13:36 INFO - 1500322416149 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:36 INFO - 1500322416149 Marionette TRACE 390 <- [1,3,null,{}]
13:13:36 INFO - 1500322416150 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:36 INFO - 1500322416151 Marionette TRACE 390 <- [1,4,null,{}]
13:13:36 INFO - 1500322416153 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}]
13:13:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500322416155 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:13:36 INFO - 1500322416156 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:13:36 INFO - 1500322416157 Marionette TRACE 390 <- [1,6,null,{}]
13:13:36 INFO - 1500322416159 Marionette DEBUG Closed connection 390
13:13:36 INFO - 1500322416159 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49759
13:13:36 INFO - 1500322416160 Marionette DEBUG Closed connection 391
13:13:36 INFO - 1500322416160 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49760
13:13:36 INFO - 1500322416161 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416162 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416164 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416166 Marionette TRACE 392 <- [1,1,null,{"sessionId":"7052949b-3cd9-4eed-ac1b-546d688c847f","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416168 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:13:36 INFO - 1500322416169 Marionette TRACE 392 <- [1,2,null,{}]
13:13:36 INFO - 1500322416171 Marionette DEBUG Closed connection 392
13:13:36 INFO - 1500322416171 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49761
13:13:36 INFO - 1500322416172 Marionette DEBUG Closed connection 393
13:13:36 INFO - 1500322416172 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49762
13:13:36 INFO - 1500322416173 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:36 INFO - 1500322416174 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416176 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416178 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a4f28e80-0d1e-4ac9-9e60-e19728f29df9","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416180 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"}]
13:13:36 INFO - 1500322416181 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:36 INFO - 1500322416182 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:36 INFO - 1500322416183 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:13:36 INFO - 1500322416198 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"
13:13:36 INFO - 1500322416200 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"
13:13:36 INFO - 1500322416202 Marionette TRACE 394 <- [1,2,null,{}]
13:13:36 INFO - 1500322416203 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:13:36 INFO - 1500322416205 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b35c87a3-d9c8-4592-972b-95e4fc679077","ELEMENT":"b35c87a3-d9c8-4592-972b-95e4fc679077"}}]
13:13:36 INFO - 1500322416206 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:13:36 INFO - 1500322416212 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f699fa22-cda9-45a7-902c-363fc2cfcc60","ELEMENT":"f699fa22-cda9-45a7-902c-363fc2cfcc60"}}]
13:13:36 INFO - 1500322416213 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b35c87a3-d9c8-4592-972b-95e4fc679077"}]
13:13:36 INFO - 1500322416448 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:13:36 INFO - 1500322416449 Marionette TRACE 394 <- [1,5,null,{}]
13:13:36 INFO - 1500322416450 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f699fa22-cda9-45a7-902c-363fc2cfcc60"}]
13:13:36 INFO - 1500322416463 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:13:36 INFO - 1500322416464 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:13:36 INFO - 1500322416466 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:13:36 INFO - 1500322416469 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}]
13:13:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500322416471 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:13:36 INFO - 1500322416472 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:13:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
13:13:36 INFO - 1500322416473 Marionette TRACE 394 <- [1,9,null,{}]
13:13:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:13:36 INFO - 1500322416475 Marionette DEBUG Closed connection 394
13:13:36 INFO - 1500322416476 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49763
13:13:36 INFO - 1500322416476 Marionette DEBUG Closed connection 395
13:13:36 INFO - 1500322416477 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49764
13:13:36 INFO - 1500322416478 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416478 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416480 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416483 Marionette TRACE 396 <- [1,1,null,{"sessionId":"cb76f414-f852-42b2-a7b4-a2a2be2dfb15","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416485 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:13:36 INFO - 1500322416485 Marionette TRACE 396 <- [1,2,null,{}]
13:13:36 INFO - 1500322416486 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:13:36 INFO - 1500322416487 Marionette TRACE 396 <- [1,3,null,{}]
13:13:36 INFO - 1500322416488 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:13:36 INFO - 1500322416488 Marionette TRACE 396 <- [1,4,null,{}]
13:13:36 INFO - 1500322416490 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}]
13:13:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500322416492 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:13:36 INFO - 1500322416493 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:13:36 INFO - 1500322416494 Marionette TRACE 396 <- [1,6,null,{}]
13:13:36 INFO - 1500322416496 Marionette DEBUG Closed connection 396
13:13:36 INFO - 1500322416496 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49765
13:13:36 INFO - 1500322416497 Marionette DEBUG Closed connection 397
13:13:36 INFO - 1500322416497 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49766
13:13:36 INFO - 1500322416498 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:13:36 INFO - 1500322416499 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416501 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416510 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6af18ef3-f904-4bbb-aa4b-d54a771ec194","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416512 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:13:36 INFO - 1500322416513 Marionette TRACE 398 <- [1,2,null,{}]
13:13:36 INFO - 1500322416515 Marionette DEBUG Closed connection 398
13:13:36 INFO - 1500322416515 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49767
13:13:36 INFO - 1500322416516 Marionette DEBUG Closed connection 399
13:13:36 INFO - 1500322416517 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49768
13:13:36 INFO - 1500322416517 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:13:36 INFO - 1500322416518 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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}
13:13:36 INFO - 1500322416520 Marionette DEBUG Register listener.js for window 15
13:13:36 INFO - 1500322416522 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4f16f2fc-013c-45ff-a076-4e0b19017535","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":3560,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-131\\AppData\\Local\\Temp\\tmpsnvutl.mozrunner","moz:accessibilityChecks":false}}]
13:13:36 INFO - 1500322416524 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"}]
13:13:36 INFO - 1500322416525 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"
13:13:36 INFO - 1500322416527 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"
13:13:36 INFO - 1500322416527 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"
13:13:36 INFO - 1500322416539 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"
13:13:36 INFO - 1500322416542 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"
13:13:36 INFO - 1500322416551 Marionette TRACE 400 <- [1,2,null,{}]
13:13:36 INFO - 1500322416553 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:13:36 INFO - 1500322416555 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8e5ab03-3076-4ea4-a0bd-352af7348cf3","ELEMENT":"d8e5ab03-3076-4ea4-a0bd-352af7348cf3"}}]
13:13:36 INFO - 1500322416556 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d8e5ab03-3076-4ea4-a0bd-352af7348cf3"}]
13:13:36 INFO - 1500322416577 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:13:36 INFO - 1500322416589 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d8e5ab03-3076-4ea4-a0bd-352af7348cf3"}]
13:13:36 INFO - 1500322416603 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element