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]
09:44:27 INFO - 1500137067433 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}]
09:44:27 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500137067435 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:44:27 INFO - 1500137067436 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:44:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 145ms
09:44:27 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:44:27 INFO - 1500137067437 Marionette TRACE 352 <- [1,6,null,{}]
09:44:27 INFO - 1500137067438 Marionette DEBUG Closed connection 352
09:44:27 INFO - 1500137067439 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49755
09:44:27 INFO - 1500137067440 Marionette DEBUG Closed connection 353
09:44:27 INFO - 1500137067440 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49756
09:44:27 INFO - 1500137067441 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067441 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067443 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067446 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ce698e24-74c4-4524-9824-83b35c855dfe","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067447 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:44:27 INFO - 1500137067448 Marionette TRACE 354 <- [1,2,null,{}]
09:44:27 INFO - 1500137067449 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:27 INFO - 1500137067449 Marionette TRACE 354 <- [1,3,null,{}]
09:44:27 INFO - 1500137067450 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:27 INFO - 1500137067451 Marionette TRACE 354 <- [1,4,null,{}]
09:44:27 INFO - 1500137067452 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}]
09:44:27 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500137067454 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:44:27 INFO - 1500137067455 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:44:27 INFO - 1500137067456 Marionette TRACE 354 <- [1,6,null,{}]
09:44:27 INFO - 1500137067458 Marionette DEBUG Closed connection 354
09:44:27 INFO - 1500137067458 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49757
09:44:27 INFO - 1500137067459 Marionette DEBUG Closed connection 355
09:44:27 INFO - 1500137067460 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49758
09:44:27 INFO - 1500137067460 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067461 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067463 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067465 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a69ed3cd-548e-42d5-9b43-b69cf13e9b5d","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067467 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:44:27 INFO - 1500137067468 Marionette TRACE 356 <- [1,2,null,{}]
09:44:27 INFO - 1500137067469 Marionette DEBUG Closed connection 356
09:44:27 INFO - 1500137067470 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49759
09:44:27 INFO - 1500137067471 Marionette DEBUG Closed connection 357
09:44:27 INFO - 1500137067471 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49760
09:44:27 INFO - 1500137067472 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:27 INFO - 1500137067472 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067474 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067477 Marionette TRACE 358 <- [1,1,null,{"sessionId":"cd47c204-214d-4c83-9727-77fe0d07229e","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067479 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49323/clicks.html"}]
09:44:27 INFO - 1500137067480 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/hidden.html"
09:44:27 INFO - 1500137067485 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/hidden.html"
09:44:27 INFO - 1500137067485 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/hidden.html"
09:44:27 INFO - 1500137067496 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49323/clicks.html"
09:44:27 INFO - 1500137067500 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49323/clicks.html"
09:44:27 INFO - 1500137067508 Marionette TRACE 358 <- [1,2,null,{}]
09:44:27 INFO - 1500137067509 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:44:27 INFO - 1500137067511 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eab5908e-444a-4cd8-9a8d-e4096cc8ffa8","ELEMENT":"eab5908e-444a-4cd8-9a8d-e4096cc8ffa8"}}]
09:44:27 INFO - 1500137067512 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"eab5908e-444a-4cd8-9a8d-e4096cc8ffa8"}]
09:44:27 INFO - 1500137067554 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/clicks.html"
09:44:27 INFO - 1500137067558 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/clicks.html"
09:44:27 INFO - 1500137067558 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/clicks.html"
09:44:27 INFO - 1500137067575 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49323/test.html"
09:44:27 INFO - 1500137067576 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49323/test.html"
09:44:27 INFO - 1500137067578 Marionette TRACE 358 <- [1,4,null,{}]
09:44:27 INFO - 1500137067579 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:44:27 INFO - 1500137067580 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6722806c-0a7a-4ff6-8f76-4a46e904ddbd","ELEMENT":"6722806c-0a7a-4ff6-8f76-4a46e904ddbd"}}]
09:44:27 INFO - 1500137067582 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:44:27 INFO - 1500137067582 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:44:27 INFO - 1500137067590 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}]
09:44:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500137067592 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:44:27 INFO - 1500137067593 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:44:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 157ms
09:44:27 INFO - 1500137067594 Marionette TRACE 358 <- [1,8,null,{}]
09:44:27 INFO - TEST-START | test_click.py TestClick.test_container_element
09:44:27 INFO - 1500137067595 Marionette DEBUG Closed connection 358
09:44:27 INFO - 1500137067596 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49763
09:44:27 INFO - 1500137067597 Marionette DEBUG Closed connection 359
09:44:27 INFO - 1500137067597 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49764
09:44:27 INFO - 1500137067598 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067599 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067601 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067604 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b1269696-aac3-4f1f-9e4c-4ba38a7ce47e","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067607 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:44:27 INFO - 1500137067607 Marionette TRACE 360 <- [1,2,null,{}]
09:44:27 INFO - 1500137067608 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:27 INFO - 1500137067608 Marionette TRACE 360 <- [1,3,null,{}]
09:44:27 INFO - 1500137067609 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:27 INFO - 1500137067609 Marionette TRACE 360 <- [1,4,null,{}]
09:44:27 INFO - 1500137067611 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}]
09:44:27 INFO - TEST-START: test_click.py TestClick.test_container_element1500137067613 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:44:27 INFO - 1500137067614 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:44:27 INFO - 1500137067615 Marionette TRACE 360 <- [1,6,null,{}]
09:44:27 INFO - 1500137067618 Marionette DEBUG Closed connection 360
09:44:27 INFO - 1500137067618 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49765
09:44:27 INFO - 1500137067619 Marionette DEBUG Closed connection 361
09:44:27 INFO - 1500137067619 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49766
09:44:27 INFO - 1500137067620 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067621 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067623 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067626 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d33ec492-08ad-4120-bbb3-f8bdbe132bd1","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067627 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:44:27 INFO - 1500137067628 Marionette TRACE 362 <- [1,2,null,{}]
09:44:27 INFO - 1500137067630 Marionette DEBUG Closed connection 362
09:44:27 INFO - 1500137067631 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49767
09:44:27 INFO - 1500137067631 Marionette DEBUG Closed connection 363
09:44:27 INFO - 1500137067632 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49768
09:44:27 INFO - 1500137067634 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:27 INFO - 1500137067635 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067636 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067639 Marionette TRACE 364 <- [1,1,null,{"sessionId":"16ddcf64-1347-4372-a786-aabb8da28f88","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067641 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"}]
09:44:27 INFO - 1500137067642 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49323/test.html"
09:44:27 INFO - 1500137067643 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49323/test.html"
09:44:27 INFO - 1500137067644 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49323/test.html"
09:44:27 INFO - 1500137067663 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"
09:44:27 INFO - 1500137067666 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"
09:44:27 INFO - 1500137067674 Marionette TRACE 364 <- [1,2,null,{}]
09:44:27 INFO - 1500137067676 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:44:27 INFO - 1500137067678 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"825dcf88-5be0-4754-9e06-d0fd4f33c3d3","ELEMENT":"825dcf88-5be0-4754-9e06-d0fd4f33c3d3"}}]
09:44:27 INFO - 1500137067679 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"825dcf88-5be0-4754-9e06-d0fd4f33c3d3"}]
09:44:27 INFO - 1500137067915 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:44:27 INFO - 1500137067916 Marionette TRACE 364 <- [1,4,null,{}]
09:44:27 INFO - 1500137067917 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"825dcf88-5be0-4754-9e06-d0fd4f33c3d3","name":"selected"}]
09:44:27 INFO - 1500137067921 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:44:27 INFO - 1500137067922 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}]
09:44:27 INFO - TEST-END: test_click.py TestClick.test_container_element1500137067924 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:44:27 INFO - 1500137067925 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:44:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 332ms
09:44:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:44:27 INFO - 1500137067926 Marionette TRACE 364 <- [1,7,null,{}]
09:44:27 INFO - 1500137067928 Marionette DEBUG Closed connection 364
09:44:27 INFO - 1500137067929 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49769
09:44:27 INFO - 1500137067930 Marionette DEBUG Closed connection 365
09:44:27 INFO - 1500137067930 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49770
09:44:27 INFO - 1500137067931 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067932 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067934 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067936 Marionette TRACE 366 <- [1,1,null,{"sessionId":"40721a81-6f44-449b-b400-cb2c9ad0493a","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067938 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:44:27 INFO - 1500137067938 Marionette TRACE 366 <- [1,2,null,{}]
09:44:27 INFO - 1500137067939 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:27 INFO - 1500137067939 Marionette TRACE 366 <- [1,3,null,{}]
09:44:27 INFO - 1500137067940 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:27 INFO - 1500137067941 Marionette TRACE 366 <- [1,4,null,{}]
09:44:27 INFO - 1500137067942 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}]
09:44:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500137067944 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:44:27 INFO - 1500137067945 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:44:27 INFO - 1500137067946 Marionette TRACE 366 <- [1,6,null,{}]
09:44:27 INFO - 1500137067948 Marionette DEBUG Closed connection 366
09:44:27 INFO - 1500137067948 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49771
09:44:27 INFO - 1500137067949 Marionette DEBUG Closed connection 367
09:44:27 INFO - 1500137067950 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49772
09:44:27 INFO - 1500137067951 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:27 INFO - 1500137067951 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067953 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067956 Marionette TRACE 368 <- [1,1,null,{"sessionId":"02a4d7f5-e363-4ec3-8649-29090a531a38","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067959 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:44:27 INFO - 1500137067960 Marionette TRACE 368 <- [1,2,null,{}]
09:44:27 INFO - 1500137067961 Marionette DEBUG Closed connection 368
09:44:27 INFO - 1500137067962 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49773
09:44:27 INFO - 1500137067962 Marionette DEBUG Closed connection 369
09:44:27 INFO - 1500137067963 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49774
09:44:27 INFO - 1500137067964 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:27 INFO - 1500137067965 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:27 INFO - 1500137067967 Marionette DEBUG Register listener.js for window 15
09:44:27 INFO - 1500137067970 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a1c72dbc-269b-473b-bbbd-5335a6336ed1","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:27 INFO - 1500137067972 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"}]
09:44:27 INFO - 1500137067973 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"
09:44:27 INFO - 1500137067975 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"
09:44:27 INFO - 1500137067975 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"
09:44:28 INFO - 1500137067998 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"
09:44:28 INFO - 1500137068004 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"
09:44:28 INFO - 1500137068006 Marionette TRACE 370 <- [1,2,null,{}]
09:44:28 INFO - 1500137068007 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:44:28 INFO - 1500137068009 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af5a0419-6af5-4526-a317-b00d9e67db75","ELEMENT":"af5a0419-6af5-4526-a317-b00d9e67db75"}}]
09:44:28 INFO - 1500137068010 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"af5a0419-6af5-4526-a317-b00d9e67db75"}]
09:44:28 INFO - 1500137068250 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:44:28 INFO - 1500137068251 Marionette TRACE 370 <- [1,4,null,{}]
09:44:28 INFO - 1500137068252 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"af5a0419-6af5-4526-a317-b00d9e67db75","name":"selected"}]
09:44:28 INFO - 1500137068256 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:44:28 INFO - 1500137068257 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}]
09:44:28 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500137068259 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:44:28 INFO - 1500137068260 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:44:28 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 334ms
09:44:28 INFO - 1500137068261 Marionette TRACE 370 <- [1,7,null,{}]
09:44:28 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:44:28 INFO - 1500137068263 Marionette DEBUG Closed connection 370
09:44:28 INFO - 1500137068264 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49775
09:44:28 INFO - 1500137068265 Marionette DEBUG Closed connection 371
09:44:28 INFO - 1500137068265 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49776
09:44:28 INFO - 1500137068266 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068267 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068269 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068271 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a5291a2d-a872-48ae-9595-9cbe58ac1d0d","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068282 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:44:28 INFO - 1500137068283 Marionette TRACE 372 <- [1,2,null,{}]
09:44:28 INFO - 1500137068285 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:28 INFO - 1500137068285 Marionette TRACE 372 <- [1,3,null,{}]
09:44:28 INFO - 1500137068286 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:28 INFO - 1500137068286 Marionette TRACE 372 <- [1,4,null,{}]
09:44:28 INFO - 1500137068288 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}]
09:44:28 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500137068290 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:44:28 INFO - 1500137068291 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:44:28 INFO - 1500137068292 Marionette TRACE 372 <- [1,6,null,{}]
09:44:28 INFO - 1500137068294 Marionette DEBUG Closed connection 372
09:44:28 INFO - 1500137068294 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49777
09:44:28 INFO - 1500137068295 Marionette DEBUG Closed connection 373
09:44:28 INFO - 1500137068296 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49778
09:44:28 INFO - 1500137068296 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068297 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068299 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068301 Marionette TRACE 374 <- [1,1,null,{"sessionId":"aae53343-de88-4348-993a-8894316191b8","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068303 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:44:28 INFO - 1500137068304 Marionette TRACE 374 <- [1,2,null,{}]
09:44:28 INFO - 1500137068305 Marionette DEBUG Closed connection 374
09:44:28 INFO - 1500137068306 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49779
09:44:28 INFO - 1500137068307 Marionette DEBUG Closed connection 375
09:44:28 INFO - 1500137068307 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49780
09:44:28 INFO - 1500137068308 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:28 INFO - 1500137068309 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068310 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068313 Marionette TRACE 376 <- [1,1,null,{"sessionId":"41c4bd79-0d38-4e11-bbc6-84b34ab2be1c","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068314 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"}]
09:44:28 INFO - 1500137068316 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"
09:44:28 INFO - 1500137068317 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"
09:44:28 INFO - 1500137068318 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"
09:44:28 INFO - 1500137068327 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"
09:44:28 INFO - 1500137068330 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"
09:44:28 INFO - 1500137068333 Marionette TRACE 376 <- [1,2,null,{}]
09:44:28 INFO - 1500137068339 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:44:28 INFO - 1500137068341 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e286df8b-ad7b-4d74-8193-975514bbb228","ELEMENT":"e286df8b-ad7b-4d74-8193-975514bbb228"}}]
09:44:28 INFO - 1500137068342 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e286df8b-ad7b-4d74-8193-975514bbb228"}]
09:44:28 INFO - 1500137068582 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:44:28 INFO - 1500137068582 Marionette TRACE 376 <- [1,4,null,{}]
09:44:28 INFO - 1500137068584 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}]
09:44:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500137068586 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:44:28 INFO - 1500137068587 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:44:28 INFO - 1500137068588 Marionette TRACE 376 <- [1,6,null,{}]
09:44:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
09:44:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:44:28 INFO - 1500137068590 Marionette DEBUG Closed connection 376
09:44:28 INFO - 1500137068590 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49781
09:44:28 INFO - 1500137068591 Marionette DEBUG Closed connection 377
09:44:28 INFO - 1500137068591 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49782
09:44:28 INFO - 1500137068592 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068593 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068595 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068597 Marionette TRACE 378 <- [1,1,null,{"sessionId":"96733ba3-02ca-4662-8a54-664024f7ef88","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068599 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:44:28 INFO - 1500137068600 Marionette TRACE 378 <- [1,2,null,{}]
09:44:28 INFO - 1500137068600 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:28 INFO - 1500137068601 Marionette TRACE 378 <- [1,3,null,{}]
09:44:28 INFO - 1500137068602 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:28 INFO - 1500137068602 Marionette TRACE 378 <- [1,4,null,{}]
09:44:28 INFO - 1500137068604 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}]
09:44:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500137068605 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:44:28 INFO - 1500137068606 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:44:28 INFO - 1500137068607 Marionette TRACE 378 <- [1,6,null,{}]
09:44:28 INFO - 1500137068609 Marionette DEBUG Closed connection 378
09:44:28 INFO - 1500137068609 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49783
09:44:28 INFO - 1500137068610 Marionette DEBUG Closed connection 379
09:44:28 INFO - 1500137068611 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49784
09:44:28 INFO - 1500137068612 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068612 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068614 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068616 Marionette TRACE 380 <- [1,1,null,{"sessionId":"689b6e1c-0b13-4457-8b6d-0f74c6e4aac6","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068618 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:44:28 INFO - 1500137068619 Marionette TRACE 380 <- [1,2,null,{}]
09:44:28 INFO - 1500137068620 Marionette DEBUG Closed connection 380
09:44:28 INFO - 1500137068621 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49785
09:44:28 INFO - 1500137068622 Marionette DEBUG Closed connection 381
09:44:28 INFO - 1500137068622 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49786
09:44:28 INFO - 1500137068623 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:28 INFO - 1500137068624 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068625 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068628 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7591df5a-8aa3-460e-84b7-80dc809ffacf","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068629 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"}]
09:44:28 INFO - 1500137068630 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"
09:44:28 INFO - 1500137068632 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"
09:44:28 INFO - 1500137068632 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"
09:44:28 INFO - 1500137068641 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"
09:44:28 INFO - 1500137068644 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"
09:44:28 INFO - 1500137068646 Marionette TRACE 382 <- [1,2,null,{}]
09:44:28 INFO - 1500137068648 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:44:28 INFO - 1500137068657 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"293e6001-b5d8-46c8-b1e8-f0cb3f8f9f87","ELEMENT":"293e6001-b5d8-46c8-b1e8-f0cb3f8f9f87"}}]
09:44:28 INFO - 1500137068659 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"293e6001-b5d8-46c8-b1e8-f0cb3f8f9f87"}]
09:44:28 INFO - 1500137068894 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:44:28 INFO - 1500137068895 Marionette TRACE 382 <- [1,4,null,{}]
09:44:28 INFO - 1500137068897 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}]
09:44:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500137068899 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:44:28 INFO - 1500137068900 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:44:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 312ms
09:44:28 INFO - TEST-START | test_click.py TestClick.test_input_file
09:44:28 INFO - 1500137068901 Marionette TRACE 382 <- [1,6,null,{}]
09:44:28 INFO - 1500137068902 Marionette DEBUG Closed connection 382
09:44:28 INFO - 1500137068903 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49787
09:44:28 INFO - 1500137068904 Marionette DEBUG Closed connection 383
09:44:28 INFO - 1500137068905 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49788
09:44:28 INFO - 1500137068906 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068906 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068908 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068910 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a509c07c-40f8-458c-817b-803c97540b4a","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068912 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:44:28 INFO - 1500137068912 Marionette TRACE 384 <- [1,2,null,{}]
09:44:28 INFO - 1500137068913 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:28 INFO - 1500137068913 Marionette TRACE 384 <- [1,3,null,{}]
09:44:28 INFO - 1500137068914 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:28 INFO - 1500137068914 Marionette TRACE 384 <- [1,4,null,{}]
09:44:28 INFO - 1500137068916 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}]
09:44:28 INFO - TEST-START: test_click.py TestClick.test_input_file1500137068918 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:44:28 INFO - 1500137068919 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:44:28 INFO - 1500137068920 Marionette TRACE 384 <- [1,6,null,{}]
09:44:28 INFO - 1500137068921 Marionette DEBUG Closed connection 384
09:44:28 INFO - 1500137068922 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49789
09:44:28 INFO - 1500137068922 Marionette DEBUG Closed connection 385
09:44:28 INFO - 1500137068923 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49790
09:44:28 INFO - 1500137068924 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:28 INFO - 1500137068925 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068927 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068929 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4c81f798-0953-404b-9dbe-ed4fbc250ec4","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068931 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:44:28 INFO - 1500137068932 Marionette TRACE 386 <- [1,2,null,{}]
09:44:28 INFO - 1500137068933 Marionette DEBUG Closed connection 386
09:44:28 INFO - 1500137068934 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49791
09:44:28 INFO - 1500137068934 Marionette DEBUG Closed connection 387
09:44:28 INFO - 1500137068935 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49792
09:44:28 INFO - 1500137068936 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:28 INFO - 1500137068937 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:28 INFO - 1500137068938 Marionette DEBUG Register listener.js for window 15
09:44:28 INFO - 1500137068941 Marionette TRACE 388 <- [1,1,null,{"sessionId":"060b958e-7529-4ede-9ab0-18cb6505f9d2","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:28 INFO - 1500137068942 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:44:28 INFO - 1500137068943 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"
09:44:28 INFO - 1500137068945 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"
09:44:28 INFO - 1500137068945 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"
09:44:28 INFO - 1500137068961 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:44:28 INFO - 1500137068963 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:44:28 INFO - 1500137068966 Marionette TRACE 388 <- [1,2,null,{}]
09:44:28 INFO - 1500137068972 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:44:28 INFO - 1500137068974 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77a6d370-0ad8-403f-bfc1-a895e0a37bf7","ELEMENT":"77a6d370-0ad8-403f-bfc1-a895e0a37bf7"}}]
09:44:28 INFO - 1500137068975 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"77a6d370-0ad8-403f-bfc1-a895e0a37bf7"}]
09:44:29 INFO - 1500137069005 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]
09:44:29 INFO - 1500137069007 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}]
09:44:29 INFO - TEST-END: test_click.py TestClick.test_input_file1500137069009 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:44:29 INFO - 1500137069010 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:44:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
09:44:29 INFO - 1500137069011 Marionette TRACE 388 <- [1,6,null,{}]
09:44:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:44:29 INFO - 1500137069013 Marionette DEBUG Closed connection 388
09:44:29 INFO - 1500137069014 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49793
09:44:29 INFO - 1500137069016 Marionette DEBUG Closed connection 389
09:44:29 INFO - 1500137069016 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49794
09:44:29 INFO - 1500137069017 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:29 INFO - 1500137069017 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069019 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069022 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1162854f-d6e4-4e9d-bb62-b8bc0e5f60f8","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069024 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:44:29 INFO - 1500137069024 Marionette TRACE 390 <- [1,2,null,{}]
09:44:29 INFO - 1500137069025 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:29 INFO - 1500137069025 Marionette TRACE 390 <- [1,3,null,{}]
09:44:29 INFO - 1500137069026 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:29 INFO - 1500137069026 Marionette TRACE 390 <- [1,4,null,{}]
09:44:29 INFO - 1500137069028 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}]
09:44:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500137069030 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:44:29 INFO - 1500137069030 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:44:29 INFO - 1500137069032 Marionette TRACE 390 <- [1,6,null,{}]
09:44:29 INFO - 1500137069033 Marionette DEBUG Closed connection 390
09:44:29 INFO - 1500137069034 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49795
09:44:29 INFO - 1500137069034 Marionette DEBUG Closed connection 391
09:44:29 INFO - 1500137069035 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49796
09:44:29 INFO - 1500137069036 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:29 INFO - 1500137069036 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069038 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069041 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8bbfd1ab-b926-4bf6-bc60-04adac9253f4","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069043 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:44:29 INFO - 1500137069044 Marionette TRACE 392 <- [1,2,null,{}]
09:44:29 INFO - 1500137069045 Marionette DEBUG Closed connection 392
09:44:29 INFO - 1500137069046 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49797
09:44:29 INFO - 1500137069046 Marionette DEBUG Closed connection 393
09:44:29 INFO - 1500137069047 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49798
09:44:29 INFO - 1500137069048 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:29 INFO - 1500137069048 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069050 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069053 Marionette TRACE 394 <- [1,1,null,{"sessionId":"dc27f9c6-1160-42b3-9e9a-d444655c90ba","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069055 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"}]
09:44:29 INFO - 1500137069056 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:44:29 INFO - 1500137069057 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:44:29 INFO - 1500137069058 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:44:29 INFO - 1500137069074 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"
09:44:29 INFO - 1500137069076 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"
09:44:29 INFO - 1500137069078 Marionette TRACE 394 <- [1,2,null,{}]
09:44:29 INFO - 1500137069079 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:44:29 INFO - 1500137069081 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db8dccc7-8036-41f9-8518-fd6ca3831996","ELEMENT":"db8dccc7-8036-41f9-8518-fd6ca3831996"}}]
09:44:29 INFO - 1500137069081 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:44:29 INFO - 1500137069088 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9767ee69-d6cc-4d82-902e-fbbc5d808069","ELEMENT":"9767ee69-d6cc-4d82-902e-fbbc5d808069"}}]
09:44:29 INFO - 1500137069089 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"db8dccc7-8036-41f9-8518-fd6ca3831996"}]
09:44:29 INFO - 1500137069322 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:44:29 INFO - 1500137069322 Marionette TRACE 394 <- [1,5,null,{}]
09:44:29 INFO - 1500137069323 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9767ee69-d6cc-4d82-902e-fbbc5d808069"}]
09:44:29 INFO - 1500137069336 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]
09:44:29 INFO - 1500137069338 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:44:29 INFO - 1500137069340 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:44:29 INFO - 1500137069342 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}]
09:44:29 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500137069344 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:44:29 INFO - 1500137069344 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:44:29 INFO - 1500137069346 Marionette TRACE 394 <- [1,9,null,{}]
09:44:29 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 334ms
09:44:29 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:44:29 INFO - 1500137069347 Marionette DEBUG Closed connection 394
09:44:29 INFO - 1500137069349 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49799
09:44:29 INFO - 1500137069349 Marionette DEBUG Closed connection 395
09:44:29 INFO - 1500137069350 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49800
09:44:29 INFO - 1500137069351 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:29 INFO - 1500137069351 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069353 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069362 Marionette TRACE 396 <- [1,1,null,{"sessionId":"779ffe21-8b39-4945-aa99-9065b9a34f2c","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069364 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:44:29 INFO - 1500137069364 Marionette TRACE 396 <- [1,2,null,{}]
09:44:29 INFO - 1500137069365 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:44:29 INFO - 1500137069366 Marionette TRACE 396 <- [1,3,null,{}]
09:44:29 INFO - 1500137069367 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:44:29 INFO - 1500137069367 Marionette TRACE 396 <- [1,4,null,{}]
09:44:29 INFO - 1500137069368 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}]
09:44:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500137069370 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:44:29 INFO - 1500137069371 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:44:29 INFO - 1500137069372 Marionette TRACE 396 <- [1,6,null,{}]
09:44:29 INFO - 1500137069374 Marionette DEBUG Closed connection 396
09:44:29 INFO - 1500137069374 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49801
09:44:29 INFO - 1500137069375 Marionette DEBUG Closed connection 397
09:44:29 INFO - 1500137069376 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49802
09:44:29 INFO - 1500137069377 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:44:29 INFO - 1500137069377 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069379 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069381 Marionette TRACE 398 <- [1,1,null,{"sessionId":"17020792-6b22-4de9-bcf8-8dab12cd3029","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069383 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:44:29 INFO - 1500137069384 Marionette TRACE 398 <- [1,2,null,{}]
09:44:29 INFO - 1500137069386 Marionette DEBUG Closed connection 398
09:44:29 INFO - 1500137069386 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49803
09:44:29 INFO - 1500137069387 Marionette DEBUG Closed connection 399
09:44:29 INFO - 1500137069388 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49804
09:44:29 INFO - 1500137069388 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:44:29 INFO - 1500137069389 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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}
09:44:29 INFO - 1500137069391 Marionette DEBUG Register listener.js for window 15
09:44:29 INFO - 1500137069393 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ad9de1ca-3d55-4d81-8829-49b1ce4a6415","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":2132,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-046.001\\AppData\\Local\\Temp\\tmpzcsodm.mozrunner","moz:accessibilityChecks":false}}]
09:44:29 INFO - 1500137069395 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"}]
09:44:29 INFO - 1500137069396 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"
09:44:29 INFO - 1500137069398 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"
09:44:29 INFO - 1500137069398 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"
09:44:29 INFO - 1500137069415 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"
09:44:29 INFO - 1500137069418 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"
09:44:29 INFO - 1500137069425 Marionette TRACE 400 <- [1,2,null,{}]
09:44:29 INFO - 1500137069426 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:44:29 INFO - 1500137069428 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"965b4431-c2ac-4d68-bc35-fe2a77acc456","ELEMENT":"965b4431-c2ac-4d68-bc35-fe2a77acc456"}}]
09:44:29 INFO - 1500137069429 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"965b4431-c2ac-4d68-bc35-fe2a77acc456"}]
09:44:29 INFO - 1500137069449 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:44:29 INFO - 1500137069451 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"965b4431-c2ac-4d68-bc35-fe2a77acc456"}]
09:44:29 INFO - 1500137069463 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element