could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
08:47:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 139ms
08:47:54 INFO - 1499701674575 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}]
08:47:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499701674577 Marionette TRACE 352 <- [1,5,null,{"value":null}]
08:47:54 INFO - 1499701674578 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
08:47:54 INFO - 1499701674579 Marionette TRACE 352 <- [1,6,null,{}]
08:47:54 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:47:54 INFO - 1499701674581 Marionette DEBUG Closed connection 352
08:47:54 INFO - 1499701674584 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49995
08:47:54 INFO - 1499701674584 Marionette DEBUG Closed connection 353
08:47:54 INFO - 1499701674585 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49996
08:47:54 INFO - 1499701674586 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:54 INFO - 1499701674587 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674589 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674591 Marionette TRACE 354 <- [1,1,null,{"sessionId":"04f81600-29b8-48cc-aa3a-55978cafe6e8","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674593 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
08:47:54 INFO - 1499701674594 Marionette TRACE 354 <- [1,2,null,{}]
08:47:54 INFO - 1499701674595 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:54 INFO - 1499701674595 Marionette TRACE 354 <- [1,3,null,{}]
08:47:54 INFO - 1499701674596 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:54 INFO - 1499701674596 Marionette TRACE 354 <- [1,4,null,{}]
08:47:54 INFO - 1499701674598 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}]
08:47:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499701674600 Marionette TRACE 354 <- [1,5,null,{"value":null}]
08:47:54 INFO - 1499701674601 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
08:47:54 INFO - 1499701674602 Marionette TRACE 354 <- [1,6,null,{}]
08:47:54 INFO - 1499701674604 Marionette DEBUG Closed connection 354
08:47:54 INFO - 1499701674605 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49997
08:47:54 INFO - 1499701674605 Marionette DEBUG Closed connection 355
08:47:54 INFO - 1499701674606 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49998
08:47:54 INFO - 1499701674607 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:54 INFO - 1499701674608 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674609 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674612 Marionette TRACE 356 <- [1,1,null,{"sessionId":"311b6329-a9c4-4dea-8fd8-da98e81f7ca5","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674614 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
08:47:54 INFO - 1499701674615 Marionette TRACE 356 <- [1,2,null,{}]
08:47:54 INFO - 1499701674617 Marionette DEBUG Closed connection 356
08:47:54 INFO - 1499701674617 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50000
08:47:54 INFO - 1499701674618 Marionette DEBUG Closed connection 357
08:47:54 INFO - 1499701674619 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50001
08:47:54 INFO - 1499701674620 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:54 INFO - 1499701674620 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674622 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674625 Marionette TRACE 358 <- [1,1,null,{"sessionId":"38475b72-ed42-4216-b8cc-ef300f545fe6","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674627 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49570/clicks.html"}]
08:47:54 INFO - 1499701674629 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49570/hidden.html"
08:47:54 INFO - 1499701674634 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49570/hidden.html"
08:47:54 INFO - 1499701674634 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49570/hidden.html"
08:47:54 INFO - 1499701674650 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49570/clicks.html"
08:47:54 INFO - 1499701674655 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49570/clicks.html"
08:47:54 INFO - 1499701674666 Marionette TRACE 358 <- [1,2,null,{}]
08:47:54 INFO - 1499701674668 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:47:54 INFO - 1499701674673 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14a6243e-05b9-4b98-b003-31d8ef37b5c7","ELEMENT":"14a6243e-05b9-4b98-b003-31d8ef37b5c7"}}]
08:47:54 INFO - 1499701674674 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"14a6243e-05b9-4b98-b003-31d8ef37b5c7"}]
08:47:54 INFO - 1499701674712 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49570/clicks.html"
08:47:54 INFO - 1499701674716 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49570/clicks.html"
08:47:54 INFO - 1499701674717 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49570/clicks.html"
08:47:54 INFO - 1499701674734 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49570/test.html"
08:47:54 INFO - 1499701674737 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49570/test.html"
08:47:54 INFO - 1499701674747 Marionette TRACE 358 <- [1,4,null,{}]
08:47:54 INFO - 1499701674748 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:47:54 INFO - 1499701674751 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5332ca3c-b640-40d9-8f54-3926d4a7e7d8","ELEMENT":"5332ca3c-b640-40d9-8f54-3926d4a7e7d8"}}]
08:47:54 INFO - 1499701674751 Marionette TRACE 358 -> [0,6,"getTitle",{}]
08:47:54 INFO - 1499701674752 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
08:47:54 INFO - 1499701674754 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}]
08:47:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499701674758 Marionette TRACE 358 <- [1,7,null,{"value":null}]
08:47:54 INFO - 1499701674758 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
08:47:54 INFO - 1499701674760 Marionette TRACE 358 <- [1,8,null,{}]
08:47:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 179ms
08:47:54 INFO - TEST-START | test_click.py TestClick.test_container_element
08:47:54 INFO - 1499701674761 Marionette DEBUG Closed connection 358
08:47:54 INFO - 1499701674762 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50004
08:47:54 INFO - 1499701674763 Marionette DEBUG Closed connection 359
08:47:54 INFO - 1499701674764 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50005
08:47:54 INFO - 1499701674765 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:54 INFO - 1499701674766 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674768 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674781 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d54e2c70-0098-448e-820e-d8a23d16335d","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674784 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
08:47:54 INFO - 1499701674784 Marionette TRACE 360 <- [1,2,null,{}]
08:47:54 INFO - 1499701674785 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:54 INFO - 1499701674786 Marionette TRACE 360 <- [1,3,null,{}]
08:47:54 INFO - 1499701674787 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:54 INFO - 1499701674788 Marionette TRACE 360 <- [1,4,null,{}]
08:47:54 INFO - 1499701674789 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}]
08:47:54 INFO - TEST-START: test_click.py TestClick.test_container_element1499701674792 Marionette TRACE 360 <- [1,5,null,{"value":null}]
08:47:54 INFO - 1499701674793 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
08:47:54 INFO - 1499701674794 Marionette TRACE 360 <- [1,6,null,{}]
08:47:54 INFO - 1499701674796 Marionette DEBUG Closed connection 360
08:47:54 INFO - 1499701674796 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50006
08:47:54 INFO - 1499701674797 Marionette DEBUG Closed connection 361
08:47:54 INFO - 1499701674798 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50007
08:47:54 INFO - 1499701674799 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:54 INFO - 1499701674799 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674801 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674805 Marionette TRACE 362 <- [1,1,null,{"sessionId":"18c557b0-9fbc-4846-b87b-6a774d1f10fa","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674806 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
08:47:54 INFO - 1499701674807 Marionette TRACE 362 <- [1,2,null,{}]
08:47:54 INFO - 1499701674809 Marionette DEBUG Closed connection 362
08:47:54 INFO - 1499701674810 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50008
08:47:54 INFO - 1499701674810 Marionette DEBUG Closed connection 363
08:47:54 INFO - 1499701674811 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50009
08:47:54 INFO - 1499701674812 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:54 INFO - 1499701674813 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:54 INFO - 1499701674814 Marionette DEBUG Register listener.js for window 12
08:47:54 INFO - 1499701674817 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0da85b08-5078-4b09-8dd0-b9c01ef36633","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:54 INFO - 1499701674820 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"}]
08:47:54 INFO - 1499701674821 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49570/test.html"
08:47:54 INFO - 1499701674823 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49570/test.html"
08:47:54 INFO - 1499701674823 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49570/test.html"
08:47:54 INFO - 1499701674834 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"
08:47:54 INFO - 1499701674843 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"
08:47:54 INFO - 1499701674846 Marionette TRACE 364 <- [1,2,null,{}]
08:47:54 INFO - 1499701674847 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:47:54 INFO - 1499701674849 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b2bf6085-8833-4b3b-8403-7bcc9bd85ba3","ELEMENT":"b2bf6085-8833-4b3b-8403-7bcc9bd85ba3"}}]
08:47:54 INFO - 1499701674850 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b2bf6085-8833-4b3b-8403-7bcc9bd85ba3"}]
08:47:55 INFO - 1499701675087 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:47:55 INFO - 1499701675088 Marionette TRACE 364 <- [1,4,null,{}]
08:47:55 INFO - 1499701675089 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b2bf6085-8833-4b3b-8403-7bcc9bd85ba3","name":"selected"}]
08:47:55 INFO - 1499701675093 Marionette TRACE 364 <- [1,5,null,{"value":true}]
08:47:55 INFO - 1499701675095 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}]
08:47:55 INFO - TEST-END: test_click.py TestClick.test_container_element1499701675097 Marionette TRACE 364 <- [1,6,null,{"value":null}]
08:47:55 INFO - 1499701675098 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
08:47:55 INFO - 1499701675099 Marionette TRACE 364 <- [1,7,null,{}]
08:47:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 340ms
08:47:55 INFO - 1499701675101 Marionette DEBUG Closed connection 364
08:47:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:47:55 INFO - 1499701675103 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50010
08:47:55 INFO - 1499701675104 Marionette DEBUG Closed connection 365
08:47:55 INFO - 1499701675105 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50011
08:47:55 INFO - 1499701675106 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675106 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675108 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675111 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9c4ffcea-c29f-4a79-a7f5-cbda3a2618a7","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675113 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
08:47:55 INFO - 1499701675113 Marionette TRACE 366 <- [1,2,null,{}]
08:47:55 INFO - 1499701675114 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:55 INFO - 1499701675114 Marionette TRACE 366 <- [1,3,null,{}]
08:47:55 INFO - 1499701675115 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:55 INFO - 1499701675115 Marionette TRACE 366 <- [1,4,null,{}]
08:47:55 INFO - 1499701675117 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}]
08:47:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499701675119 Marionette TRACE 366 <- [1,5,null,{"value":null}]
08:47:55 INFO - 1499701675120 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
08:47:55 INFO - 1499701675121 Marionette TRACE 366 <- [1,6,null,{}]
08:47:55 INFO - 1499701675123 Marionette DEBUG Closed connection 366
08:47:55 INFO - 1499701675123 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50012
08:47:55 INFO - 1499701675124 Marionette DEBUG Closed connection 367
08:47:55 INFO - 1499701675125 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50013
08:47:55 INFO - 1499701675126 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675126 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675128 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675131 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e8e09af5-a44f-4338-944a-a6b621c3ef7b","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675133 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
08:47:55 INFO - 1499701675134 Marionette TRACE 368 <- [1,2,null,{}]
08:47:55 INFO - 1499701675138 Marionette DEBUG Closed connection 368
08:47:55 INFO - 1499701675139 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50014
08:47:55 INFO - 1499701675140 Marionette DEBUG Closed connection 369
08:47:55 INFO - 1499701675140 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50015
08:47:55 INFO - 1499701675141 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:55 INFO - 1499701675142 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675144 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675146 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e4ae14b2-96ec-49f9-97ae-fab8eed30657","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675148 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"}]
08:47:55 INFO - 1499701675149 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"
08:47:55 INFO - 1499701675151 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"
08:47:55 INFO - 1499701675152 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"
08:47:55 INFO - 1499701675167 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"
08:47:55 INFO - 1499701675171 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"
08:47:55 INFO - 1499701675179 Marionette TRACE 370 <- [1,2,null,{}]
08:47:55 INFO - 1499701675180 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:47:55 INFO - 1499701675184 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fec2764f-08b8-4995-8ced-49c6455c642c","ELEMENT":"fec2764f-08b8-4995-8ced-49c6455c642c"}}]
08:47:55 INFO - 1499701675184 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fec2764f-08b8-4995-8ced-49c6455c642c"}]
08:47:55 INFO - 1499701675422 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:47:55 INFO - 1499701675423 Marionette TRACE 370 <- [1,4,null,{}]
08:47:55 INFO - 1499701675424 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fec2764f-08b8-4995-8ced-49c6455c642c","name":"selected"}]
08:47:55 INFO - 1499701675428 Marionette TRACE 370 <- [1,5,null,{"value":true}]
08:47:55 INFO - 1499701675429 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}]
08:47:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499701675432 Marionette TRACE 370 <- [1,6,null,{"value":null}]
08:47:55 INFO - 1499701675433 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
08:47:55 INFO - 1499701675434 Marionette TRACE 370 <- [1,7,null,{}]
08:47:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
08:47:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:47:55 INFO - 1499701675436 Marionette DEBUG Closed connection 370
08:47:55 INFO - 1499701675437 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50016
08:47:55 INFO - 1499701675437 Marionette DEBUG Closed connection 371
08:47:55 INFO - 1499701675438 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50017
08:47:55 INFO - 1499701675439 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675440 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675442 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675444 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9d62de19-dcdb-40a7-9946-3b084744481e","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675446 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
08:47:55 INFO - 1499701675447 Marionette TRACE 372 <- [1,2,null,{}]
08:47:55 INFO - 1499701675447 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:55 INFO - 1499701675448 Marionette TRACE 372 <- [1,3,null,{}]
08:47:55 INFO - 1499701675449 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:55 INFO - 1499701675449 Marionette TRACE 372 <- [1,4,null,{}]
08:47:55 INFO - 1499701675451 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}]
08:47:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499701675456 Marionette TRACE 372 <- [1,5,null,{"value":null}]
08:47:55 INFO - 1499701675457 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
08:47:55 INFO - 1499701675459 Marionette TRACE 372 <- [1,6,null,{}]
08:47:55 INFO - 1499701675461 Marionette DEBUG Closed connection 372
08:47:55 INFO - 1499701675461 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50018
08:47:55 INFO - 1499701675462 Marionette DEBUG Closed connection 373
08:47:55 INFO - 1499701675463 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50019
08:47:55 INFO - 1499701675464 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675464 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675473 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675475 Marionette TRACE 374 <- [1,1,null,{"sessionId":"89d039e2-86d4-48af-b98e-ba0e54f2a973","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675477 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
08:47:55 INFO - 1499701675478 Marionette TRACE 374 <- [1,2,null,{}]
08:47:55 INFO - 1499701675480 Marionette DEBUG Closed connection 374
08:47:55 INFO - 1499701675481 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50020
08:47:55 INFO - 1499701675481 Marionette DEBUG Closed connection 375
08:47:55 INFO - 1499701675482 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50021
08:47:55 INFO - 1499701675483 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:55 INFO - 1499701675484 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675486 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675488 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ca570c9c-a0b8-4205-9d9c-632005e8099a","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675490 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"}]
08:47:55 INFO - 1499701675494 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"
08:47:55 INFO - 1499701675497 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"
08:47:55 INFO - 1499701675497 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"
08:47:55 INFO - 1499701675524 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"
08:47:55 INFO - 1499701675528 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"
08:47:55 INFO - 1499701675532 Marionette TRACE 376 <- [1,2,null,{}]
08:47:55 INFO - 1499701675533 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:47:55 INFO - 1499701675535 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25fe3cba-c5a9-4bcc-9245-a3351d8d76c9","ELEMENT":"25fe3cba-c5a9-4bcc-9245-a3351d8d76c9"}}]
08:47:55 INFO - 1499701675542 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"25fe3cba-c5a9-4bcc-9245-a3351d8d76c9"}]
08:47:55 INFO - 1499701675776 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:47:55 INFO - 1499701675777 Marionette TRACE 376 <- [1,4,null,{}]
08:47:55 INFO - 1499701675779 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}]
08:47:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499701675782 Marionette TRACE 376 <- [1,5,null,{"value":null}]
08:47:55 INFO - 1499701675782 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
08:47:55 INFO - 1499701675784 Marionette TRACE 376 <- [1,6,null,{}]
08:47:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
08:47:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:47:55 INFO - 1499701675786 Marionette DEBUG Closed connection 376
08:47:55 INFO - 1499701675786 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50022
08:47:55 INFO - 1499701675787 Marionette DEBUG Closed connection 377
08:47:55 INFO - 1499701675788 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50023
08:47:55 INFO - 1499701675789 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675789 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675791 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675794 Marionette TRACE 378 <- [1,1,null,{"sessionId":"907e90f3-9936-4a67-a21e-e708e7c3e5d2","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675797 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
08:47:55 INFO - 1499701675797 Marionette TRACE 378 <- [1,2,null,{}]
08:47:55 INFO - 1499701675798 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:55 INFO - 1499701675798 Marionette TRACE 378 <- [1,3,null,{}]
08:47:55 INFO - 1499701675799 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:55 INFO - 1499701675799 Marionette TRACE 378 <- [1,4,null,{}]
08:47:55 INFO - 1499701675801 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}]
08:47:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499701675803 Marionette TRACE 378 <- [1,5,null,{"value":null}]
08:47:55 INFO - 1499701675804 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
08:47:55 INFO - 1499701675805 Marionette TRACE 378 <- [1,6,null,{}]
08:47:55 INFO - 1499701675807 Marionette DEBUG Closed connection 378
08:47:55 INFO - 1499701675807 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50024
08:47:55 INFO - 1499701675808 Marionette DEBUG Closed connection 379
08:47:55 INFO - 1499701675809 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50025
08:47:55 INFO - 1499701675810 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:55 INFO - 1499701675810 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675812 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675815 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b88b5860-5884-4413-98f2-02ede6ddea02","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675817 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
08:47:55 INFO - 1499701675818 Marionette TRACE 380 <- [1,2,null,{}]
08:47:55 INFO - 1499701675820 Marionette DEBUG Closed connection 380
08:47:55 INFO - 1499701675821 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50026
08:47:55 INFO - 1499701675821 Marionette DEBUG Closed connection 381
08:47:55 INFO - 1499701675822 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50027
08:47:55 INFO - 1499701675823 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:55 INFO - 1499701675823 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:55 INFO - 1499701675825 Marionette DEBUG Register listener.js for window 12
08:47:55 INFO - 1499701675828 Marionette TRACE 382 <- [1,1,null,{"sessionId":"6979fcb2-9152-4c26-b962-1a04306ea1fb","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:55 INFO - 1499701675830 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"}]
08:47:55 INFO - 1499701675831 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"
08:47:55 INFO - 1499701675833 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"
08:47:55 INFO - 1499701675833 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"
08:47:55 INFO - 1499701675848 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"
08:47:55 INFO - 1499701675852 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"
08:47:55 INFO - 1499701675861 Marionette TRACE 382 <- [1,2,null,{}]
08:47:55 INFO - 1499701675862 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:47:55 INFO - 1499701675864 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8f8b5fd-7e9a-4261-838f-5cca10b1186f","ELEMENT":"d8f8b5fd-7e9a-4261-838f-5cca10b1186f"}}]
08:47:55 INFO - 1499701675865 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d8f8b5fd-7e9a-4261-838f-5cca10b1186f"}]
08:47:56 INFO - 1499701676103 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:47:56 INFO - 1499701676104 Marionette TRACE 382 <- [1,4,null,{}]
08:47:56 INFO - 1499701676106 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}]
08:47:56 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499701676109 Marionette TRACE 382 <- [1,5,null,{"value":null}]
08:47:56 INFO - 1499701676109 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
08:47:56 INFO - 1499701676111 Marionette TRACE 382 <- [1,6,null,{}]
08:47:56 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 327ms
08:47:56 INFO - TEST-START | test_click.py TestClick.test_input_file
08:47:56 INFO - 1499701676112 Marionette DEBUG Closed connection 382
08:47:56 INFO - 1499701676113 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50028
08:47:56 INFO - 1499701676114 Marionette DEBUG Closed connection 383
08:47:56 INFO - 1499701676115 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50029
08:47:56 INFO - 1499701676116 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676116 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676118 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676121 Marionette TRACE 384 <- [1,1,null,{"sessionId":"6f606de8-af1b-436f-9655-a3b214f30362","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676123 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
08:47:56 INFO - 1499701676123 Marionette TRACE 384 <- [1,2,null,{}]
08:47:56 INFO - 1499701676124 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:56 INFO - 1499701676125 Marionette TRACE 384 <- [1,3,null,{}]
08:47:56 INFO - 1499701676125 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:56 INFO - 1499701676126 Marionette TRACE 384 <- [1,4,null,{}]
08:47:56 INFO - 1499701676127 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}]
08:47:56 INFO - TEST-START: test_click.py TestClick.test_input_file1499701676130 Marionette TRACE 384 <- [1,5,null,{"value":null}]
08:47:56 INFO - 1499701676131 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
08:47:56 INFO - 1499701676131 Marionette TRACE 384 <- [1,6,null,{}]
08:47:56 INFO - 1499701676133 Marionette DEBUG Closed connection 384
08:47:56 INFO - 1499701676134 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50030
08:47:56 INFO - 1499701676135 Marionette DEBUG Closed connection 385
08:47:56 INFO - 1499701676135 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50031
08:47:56 INFO - 1499701676136 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676137 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676139 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676148 Marionette TRACE 386 <- [1,1,null,{"sessionId":"56b84043-816a-43b8-950d-96e6aa59aa5c","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676150 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
08:47:56 INFO - 1499701676151 Marionette TRACE 386 <- [1,2,null,{}]
08:47:56 INFO - 1499701676153 Marionette DEBUG Closed connection 386
08:47:56 INFO - 1499701676153 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50032
08:47:56 INFO - 1499701676154 Marionette DEBUG Closed connection 387
08:47:56 INFO - 1499701676155 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50033
08:47:56 INFO - 1499701676156 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:56 INFO - 1499701676156 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676158 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676160 Marionette TRACE 388 <- [1,1,null,{"sessionId":"296c5c83-6b5e-4898-803b-602ca401fcff","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676162 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:47:56 INFO - 1499701676164 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"
08:47:56 INFO - 1499701676166 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"
08:47:56 INFO - 1499701676167 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"
08:47:56 INFO - 1499701676184 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:47:56 INFO - 1499701676188 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:47:56 INFO - 1499701676197 Marionette TRACE 388 <- [1,2,null,{}]
08:47:56 INFO - 1499701676199 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:47:56 INFO - 1499701676201 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2bdd7429-15f9-4e40-ac64-45f737c279ed","ELEMENT":"2bdd7429-15f9-4e40-ac64-45f737c279ed"}}]
08:47:56 INFO - 1499701676203 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2bdd7429-15f9-4e40-ac64-45f737c279ed"}]
08:47:56 INFO - 1499701676227 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
08:47:56 INFO - 1499701676232 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}]
08:47:56 INFO - TEST-END: test_click.py TestClick.test_input_file1499701676235 Marionette TRACE 388 <- [1,5,null,{"value":null}]
08:47:56 INFO - 1499701676236 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
08:47:56 INFO - 1499701676237 Marionette TRACE 388 <- [1,6,null,{}]
08:47:56 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 126ms
08:47:56 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:47:56 INFO - 1499701676238 Marionette DEBUG Closed connection 388
08:47:56 INFO - 1499701676239 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50034
08:47:56 INFO - 1499701676240 Marionette DEBUG Closed connection 389
08:47:56 INFO - 1499701676241 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50035
08:47:56 INFO - 1499701676242 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676242 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676244 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676247 Marionette TRACE 390 <- [1,1,null,{"sessionId":"512df1de-7936-4741-8788-fab7b49fd3cc","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676248 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
08:47:56 INFO - 1499701676249 Marionette TRACE 390 <- [1,2,null,{}]
08:47:56 INFO - 1499701676250 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:56 INFO - 1499701676250 Marionette TRACE 390 <- [1,3,null,{}]
08:47:56 INFO - 1499701676251 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:56 INFO - 1499701676251 Marionette TRACE 390 <- [1,4,null,{}]
08:47:56 INFO - 1499701676253 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}]
08:47:56 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499701676255 Marionette TRACE 390 <- [1,5,null,{"value":null}]
08:47:56 INFO - 1499701676256 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
08:47:56 INFO - 1499701676257 Marionette TRACE 390 <- [1,6,null,{}]
08:47:56 INFO - 1499701676259 Marionette DEBUG Closed connection 390
08:47:56 INFO - 1499701676259 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50036
08:47:56 INFO - 1499701676260 Marionette DEBUG Closed connection 391
08:47:56 INFO - 1499701676261 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50037
08:47:56 INFO - 1499701676262 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676262 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676264 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676266 Marionette TRACE 392 <- [1,1,null,{"sessionId":"7805df18-df53-4ca7-b6ee-3b65270bcada","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676268 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
08:47:56 INFO - 1499701676269 Marionette TRACE 392 <- [1,2,null,{}]
08:47:56 INFO - 1499701676320 Marionette DEBUG Closed connection 392
08:47:56 INFO - 1499701676321 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50038
08:47:56 INFO - 1499701676353 Marionette DEBUG Closed connection 393
08:47:56 INFO - 1499701676354 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50039
08:47:56 INFO - 1499701676355 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:56 INFO - 1499701676355 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676358 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676360 Marionette TRACE 394 <- [1,1,null,{"sessionId":"bf5264df-b965-4313-aee3-af4a1e597235","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676364 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"}]
08:47:56 INFO - 1499701676365 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:47:56 INFO - 1499701676368 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:47:56 INFO - 1499701676368 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:47:56 INFO - 1499701676386 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"
08:47:56 INFO - 1499701676388 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"
08:47:56 INFO - 1499701676397 Marionette TRACE 394 <- [1,2,null,{}]
08:47:56 INFO - 1499701676398 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:47:56 INFO - 1499701676401 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e28e39b-ead5-4766-a8bf-d9cdad97eabd","ELEMENT":"2e28e39b-ead5-4766-a8bf-d9cdad97eabd"}}]
08:47:56 INFO - 1499701676402 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:47:56 INFO - 1499701676404 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3555d3c4-6c46-4a02-b1e5-df0a05bd795e","ELEMENT":"3555d3c4-6c46-4a02-b1e5-df0a05bd795e"}}]
08:47:56 INFO - 1499701676405 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2e28e39b-ead5-4766-a8bf-d9cdad97eabd"}]
08:47:56 INFO - 1499701676640 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:47:56 INFO - 1499701676641 Marionette TRACE 394 <- [1,5,null,{}]
08:47:56 INFO - 1499701676642 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3555d3c4-6c46-4a02-b1e5-df0a05bd795e"}]
08:47:56 INFO - 1499701676656 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
08:47:56 INFO - 1499701676657 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
08:47:56 INFO - 1499701676660 Marionette TRACE 394 <- [1,7,null,{"value":false}]
08:47:56 INFO - 1499701676661 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}]
08:47:56 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499701676664 Marionette TRACE 394 <- [1,8,null,{"value":null}]
08:47:56 INFO - 1499701676665 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
08:47:56 INFO - 1499701676666 Marionette TRACE 394 <- [1,9,null,{}]
08:47:56 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 429ms
08:47:56 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:47:56 INFO - 1499701676667 Marionette DEBUG Closed connection 394
08:47:56 INFO - 1499701676668 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50042
08:47:56 INFO - 1499701676669 Marionette DEBUG Closed connection 395
08:47:56 INFO - 1499701676670 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50043
08:47:56 INFO - 1499701676671 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676671 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676673 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676676 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b13fa9cb-a570-421c-83d7-df1fc7ae5e26","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676678 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
08:47:56 INFO - 1499701676678 Marionette TRACE 396 <- [1,2,null,{}]
08:47:56 INFO - 1499701676679 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:47:56 INFO - 1499701676679 Marionette TRACE 396 <- [1,3,null,{}]
08:47:56 INFO - 1499701676680 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
08:47:56 INFO - 1499701676681 Marionette TRACE 396 <- [1,4,null,{}]
08:47:56 INFO - 1499701676682 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}]
08:47:56 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499701676685 Marionette TRACE 396 <- [1,5,null,{"value":null}]
08:47:56 INFO - 1499701676685 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
08:47:56 INFO - 1499701676686 Marionette TRACE 396 <- [1,6,null,{}]
08:47:56 INFO - 1499701676688 Marionette DEBUG Closed connection 396
08:47:56 INFO - 1499701676689 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50044
08:47:56 INFO - 1499701676689 Marionette DEBUG Closed connection 397
08:47:56 INFO - 1499701676690 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50045
08:47:56 INFO - 1499701676691 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:47:56 INFO - 1499701676691 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676693 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676696 Marionette TRACE 398 <- [1,1,null,{"sessionId":"83072cea-120c-4ba0-8b01-606938f31492","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676698 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
08:47:56 INFO - 1499701676699 Marionette TRACE 398 <- [1,2,null,{}]
08:47:56 INFO - 1499701676700 Marionette DEBUG Closed connection 398
08:47:56 INFO - 1499701676701 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50046
08:47:56 INFO - 1499701676702 Marionette DEBUG Closed connection 399
08:47:56 INFO - 1499701676702 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50047
08:47:56 INFO - 1499701676703 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:47:56 INFO - 1499701676704 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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}
08:47:56 INFO - 1499701676712 Marionette DEBUG Register listener.js for window 12
08:47:56 INFO - 1499701676715 Marionette TRACE 400 <- [1,1,null,{"sessionId":"54d761c4-5de3-41df-8303-8c7ed97b055f","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":3004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnlk1iu.mozrunner","moz:accessibilityChecks":false}}]
08:47:56 INFO - 1499701676717 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"}]
08:47:56 INFO - 1499701676719 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"
08:47:56 INFO - 1499701676721 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"
08:47:56 INFO - 1499701676721 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"
08:47:56 INFO - 1499701676732 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"
08:47:56 INFO - 1499701676735 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"
08:47:56 INFO - 1499701676744 Marionette TRACE 400 <- [1,2,null,{}]
08:47:56 INFO - 1499701676746 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:47:56 INFO - 1499701676749 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ef87543-0b84-460f-8e0e-f7adc655f81a","ELEMENT":"8ef87543-0b84-460f-8e0e-f7adc655f81a"}}]
08:47:56 INFO - 1499701676750 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8ef87543-0b84-460f-8e0e-f7adc655f81a"}]
08:47:56 INFO - 1499701676770 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
08:47:56 INFO - 1499701676771 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8ef87543-0b84-460f-8e0e-f7adc655f81a"}]
08:47:56 INFO - 1499701676784 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element