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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:08:34 INFO - 1500721714458 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}]
04:08:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500721714462 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:08:34 INFO - 1500721714463 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:08:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 204ms
04:08:34 INFO - 1500721714465 Marionette TRACE 352 <- [1,6,null,{}]
04:08:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:08:34 INFO - 1500721714468 Marionette DEBUG Closed connection 352
04:08:34 INFO - 1500721714469 Marionette DEBUG Accepted connection 353 from 127.0.0.1:51360
04:08:34 INFO - 1500721714470 Marionette DEBUG Closed connection 353
04:08:34 INFO - 1500721714470 Marionette DEBUG Accepted connection 354 from 127.0.0.1:51361
04:08:34 INFO - 1500721714472 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:34 INFO - 1500721714472 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714475 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714499 Marionette TRACE 354 <- [1,1,null,{"sessionId":"e4ac26c5-3c8d-4354-9616-79e88cdb8278","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714501 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:08:34 INFO - 1500721714502 Marionette TRACE 354 <- [1,2,null,{}]
04:08:34 INFO - 1500721714503 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:34 INFO - 1500721714504 Marionette TRACE 354 <- [1,3,null,{}]
04:08:34 INFO - 1500721714505 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:34 INFO - 1500721714505 Marionette TRACE 354 <- [1,4,null,{}]
04:08:34 INFO - 1500721714507 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}]
04:08:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500721714510 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:08:34 INFO - 1500721714511 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:08:34 INFO - 1500721714513 Marionette TRACE 354 <- [1,6,null,{}]
04:08:34 INFO - 1500721714515 Marionette DEBUG Closed connection 354
04:08:34 INFO - 1500721714516 Marionette DEBUG Accepted connection 355 from 127.0.0.1:51362
04:08:34 INFO - 1500721714517 Marionette DEBUG Closed connection 355
04:08:34 INFO - 1500721714518 Marionette DEBUG Accepted connection 356 from 127.0.0.1:51363
04:08:34 INFO - 1500721714519 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:34 INFO - 1500721714519 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714522 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714533 Marionette TRACE 356 <- [1,1,null,{"sessionId":"10a88790-3a7e-4183-b98b-dc3f7a71fc39","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714536 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:08:34 INFO - 1500721714538 Marionette TRACE 356 <- [1,2,null,{}]
04:08:34 INFO - 1500721714540 Marionette DEBUG Closed connection 356
04:08:34 INFO - 1500721714541 Marionette DEBUG Accepted connection 357 from 127.0.0.1:51364
04:08:34 INFO - 1500721714542 Marionette DEBUG Closed connection 357
04:08:34 INFO - 1500721714542 Marionette DEBUG Accepted connection 358 from 127.0.0.1:51365
04:08:34 INFO - 1500721714544 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:34 INFO - 1500721714544 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714547 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714551 Marionette TRACE 358 <- [1,1,null,{"sessionId":"81f7e167-1d9a-43e5-acc2-02c5cf21f2b8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714553 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50924/clicks.html"}]
04:08:34 INFO - 1500721714555 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50924/hidden.html"
04:08:34 INFO - 1500721714561 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50924/hidden.html"
04:08:34 INFO - 1500721714562 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50924/hidden.html"
04:08:34 INFO - 1500721714599 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50924/clicks.html"
04:08:34 INFO - 1500721714601 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50924/clicks.html"
04:08:34 INFO - 1500721714605 Marionette TRACE 358 <- [1,2,null,{}]
04:08:34 INFO - 1500721714607 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:08:34 INFO - 1500721714617 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d99c0765-34cc-42fa-9cba-740e74fb0fd8","ELEMENT":"d99c0765-34cc-42fa-9cba-740e74fb0fd8"}}]
04:08:34 INFO - 1500721714632 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d99c0765-34cc-42fa-9cba-740e74fb0fd8"}]
04:08:34 INFO - 1500721714677 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50924/clicks.html"
04:08:34 INFO - 1500721714686 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50924/clicks.html"
04:08:34 INFO - 1500721714686 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50924/clicks.html"
04:08:34 INFO - 1500721714724 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50924/test.html"
04:08:34 INFO - 1500721714734 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50924/test.html"
04:08:34 INFO - 1500721714738 Marionette TRACE 358 <- [1,4,null,{}]
04:08:34 INFO - 1500721714748 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:08:34 INFO - 1500721714754 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27eb0afa-465d-4e00-858d-5de24989eaab","ELEMENT":"27eb0afa-465d-4e00-858d-5de24989eaab"}}]
04:08:34 INFO - 1500721714757 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:08:34 INFO - 1500721714758 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:08:34 INFO - 1500721714761 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}]
04:08:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500721714764 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:08:34 INFO - 1500721714766 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:08:34 INFO - 1500721714767 Marionette TRACE 358 <- [1,8,null,{}]
04:08:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 302ms
04:08:34 INFO - TEST-START | test_click.py TestClick.test_container_element
04:08:34 INFO - 1500721714770 Marionette DEBUG Closed connection 358
04:08:34 INFO - 1500721714771 Marionette DEBUG Accepted connection 359 from 127.0.0.1:51368
04:08:34 INFO - 1500721714772 Marionette DEBUG Closed connection 359
04:08:34 INFO - 1500721714772 Marionette DEBUG Accepted connection 360 from 127.0.0.1:51369
04:08:34 INFO - 1500721714774 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:34 INFO - 1500721714774 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714777 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714781 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5dfeedfd-0068-4d20-8c37-1891204b135a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714783 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:08:34 INFO - 1500721714784 Marionette TRACE 360 <- [1,2,null,{}]
04:08:34 INFO - 1500721714785 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:34 INFO - 1500721714785 Marionette TRACE 360 <- [1,3,null,{}]
04:08:34 INFO - 1500721714786 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:34 INFO - 1500721714787 Marionette TRACE 360 <- [1,4,null,{}]
04:08:34 INFO - 1500721714789 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}]
04:08:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500721714792 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:08:34 INFO - 1500721714793 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:08:34 INFO - 1500721714795 Marionette TRACE 360 <- [1,6,null,{}]
04:08:34 INFO - 1500721714797 Marionette DEBUG Closed connection 360
04:08:34 INFO - 1500721714798 Marionette DEBUG Accepted connection 361 from 127.0.0.1:51370
04:08:34 INFO - 1500721714799 Marionette DEBUG Closed connection 361
04:08:34 INFO - 1500721714799 Marionette DEBUG Accepted connection 362 from 127.0.0.1:51371
04:08:34 INFO - 1500721714800 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:34 INFO - 1500721714801 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714804 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714807 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4c9ffa88-cbd9-48eb-b02d-bfae507ac06d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714810 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:08:34 INFO - 1500721714811 Marionette TRACE 362 <- [1,2,null,{}]
04:08:34 INFO - 1500721714814 Marionette DEBUG Closed connection 362
04:08:34 INFO - 1500721714815 Marionette DEBUG Accepted connection 363 from 127.0.0.1:51372
04:08:34 INFO - 1500721714816 Marionette DEBUG Closed connection 363
04:08:34 INFO - 1500721714817 Marionette DEBUG Accepted connection 364 from 127.0.0.1:51373
04:08:34 INFO - 1500721714818 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:34 INFO - 1500721714819 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:34 INFO - 1500721714822 Marionette DEBUG Register listener.js for window 15
04:08:34 INFO - 1500721714825 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3c2a3a10-cb3c-4204-bbcd-1998c524b6d1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:34 INFO - 1500721714828 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"}]
04:08:34 INFO - 1500721714830 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50924/test.html"
04:08:34 INFO - 1500721714832 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50924/test.html"
04:08:34 INFO - 1500721714833 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50924/test.html"
04:08:34 INFO - 1500721714863 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"
04:08:34 INFO - 1500721714867 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"
04:08:34 INFO - 1500721714871 Marionette TRACE 364 <- [1,2,null,{}]
04:08:34 INFO - 1500721714873 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:08:34 INFO - 1500721714884 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e83107ed-9aea-44ec-81a7-e025f1809329","ELEMENT":"e83107ed-9aea-44ec-81a7-e025f1809329"}}]
04:08:34 INFO - 1500721714886 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e83107ed-9aea-44ec-81a7-e025f1809329"}]
04:08:35 INFO - 1500721715131 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:35 INFO - 1500721715132 Marionette TRACE 364 <- [1,4,null,{}]
04:08:35 INFO - 1500721715133 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e83107ed-9aea-44ec-81a7-e025f1809329","name":"selected"}]
04:08:35 INFO - 1500721715138 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:08:35 INFO - 1500721715140 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}]
04:08:35 INFO - TEST-END: test_click.py TestClick.test_container_element1500721715143 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:08:35 INFO - 1500721715144 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:08:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 378ms
04:08:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:08:35 INFO - 1500721715146 Marionette TRACE 364 <- [1,7,null,{}]
04:08:35 INFO - 1500721715148 Marionette DEBUG Closed connection 364
04:08:35 INFO - 1500721715149 Marionette DEBUG Accepted connection 365 from 127.0.0.1:51374
04:08:35 INFO - 1500721715150 Marionette DEBUG Closed connection 365
04:08:35 INFO - 1500721715151 Marionette DEBUG Accepted connection 366 from 127.0.0.1:51375
04:08:35 INFO - 1500721715152 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715153 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715156 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715159 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d7763d10-0a19-4fc1-ad58-08d457bc09bb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715162 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:08:35 INFO - 1500721715162 Marionette TRACE 366 <- [1,2,null,{}]
04:08:35 INFO - 1500721715163 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:35 INFO - 1500721715164 Marionette TRACE 366 <- [1,3,null,{}]
04:08:35 INFO - 1500721715165 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:35 INFO - 1500721715165 Marionette TRACE 366 <- [1,4,null,{}]
04:08:35 INFO - 1500721715167 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}]
04:08:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500721715170 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:08:35 INFO - 1500721715171 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:08:35 INFO - 1500721715173 Marionette TRACE 366 <- [1,6,null,{}]
04:08:35 INFO - 1500721715175 Marionette DEBUG Closed connection 366
04:08:35 INFO - 1500721715176 Marionette DEBUG Accepted connection 367 from 127.0.0.1:51376
04:08:35 INFO - 1500721715177 Marionette DEBUG Closed connection 367
04:08:35 INFO - 1500721715178 Marionette DEBUG Accepted connection 368 from 127.0.0.1:51377
04:08:35 INFO - 1500721715179 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715180 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715183 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715194 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c7e42762-c6e5-4a8c-ae7f-9414d6be1d49","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715196 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:08:35 INFO - 1500721715198 Marionette TRACE 368 <- [1,2,null,{}]
04:08:35 INFO - 1500721715200 Marionette DEBUG Closed connection 368
04:08:35 INFO - 1500721715201 Marionette DEBUG Accepted connection 369 from 127.0.0.1:51380
04:08:35 INFO - 1500721715202 Marionette DEBUG Closed connection 369
04:08:35 INFO - 1500721715203 Marionette DEBUG Accepted connection 370 from 127.0.0.1:51381
04:08:35 INFO - 1500721715204 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:35 INFO - 1500721715205 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715207 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715211 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cfc0328c-7bce-4682-b31d-7af8dbd88f30","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715213 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"}]
04:08:35 INFO - 1500721715216 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"
04:08:35 INFO - 1500721715219 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"
04:08:35 INFO - 1500721715220 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"
04:08:35 INFO - 1500721715245 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"
04:08:35 INFO - 1500721715246 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"
04:08:35 INFO - 1500721715251 Marionette TRACE 370 <- [1,2,null,{}]
04:08:35 INFO - 1500721715252 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:08:35 INFO - 1500721715255 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"96ce366b-22fc-40fc-8719-7dae5770dfb2","ELEMENT":"96ce366b-22fc-40fc-8719-7dae5770dfb2"}}]
04:08:35 INFO - 1500721715263 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"96ce366b-22fc-40fc-8719-7dae5770dfb2"}]
04:08:35 INFO - 1500721715510 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:35 INFO - 1500721715511 Marionette TRACE 370 <- [1,4,null,{}]
04:08:35 INFO - 1500721715512 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"96ce366b-22fc-40fc-8719-7dae5770dfb2","name":"selected"}]
04:08:35 INFO - 1500721715517 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:08:35 INFO - 1500721715519 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}]
04:08:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500721715522 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:08:35 INFO - 1500721715523 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:08:35 INFO - 1500721715525 Marionette TRACE 370 <- [1,7,null,{}]
04:08:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 379ms
04:08:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:08:35 INFO - 1500721715527 Marionette DEBUG Closed connection 370
04:08:35 INFO - 1500721715528 Marionette DEBUG Accepted connection 371 from 127.0.0.1:51382
04:08:35 INFO - 1500721715529 Marionette DEBUG Closed connection 371
04:08:35 INFO - 1500721715530 Marionette DEBUG Accepted connection 372 from 127.0.0.1:51383
04:08:35 INFO - 1500721715531 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715531 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715534 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715538 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0b934846-f968-4463-a8f5-0d921e10e1cb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715540 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:08:35 INFO - 1500721715541 Marionette TRACE 372 <- [1,2,null,{}]
04:08:35 INFO - 1500721715542 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:35 INFO - 1500721715542 Marionette TRACE 372 <- [1,3,null,{}]
04:08:35 INFO - 1500721715543 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:35 INFO - 1500721715544 Marionette TRACE 372 <- [1,4,null,{}]
04:08:35 INFO - 1500721715545 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}]
04:08:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500721715548 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:08:35 INFO - 1500721715549 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:08:35 INFO - 1500721715551 Marionette TRACE 372 <- [1,6,null,{}]
04:08:35 INFO - 1500721715553 Marionette DEBUG Closed connection 372
04:08:35 INFO - 1500721715554 Marionette DEBUG Accepted connection 373 from 127.0.0.1:51384
04:08:35 INFO - 1500721715555 Marionette DEBUG Closed connection 373
04:08:35 INFO - 1500721715556 Marionette DEBUG Accepted connection 374 from 127.0.0.1:51385
04:08:35 INFO - 1500721715557 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715558 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715561 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715564 Marionette TRACE 374 <- [1,1,null,{"sessionId":"49c5edbb-7ac8-40e6-9956-c0c1a4a2321a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715567 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:08:35 INFO - 1500721715568 Marionette TRACE 374 <- [1,2,null,{}]
04:08:35 INFO - 1500721715570 Marionette DEBUG Closed connection 374
04:08:35 INFO - 1500721715571 Marionette DEBUG Accepted connection 375 from 127.0.0.1:51386
04:08:35 INFO - 1500721715572 Marionette DEBUG Closed connection 375
04:08:35 INFO - 1500721715573 Marionette DEBUG Accepted connection 376 from 127.0.0.1:51387
04:08:35 INFO - 1500721715574 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:35 INFO - 1500721715575 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715578 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715581 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7f66c9bf-665b-4f84-881a-03448109212d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715583 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"}]
04:08:35 INFO - 1500721715585 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"
04:08:35 INFO - 1500721715588 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"
04:08:35 INFO - 1500721715588 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"
04:08:35 INFO - 1500721715611 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"
04:08:35 INFO - 1500721715614 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"
04:08:35 INFO - 1500721715618 Marionette TRACE 376 <- [1,2,null,{}]
04:08:35 INFO - 1500721715619 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:08:35 INFO - 1500721715622 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ebaa379d-e6d1-4d18-8e36-343ac3bcf97f","ELEMENT":"ebaa379d-e6d1-4d18-8e36-343ac3bcf97f"}}]
04:08:35 INFO - 1500721715631 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ebaa379d-e6d1-4d18-8e36-343ac3bcf97f"}]
04:08:35 INFO - 1500721715874 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:35 INFO - 1500721715875 Marionette TRACE 376 <- [1,4,null,{}]
04:08:35 INFO - 1500721715877 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}]
04:08:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500721715881 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:08:35 INFO - 1500721715882 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:08:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 358ms
04:08:35 INFO - 1500721715883 Marionette TRACE 376 <- [1,6,null,{}]
04:08:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:08:35 INFO - 1500721715886 Marionette DEBUG Closed connection 376
04:08:35 INFO - 1500721715887 Marionette DEBUG Accepted connection 377 from 127.0.0.1:51388
04:08:35 INFO - 1500721715888 Marionette DEBUG Closed connection 377
04:08:35 INFO - 1500721715888 Marionette DEBUG Accepted connection 378 from 127.0.0.1:51389
04:08:35 INFO - 1500721715890 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715890 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715893 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715897 Marionette TRACE 378 <- [1,1,null,{"sessionId":"835289d7-d2c9-4326-910b-1819f5db9628","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715900 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:08:35 INFO - 1500721715900 Marionette TRACE 378 <- [1,2,null,{}]
04:08:35 INFO - 1500721715901 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:35 INFO - 1500721715902 Marionette TRACE 378 <- [1,3,null,{}]
04:08:35 INFO - 1500721715903 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:35 INFO - 1500721715903 Marionette TRACE 378 <- [1,4,null,{}]
04:08:35 INFO - 1500721715905 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}]
04:08:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500721715908 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:08:35 INFO - 1500721715909 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:08:35 INFO - 1500721715911 Marionette TRACE 378 <- [1,6,null,{}]
04:08:35 INFO - 1500721715913 Marionette DEBUG Closed connection 378
04:08:35 INFO - 1500721715914 Marionette DEBUG Accepted connection 379 from 127.0.0.1:51390
04:08:35 INFO - 1500721715915 Marionette DEBUG Closed connection 379
04:08:35 INFO - 1500721715916 Marionette DEBUG Accepted connection 380 from 127.0.0.1:51391
04:08:35 INFO - 1500721715917 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:35 INFO - 1500721715917 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715920 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715924 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f401f187-5da0-4d62-9ca4-bd23eca67436","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715926 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:08:35 INFO - 1500721715928 Marionette TRACE 380 <- [1,2,null,{}]
04:08:35 INFO - 1500721715930 Marionette DEBUG Closed connection 380
04:08:35 INFO - 1500721715931 Marionette DEBUG Accepted connection 381 from 127.0.0.1:51392
04:08:35 INFO - 1500721715932 Marionette DEBUG Closed connection 381
04:08:35 INFO - 1500721715932 Marionette DEBUG Accepted connection 382 from 127.0.0.1:51393
04:08:35 INFO - 1500721715933 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:35 INFO - 1500721715941 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:35 INFO - 1500721715944 Marionette DEBUG Register listener.js for window 15
04:08:35 INFO - 1500721715948 Marionette TRACE 382 <- [1,1,null,{"sessionId":"aff86e1a-7378-4c20-8a45-f9c2476aa519","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:35 INFO - 1500721715951 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"}]
04:08:35 INFO - 1500721715952 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"
04:08:35 INFO - 1500721715955 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"
04:08:35 INFO - 1500721715955 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"
04:08:35 INFO - 1500721715977 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"
04:08:35 INFO - 1500721715980 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"
04:08:35 INFO - 1500721715985 Marionette TRACE 382 <- [1,2,null,{}]
04:08:35 INFO - 1500721715986 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:08:35 INFO - 1500721715989 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1738f3ed-9331-46a2-bba0-bd36dd13f7a0","ELEMENT":"1738f3ed-9331-46a2-bba0-bd36dd13f7a0"}}]
04:08:36 INFO - 1500721715998 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1738f3ed-9331-46a2-bba0-bd36dd13f7a0"}]
04:08:36 INFO - 1500721716244 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:36 INFO - 1500721716245 Marionette TRACE 382 <- [1,4,null,{}]
04:08:36 INFO - 1500721716247 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}]
04:08:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500721716250 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:08:36 INFO - 1500721716251 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:08:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
04:08:36 INFO - TEST-START | test_click.py TestClick.test_input_file
04:08:36 INFO - 1500721716253 Marionette TRACE 382 <- [1,6,null,{}]
04:08:36 INFO - 1500721716255 Marionette DEBUG Closed connection 382
04:08:36 INFO - 1500721716256 Marionette DEBUG Accepted connection 383 from 127.0.0.1:51394
04:08:36 INFO - 1500721716257 Marionette DEBUG Closed connection 383
04:08:36 INFO - 1500721716258 Marionette DEBUG Accepted connection 384 from 127.0.0.1:51395
04:08:36 INFO - 1500721716259 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716259 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716262 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716266 Marionette TRACE 384 <- [1,1,null,{"sessionId":"6a2090d8-ce71-44a4-a750-cbbf6c2e5cc1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716268 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:08:36 INFO - 1500721716269 Marionette TRACE 384 <- [1,2,null,{}]
04:08:36 INFO - 1500721716270 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:36 INFO - 1500721716270 Marionette TRACE 384 <- [1,3,null,{}]
04:08:36 INFO - 1500721716271 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:36 INFO - 1500721716272 Marionette TRACE 384 <- [1,4,null,{}]
04:08:36 INFO - 1500721716274 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}]
04:08:36 INFO - TEST-START: test_click.py TestClick.test_input_file1500721716277 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:08:36 INFO - 1500721716278 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:08:36 INFO - 1500721716280 Marionette TRACE 384 <- [1,6,null,{}]
04:08:36 INFO - 1500721716282 Marionette DEBUG Closed connection 384
04:08:36 INFO - 1500721716283 Marionette DEBUG Accepted connection 385 from 127.0.0.1:51396
04:08:36 INFO - 1500721716284 Marionette DEBUG Closed connection 385
04:08:36 INFO - 1500721716285 Marionette DEBUG Accepted connection 386 from 127.0.0.1:51397
04:08:36 INFO - 1500721716286 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716287 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716289 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716293 Marionette TRACE 386 <- [1,1,null,{"sessionId":"50f90ca6-fbcd-4fbb-bcfd-7fc6733edf7a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716295 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:08:36 INFO - 1500721716297 Marionette TRACE 386 <- [1,2,null,{}]
04:08:36 INFO - 1500721716299 Marionette DEBUG Closed connection 386
04:08:36 INFO - 1500721716300 Marionette DEBUG Accepted connection 387 from 127.0.0.1:51398
04:08:36 INFO - 1500721716301 Marionette DEBUG Closed connection 387
04:08:36 INFO - 1500721716302 Marionette DEBUG Accepted connection 388 from 127.0.0.1:51399
04:08:36 INFO - 1500721716303 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:36 INFO - 1500721716303 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716306 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716310 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2a2f6e60-1856-411d-85dd-3b5e1d67d18a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716312 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:08:36 INFO - 1500721716314 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"
04:08:36 INFO - 1500721716316 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"
04:08:36 INFO - 1500721716317 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"
04:08:36 INFO - 1500721716340 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:36 INFO - 1500721716346 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:36 INFO - 1500721716350 Marionette TRACE 388 <- [1,2,null,{}]
04:08:36 INFO - 1500721716352 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:08:36 INFO - 1500721716355 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d07236c0-89c3-41bb-8c46-7708675aeace","ELEMENT":"d07236c0-89c3-41bb-8c46-7708675aeace"}}]
04:08:36 INFO - 1500721716364 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d07236c0-89c3-41bb-8c46-7708675aeace"}]
04:08:36 INFO - 1500721716393 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:08:36 INFO - 1500721716395 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}]
04:08:36 INFO - TEST-END: test_click.py TestClick.test_input_file1500721716399 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:08:36 INFO - 1500721716400 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:08:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 148ms
04:08:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:08:36 INFO - 1500721716401 Marionette TRACE 388 <- [1,6,null,{}]
04:08:36 INFO - 1500721716404 Marionette DEBUG Closed connection 388
04:08:36 INFO - 1500721716405 Marionette DEBUG Accepted connection 389 from 127.0.0.1:51400
04:08:36 INFO - 1500721716405 Marionette DEBUG Closed connection 389
04:08:36 INFO - 1500721716406 Marionette DEBUG Accepted connection 390 from 127.0.0.1:51401
04:08:36 INFO - 1500721716407 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716408 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716411 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716415 Marionette TRACE 390 <- [1,1,null,{"sessionId":"74df3845-a871-4fdf-9ba3-22f937f61e1e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716417 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:08:36 INFO - 1500721716418 Marionette TRACE 390 <- [1,2,null,{}]
04:08:36 INFO - 1500721716419 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:36 INFO - 1500721716419 Marionette TRACE 390 <- [1,3,null,{}]
04:08:36 INFO - 1500721716420 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:36 INFO - 1500721716421 Marionette TRACE 390 <- [1,4,null,{}]
04:08:36 INFO - 1500721716423 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}]
04:08:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500721716426 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:08:36 INFO - 1500721716427 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:08:36 INFO - 1500721716428 Marionette TRACE 390 <- [1,6,null,{}]
04:08:36 INFO - 1500721716431 Marionette DEBUG Closed connection 390
04:08:36 INFO - 1500721716431 Marionette DEBUG Accepted connection 391 from 127.0.0.1:51402
04:08:36 INFO - 1500721716432 Marionette DEBUG Closed connection 391
04:08:36 INFO - 1500721716433 Marionette DEBUG Accepted connection 392 from 127.0.0.1:51403
04:08:36 INFO - 1500721716434 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716435 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716438 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716441 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3fb01eed-6cd8-493d-a5be-7848ca8b7681","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716444 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:08:36 INFO - 1500721716445 Marionette TRACE 392 <- [1,2,null,{}]
04:08:36 INFO - 1500721716447 Marionette DEBUG Closed connection 392
04:08:36 INFO - 1500721716448 Marionette DEBUG Accepted connection 393 from 127.0.0.1:51404
04:08:36 INFO - 1500721716449 Marionette DEBUG Closed connection 393
04:08:36 INFO - 1500721716450 Marionette DEBUG Accepted connection 394 from 127.0.0.1:51405
04:08:36 INFO - 1500721716451 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:36 INFO - 1500721716452 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716455 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716465 Marionette TRACE 394 <- [1,1,null,{"sessionId":"356dee1e-bc0b-4043-bc25-25a54fbef714","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716468 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"}]
04:08:36 INFO - 1500721716470 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:36 INFO - 1500721716472 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:36 INFO - 1500721716473 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:08:36 INFO - 1500721716500 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"
04:08:36 INFO - 1500721716501 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"
04:08:36 INFO - 1500721716506 Marionette TRACE 394 <- [1,2,null,{}]
04:08:36 INFO - 1500721716515 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:08:36 INFO - 1500721716518 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b29d8ad5-d0dd-4d67-8d31-1de7bf4e5276","ELEMENT":"b29d8ad5-d0dd-4d67-8d31-1de7bf4e5276"}}]
04:08:36 INFO - 1500721716519 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:08:36 INFO - 1500721716522 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f02fe8f-6fd5-40d0-8d40-e9bbc41a4311","ELEMENT":"7f02fe8f-6fd5-40d0-8d40-e9bbc41a4311"}}]
04:08:36 INFO - 1500721716523 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b29d8ad5-d0dd-4d67-8d31-1de7bf4e5276"}]
04:08:36 INFO - 1500721716766 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:08:36 INFO - 1500721716767 Marionette TRACE 394 <- [1,5,null,{}]
04:08:36 INFO - 1500721716768 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7f02fe8f-6fd5-40d0-8d40-e9bbc41a4311"}]
04:08:36 INFO - 1500721716786 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
04:08:36 INFO - 1500721716788 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:08:36 INFO - 1500721716791 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:08:36 INFO - 1500721716793 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}]
04:08:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500721716796 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:08:36 INFO - 1500721716797 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:08:36 INFO - 1500721716799 Marionette TRACE 394 <- [1,9,null,{}]
04:08:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 397ms
04:08:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:08:36 INFO - 1500721716801 Marionette DEBUG Closed connection 394
04:08:36 INFO - 1500721716802 Marionette DEBUG Accepted connection 395 from 127.0.0.1:51406
04:08:36 INFO - 1500721716803 Marionette DEBUG Closed connection 395
04:08:36 INFO - 1500721716804 Marionette DEBUG Accepted connection 396 from 127.0.0.1:51407
04:08:36 INFO - 1500721716805 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716805 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716808 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716812 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b4fbcc97-3b5d-42df-9f56-f87d86f19ad4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716815 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:08:36 INFO - 1500721716815 Marionette TRACE 396 <- [1,2,null,{}]
04:08:36 INFO - 1500721716816 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:08:36 INFO - 1500721716817 Marionette TRACE 396 <- [1,3,null,{}]
04:08:36 INFO - 1500721716818 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:08:36 INFO - 1500721716818 Marionette TRACE 396 <- [1,4,null,{}]
04:08:36 INFO - 1500721716820 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}]
04:08:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500721716823 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:08:36 INFO - 1500721716824 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:08:36 INFO - 1500721716826 Marionette TRACE 396 <- [1,6,null,{}]
04:08:36 INFO - 1500721716828 Marionette DEBUG Closed connection 396
04:08:36 INFO - 1500721716829 Marionette DEBUG Accepted connection 397 from 127.0.0.1:51408
04:08:36 INFO - 1500721716830 Marionette DEBUG Closed connection 397
04:08:36 INFO - 1500721716831 Marionette DEBUG Accepted connection 398 from 127.0.0.1:51409
04:08:36 INFO - 1500721716832 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:08:36 INFO - 1500721716832 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716835 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716839 Marionette TRACE 398 <- [1,1,null,{"sessionId":"fc3b2c8f-a0bc-4907-bf2e-6a584a0603c6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716841 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:08:36 INFO - 1500721716842 Marionette TRACE 398 <- [1,2,null,{}]
04:08:36 INFO - 1500721716845 Marionette DEBUG Closed connection 398
04:08:36 INFO - 1500721716846 Marionette DEBUG Accepted connection 399 from 127.0.0.1:51410
04:08:36 INFO - 1500721716846 Marionette DEBUG Closed connection 399
04:08:36 INFO - 1500721716847 Marionette DEBUG Accepted connection 400 from 127.0.0.1:51411
04:08:36 INFO - 1500721716848 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:08:36 INFO - 1500721716849 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}
04:08:36 INFO - 1500721716852 Marionette DEBUG Register listener.js for window 15
04:08:36 INFO - 1500721716857 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1615e4e2-b422-493e-a1af-7515959620d3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8bnbhk.mozrunner","moz:accessibilityChecks":false}}]
04:08:36 INFO - 1500721716859 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"}]
04:08:36 INFO - 1500721716861 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"
04:08:36 INFO - 1500721716863 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"
04:08:36 INFO - 1500721716864 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"
04:08:36 INFO - 1500721716887 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"
04:08:36 INFO - 1500721716890 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"
04:08:36 INFO - 1500721716901 Marionette TRACE 400 <- [1,2,null,{}]
04:08:36 INFO - 1500721716903 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:08:36 INFO - 1500721716906 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a9889fd-1056-417b-a7ef-2ee15c489984","ELEMENT":"5a9889fd-1056-417b-a7ef-2ee15c489984"}}]
04:08:36 INFO - 1500721716908 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5a9889fd-1056-417b-a7ef-2ee15c489984"}]
04:08:36 INFO - 1500721716933 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:08:36 INFO - 1500721716935 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5a9889fd-1056-417b-a7ef-2ee15c489984"}]
04:08:36 INFO - 1500721716953 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element