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]
14:14:44 INFO - 1499721284531 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}]
14:14:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499721284534 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:14:44 INFO - 1499721284535 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:14:44 INFO - 1499721284536 Marionette TRACE 352 <- [1,6,null,{}]
14:14:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 130ms
14:14:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:14:44 INFO - 1499721284538 Marionette DEBUG Closed connection 352
14:14:44 INFO - 1499721284539 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49808
14:14:44 INFO - 1499721284539 Marionette DEBUG Closed connection 353
14:14:44 INFO - 1499721284540 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49809
14:14:44 INFO - 1499721284541 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:44 INFO - 1499721284542 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284544 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284547 Marionette TRACE 354 <- [1,1,null,{"sessionId":"cb9003d9-1364-403f-8fca-b370b4a2dbf5","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284548 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:14:44 INFO - 1499721284548 Marionette TRACE 354 <- [1,2,null,{}]
14:14:44 INFO - 1499721284549 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:44 INFO - 1499721284550 Marionette TRACE 354 <- [1,3,null,{}]
14:14:44 INFO - 1499721284551 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:44 INFO - 1499721284551 Marionette TRACE 354 <- [1,4,null,{}]
14:14:44 INFO - 1499721284553 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}]
14:14:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499721284555 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:14:44 INFO - 1499721284556 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:14:44 INFO - 1499721284557 Marionette TRACE 354 <- [1,6,null,{}]
14:14:44 INFO - 1499721284558 Marionette DEBUG Closed connection 354
14:14:44 INFO - 1499721284559 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49810
14:14:44 INFO - 1499721284560 Marionette DEBUG Closed connection 355
14:14:44 INFO - 1499721284560 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49811
14:14:44 INFO - 1499721284561 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:44 INFO - 1499721284562 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284564 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284566 Marionette TRACE 356 <- [1,1,null,{"sessionId":"13f2cb97-e4b5-4afd-a303-209bf1f9505c","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284567 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:14:44 INFO - 1499721284569 Marionette TRACE 356 <- [1,2,null,{}]
14:14:44 INFO - 1499721284570 Marionette DEBUG Closed connection 356
14:14:44 INFO - 1499721284571 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49812
14:14:44 INFO - 1499721284571 Marionette DEBUG Closed connection 357
14:14:44 INFO - 1499721284572 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49813
14:14:44 INFO - 1499721284573 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:44 INFO - 1499721284574 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284576 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284578 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0ea23752-c557-4a50-ae44-fa322b7e089b","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284580 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49369/clicks.html"}]
14:14:44 INFO - 1499721284581 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49369/hidden.html"
14:14:44 INFO - 1499721284588 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49369/hidden.html"
14:14:44 INFO - 1499721284588 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49369/hidden.html"
14:14:44 INFO - 1499721284598 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49369/clicks.html"
14:14:44 INFO - 1499721284599 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49369/clicks.html"
14:14:44 INFO - 1499721284602 Marionette TRACE 358 <- [1,2,null,{}]
14:14:44 INFO - 1499721284606 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:14:44 INFO - 1499721284609 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"64418905-a721-4d43-a0b5-5e5ecb8aae41","ELEMENT":"64418905-a721-4d43-a0b5-5e5ecb8aae41"}}]
14:14:44 INFO - 1499721284610 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"64418905-a721-4d43-a0b5-5e5ecb8aae41"}]
14:14:44 INFO - 1499721284653 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49369/clicks.html"
14:14:44 INFO - 1499721284661 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49369/clicks.html"
14:14:44 INFO - 1499721284661 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49369/clicks.html"
14:14:44 INFO - 1499721284668 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49369/test.html"
14:14:44 INFO - 1499721284671 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49369/test.html"
14:14:44 INFO - 1499721284685 Marionette TRACE 358 <- [1,4,null,{}]
14:14:44 INFO - 1499721284692 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:14:44 INFO - 1499721284698 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"26a218d9-c2dc-4625-87f7-0bb5a4e1c49a","ELEMENT":"26a218d9-c2dc-4625-87f7-0bb5a4e1c49a"}}]
14:14:44 INFO - 1499721284699 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:14:44 INFO - 1499721284700 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:14:44 INFO - 1499721284702 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}]
14:14:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499721284704 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:14:44 INFO - 1499721284705 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:14:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 170ms
14:14:44 INFO - TEST-START | test_click.py TestClick.test_container_element
14:14:44 INFO - 1499721284706 Marionette TRACE 358 <- [1,8,null,{}]
14:14:44 INFO - 1499721284708 Marionette DEBUG Closed connection 358
14:14:44 INFO - 1499721284709 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49816
14:14:44 INFO - 1499721284709 Marionette DEBUG Closed connection 359
14:14:44 INFO - 1499721284710 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49817
14:14:44 INFO - 1499721284711 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:44 INFO - 1499721284712 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284714 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284717 Marionette TRACE 360 <- [1,1,null,{"sessionId":"83904761-884d-4d59-b92b-83d7783ee4c0","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284718 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:14:44 INFO - 1499721284719 Marionette TRACE 360 <- [1,2,null,{}]
14:14:44 INFO - 1499721284719 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:44 INFO - 1499721284720 Marionette TRACE 360 <- [1,3,null,{}]
14:14:44 INFO - 1499721284721 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:44 INFO - 1499721284721 Marionette TRACE 360 <- [1,4,null,{}]
14:14:44 INFO - 1499721284723 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}]
14:14:44 INFO - TEST-START: test_click.py TestClick.test_container_element1499721284725 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:14:44 INFO - 1499721284726 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:14:44 INFO - 1499721284727 Marionette TRACE 360 <- [1,6,null,{}]
14:14:44 INFO - 1499721284728 Marionette DEBUG Closed connection 360
14:14:44 INFO - 1499721284729 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49818
14:14:44 INFO - 1499721284730 Marionette DEBUG Closed connection 361
14:14:44 INFO - 1499721284731 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49819
14:14:44 INFO - 1499721284731 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:44 INFO - 1499721284732 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284734 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284737 Marionette TRACE 362 <- [1,1,null,{"sessionId":"242d0d84-ab4c-414f-b895-93938a7f16d1","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284738 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:14:44 INFO - 1499721284739 Marionette TRACE 362 <- [1,2,null,{}]
14:14:44 INFO - 1499721284740 Marionette DEBUG Closed connection 362
14:14:44 INFO - 1499721284741 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49820
14:14:44 INFO - 1499721284742 Marionette DEBUG Closed connection 363
14:14:44 INFO - 1499721284742 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49821
14:14:44 INFO - 1499721284743 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:44 INFO - 1499721284744 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:44 INFO - 1499721284746 Marionette DEBUG Register listener.js for window 4294967297
14:14:44 INFO - 1499721284749 Marionette TRACE 364 <- [1,1,null,{"sessionId":"55afb549-3a2b-45a4-8dd6-7c27918ee274","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:44 INFO - 1499721284750 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"}]
14:14:44 INFO - 1499721284751 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49369/test.html"
14:14:44 INFO - 1499721284753 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49369/test.html"
14:14:44 INFO - 1499721284753 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49369/test.html"
14:14:44 INFO - 1499721284759 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"
14:14:44 INFO - 1499721284763 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"
14:14:44 INFO - 1499721284772 Marionette TRACE 364 <- [1,2,null,{}]
14:14:44 INFO - 1499721284777 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:14:44 INFO - 1499721284782 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a4c105a-8db4-499e-a203-b175a4abfb9c","ELEMENT":"8a4c105a-8db4-499e-a203-b175a4abfb9c"}}]
14:14:44 INFO - 1499721284783 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8a4c105a-8db4-499e-a203-b175a4abfb9c"}]
14:14:45 INFO - 1499721285022 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:14:45 INFO - 1499721285022 Marionette TRACE 364 <- [1,4,null,{}]
14:14:45 INFO - 1499721285023 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8a4c105a-8db4-499e-a203-b175a4abfb9c","name":"selected"}]
14:14:45 INFO - 1499721285028 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:14:45 INFO - 1499721285029 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}]
14:14:45 INFO - TEST-END: test_click.py TestClick.test_container_element1499721285032 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:14:45 INFO - 1499721285033 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:14:45 INFO - 1499721285034 Marionette TRACE 364 <- [1,7,null,{}]
14:14:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 327ms
14:14:45 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:14:45 INFO - 1499721285035 Marionette DEBUG Closed connection 364
14:14:45 INFO - 1499721285037 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49822
14:14:45 INFO - 1499721285038 Marionette DEBUG Closed connection 365
14:14:45 INFO - 1499721285039 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49823
14:14:45 INFO - 1499721285040 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285040 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285042 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285046 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fcfbf53c-e902-4976-aea6-ec10efe2c2c3","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285047 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:14:45 INFO - 1499721285047 Marionette TRACE 366 <- [1,2,null,{}]
14:14:45 INFO - 1499721285048 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:45 INFO - 1499721285049 Marionette TRACE 366 <- [1,3,null,{}]
14:14:45 INFO - 1499721285049 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:45 INFO - 1499721285050 Marionette TRACE 366 <- [1,4,null,{}]
14:14:45 INFO - 1499721285051 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}]
14:14:45 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499721285054 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:14:45 INFO - 1499721285055 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:14:45 INFO - 1499721285056 Marionette TRACE 366 <- [1,6,null,{}]
14:14:45 INFO - 1499721285057 Marionette DEBUG Closed connection 366
14:14:45 INFO - 1499721285058 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49824
14:14:45 INFO - 1499721285058 Marionette DEBUG Closed connection 367
14:14:45 INFO - 1499721285059 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49825
14:14:45 INFO - 1499721285060 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285061 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285063 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285065 Marionette TRACE 368 <- [1,1,null,{"sessionId":"06e19a24-9b0e-40f8-b3eb-2f85b8f537fc","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285067 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:14:45 INFO - 1499721285068 Marionette TRACE 368 <- [1,2,null,{}]
14:14:45 INFO - 1499721285069 Marionette DEBUG Closed connection 368
14:14:45 INFO - 1499721285070 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49826
14:14:45 INFO - 1499721285070 Marionette DEBUG Closed connection 369
14:14:45 INFO - 1499721285071 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49827
14:14:45 INFO - 1499721285072 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:45 INFO - 1499721285073 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285075 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285077 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d7858183-973e-44d8-8adb-2a987233c2d1","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285079 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"}]
14:14:45 INFO - 1499721285080 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"
14:14:45 INFO - 1499721285083 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"
14:14:45 INFO - 1499721285084 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"
14:14:45 INFO - 1499721285091 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"
14:14:45 INFO - 1499721285095 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"
14:14:45 INFO - 1499721285100 Marionette TRACE 370 <- [1,2,null,{}]
14:14:45 INFO - 1499721285103 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:14:45 INFO - 1499721285107 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b68ab269-e2a2-4343-b4fb-1f2e878ff98b","ELEMENT":"b68ab269-e2a2-4343-b4fb-1f2e878ff98b"}}]
14:14:45 INFO - 1499721285108 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b68ab269-e2a2-4343-b4fb-1f2e878ff98b"}]
14:14:45 INFO - 1499721285346 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:14:45 INFO - 1499721285346 Marionette TRACE 370 <- [1,4,null,{}]
14:14:45 INFO - 1499721285348 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b68ab269-e2a2-4343-b4fb-1f2e878ff98b","name":"selected"}]
14:14:45 INFO - 1499721285352 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:14:45 INFO - 1499721285353 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}]
14:14:45 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499721285356 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:14:45 INFO - 1499721285356 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:14:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 323ms
14:14:45 INFO - 1499721285358 Marionette TRACE 370 <- [1,7,null,{}]
14:14:45 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:14:45 INFO - 1499721285359 Marionette DEBUG Closed connection 370
14:14:45 INFO - 1499721285360 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49828
14:14:45 INFO - 1499721285361 Marionette DEBUG Closed connection 371
14:14:45 INFO - 1499721285362 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49829
14:14:45 INFO - 1499721285363 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285364 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285366 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285368 Marionette TRACE 372 <- [1,1,null,{"sessionId":"29d86fe4-9bbe-4829-bdf5-11e85c52feb5","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285369 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:14:45 INFO - 1499721285370 Marionette TRACE 372 <- [1,2,null,{}]
14:14:45 INFO - 1499721285371 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:45 INFO - 1499721285371 Marionette TRACE 372 <- [1,3,null,{}]
14:14:45 INFO - 1499721285372 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:45 INFO - 1499721285372 Marionette TRACE 372 <- [1,4,null,{}]
14:14:45 INFO - 1499721285374 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}]
14:14:45 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499721285376 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:14:45 INFO - 1499721285377 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:14:45 INFO - 1499721285379 Marionette TRACE 372 <- [1,6,null,{}]
14:14:45 INFO - 1499721285380 Marionette DEBUG Closed connection 372
14:14:45 INFO - 1499721285381 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49830
14:14:45 INFO - 1499721285381 Marionette DEBUG Closed connection 373
14:14:45 INFO - 1499721285382 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49831
14:14:45 INFO - 1499721285383 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285383 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285385 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285388 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5d98f1d9-a39d-4c54-b678-2463fe9be3d2","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285389 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:14:45 INFO - 1499721285390 Marionette TRACE 374 <- [1,2,null,{}]
14:14:45 INFO - 1499721285392 Marionette DEBUG Closed connection 374
14:14:45 INFO - 1499721285392 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49832
14:14:45 INFO - 1499721285393 Marionette DEBUG Closed connection 375
14:14:45 INFO - 1499721285394 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49833
14:14:45 INFO - 1499721285395 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:45 INFO - 1499721285395 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285397 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285407 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a4fc14fe-d6b9-4db0-aa6c-d1f9904486e4","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285408 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"}]
14:14:45 INFO - 1499721285410 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"
14:14:45 INFO - 1499721285412 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"
14:14:45 INFO - 1499721285412 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"
14:14:45 INFO - 1499721285418 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"
14:14:45 INFO - 1499721285421 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"
14:14:45 INFO - 1499721285424 Marionette TRACE 376 <- [1,2,null,{}]
14:14:45 INFO - 1499721285427 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:14:45 INFO - 1499721285434 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4483b506-3f67-45be-bf37-ff1f2c07e10a","ELEMENT":"4483b506-3f67-45be-bf37-ff1f2c07e10a"}}]
14:14:45 INFO - 1499721285435 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4483b506-3f67-45be-bf37-ff1f2c07e10a"}]
14:14:45 INFO - 1499721285676 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:14:45 INFO - 1499721285677 Marionette TRACE 376 <- [1,4,null,{}]
14:14:45 INFO - 1499721285679 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}]
14:14:45 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499721285681 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:14:45 INFO - 1499721285682 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:14:45 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 325ms
14:14:45 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:14:45 INFO - 1499721285684 Marionette TRACE 376 <- [1,6,null,{}]
14:14:45 INFO - 1499721285685 Marionette DEBUG Closed connection 376
14:14:45 INFO - 1499721285686 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49834
14:14:45 INFO - 1499721285687 Marionette DEBUG Closed connection 377
14:14:45 INFO - 1499721285687 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49835
14:14:45 INFO - 1499721285688 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285689 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285691 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285694 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8ec3cffa-868b-4c93-b038-aaa9dc1e37c8","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285695 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:14:45 INFO - 1499721285696 Marionette TRACE 378 <- [1,2,null,{}]
14:14:45 INFO - 1499721285696 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:45 INFO - 1499721285697 Marionette TRACE 378 <- [1,3,null,{}]
14:14:45 INFO - 1499721285698 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:45 INFO - 1499721285698 Marionette TRACE 378 <- [1,4,null,{}]
14:14:45 INFO - 1499721285700 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}]
14:14:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499721285702 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:14:45 INFO - 1499721285703 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:14:45 INFO - 1499721285704 Marionette TRACE 378 <- [1,6,null,{}]
14:14:45 INFO - 1499721285706 Marionette DEBUG Closed connection 378
14:14:45 INFO - 1499721285706 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49836
14:14:45 INFO - 1499721285707 Marionette DEBUG Closed connection 379
14:14:45 INFO - 1499721285707 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49837
14:14:45 INFO - 1499721285708 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:45 INFO - 1499721285709 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285711 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285714 Marionette TRACE 380 <- [1,1,null,{"sessionId":"bf6b4c82-d49e-4ce3-8e88-d20288c977c0","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285715 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:14:45 INFO - 1499721285716 Marionette TRACE 380 <- [1,2,null,{}]
14:14:45 INFO - 1499721285717 Marionette DEBUG Closed connection 380
14:14:45 INFO - 1499721285718 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49838
14:14:45 INFO - 1499721285718 Marionette DEBUG Closed connection 381
14:14:45 INFO - 1499721285719 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49839
14:14:45 INFO - 1499721285720 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:45 INFO - 1499721285721 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:45 INFO - 1499721285723 Marionette DEBUG Register listener.js for window 4294967297
14:14:45 INFO - 1499721285725 Marionette TRACE 382 <- [1,1,null,{"sessionId":"83a5472a-4b0f-4b57-8581-d0e35826d7c2","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:45 INFO - 1499721285726 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"}]
14:14:45 INFO - 1499721285728 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"
14:14:45 INFO - 1499721285730 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"
14:14:45 INFO - 1499721285730 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"
14:14:45 INFO - 1499721285735 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"
14:14:45 INFO - 1499721285738 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"
14:14:45 INFO - 1499721285740 Marionette TRACE 382 <- [1,2,null,{}]
14:14:45 INFO - 1499721285743 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:14:45 INFO - 1499721285749 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1dc94599-b7b8-42b0-9299-818f44f19c1e","ELEMENT":"1dc94599-b7b8-42b0-9299-818f44f19c1e"}}]
14:14:45 INFO - 1499721285750 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1dc94599-b7b8-42b0-9299-818f44f19c1e"}]
14:14:45 INFO - 1499721285988 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:14:45 INFO - 1499721285988 Marionette TRACE 382 <- [1,4,null,{}]
14:14:45 INFO - 1499721285990 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}]
14:14:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499721285993 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:14:45 INFO - 1499721285994 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:14:45 INFO - 1499721285995 Marionette TRACE 382 <- [1,6,null,{}]
14:14:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 312ms
14:14:45 INFO - TEST-START | test_click.py TestClick.test_input_file
14:14:46 INFO - 1499721285997 Marionette DEBUG Closed connection 382
14:14:46 INFO - 1499721285998 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49840
14:14:46 INFO - 1499721285998 Marionette DEBUG Closed connection 383
14:14:46 INFO - 1499721285999 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49841
14:14:46 INFO - 1499721286000 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286001 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286003 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286006 Marionette TRACE 384 <- [1,1,null,{"sessionId":"384ca2db-b6c6-4d1e-886a-b5743402a284","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286007 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:14:46 INFO - 1499721286008 Marionette TRACE 384 <- [1,2,null,{}]
14:14:46 INFO - 1499721286009 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:46 INFO - 1499721286009 Marionette TRACE 384 <- [1,3,null,{}]
14:14:46 INFO - 1499721286010 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:46 INFO - 1499721286010 Marionette TRACE 384 <- [1,4,null,{}]
14:14:46 INFO - 1499721286012 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}]
14:14:46 INFO - TEST-START: test_click.py TestClick.test_input_file1499721286014 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:14:46 INFO - 1499721286015 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:14:46 INFO - 1499721286016 Marionette TRACE 384 <- [1,6,null,{}]
14:14:46 INFO - 1499721286017 Marionette DEBUG Closed connection 384
14:14:46 INFO - 1499721286018 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49842
14:14:46 INFO - 1499721286019 Marionette DEBUG Closed connection 385
14:14:46 INFO - 1499721286019 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49843
14:14:46 INFO - 1499721286020 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286021 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286023 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286026 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1e17fc5f-0cf6-482d-9513-1810716531f9","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286027 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:14:46 INFO - 1499721286028 Marionette TRACE 386 <- [1,2,null,{}]
14:14:46 INFO - 1499721286029 Marionette DEBUG Closed connection 386
14:14:46 INFO - 1499721286030 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49844
14:14:46 INFO - 1499721286030 Marionette DEBUG Closed connection 387
14:14:46 INFO - 1499721286031 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49845
14:14:46 INFO - 1499721286032 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:46 INFO - 1499721286033 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286035 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286037 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ae7c15d1-01be-4907-95dd-460a4df1ab4b","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286038 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:14:46 INFO - 1499721286040 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"
14:14:46 INFO - 1499721286041 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"
14:14:46 INFO - 1499721286042 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"
14:14:46 INFO - 1499721286047 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:14:46 INFO - 1499721286051 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:14:46 INFO - 1499721286055 Marionette TRACE 388 <- [1,2,null,{}]
14:14:46 INFO - 1499721286057 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:14:46 INFO - 1499721286060 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b73de88a-7222-4a1a-8b65-fdebe9e18273","ELEMENT":"b73de88a-7222-4a1a-8b65-fdebe9e18273"}}]
14:14:46 INFO - 1499721286061 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"b73de88a-7222-4a1a-8b65-fdebe9e18273"}]
14:14:46 INFO - 1499721286085 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]
14:14:46 INFO - 1499721286087 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}]
14:14:46 INFO - TEST-END: test_click.py TestClick.test_input_file1499721286091 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:14:46 INFO - 1499721286092 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:14:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 98ms
14:14:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:14:46 INFO - 1499721286094 Marionette TRACE 388 <- [1,6,null,{}]
14:14:46 INFO - 1499721286095 Marionette DEBUG Closed connection 388
14:14:46 INFO - 1499721286097 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49846
14:14:46 INFO - 1499721286098 Marionette DEBUG Closed connection 389
14:14:46 INFO - 1499721286099 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49847
14:14:46 INFO - 1499721286100 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286100 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286102 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286105 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7923a397-b757-4c8d-bcc9-0722b8fc2fa9","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286106 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:14:46 INFO - 1499721286107 Marionette TRACE 390 <- [1,2,null,{}]
14:14:46 INFO - 1499721286108 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:46 INFO - 1499721286108 Marionette TRACE 390 <- [1,3,null,{}]
14:14:46 INFO - 1499721286109 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:46 INFO - 1499721286109 Marionette TRACE 390 <- [1,4,null,{}]
14:14:46 INFO - 1499721286111 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}]
14:14:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499721286114 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:14:46 INFO - 1499721286114 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:14:46 INFO - 1499721286115 Marionette TRACE 390 <- [1,6,null,{}]
14:14:46 INFO - 1499721286117 Marionette DEBUG Closed connection 390
14:14:46 INFO - 1499721286117 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49848
14:14:46 INFO - 1499721286118 Marionette DEBUG Closed connection 391
14:14:46 INFO - 1499721286119 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49849
14:14:46 INFO - 1499721286120 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286120 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286122 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286125 Marionette TRACE 392 <- [1,1,null,{"sessionId":"71e8d2fc-4220-4ff7-bcf5-94fcb82442a7","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286126 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:14:46 INFO - 1499721286127 Marionette TRACE 392 <- [1,2,null,{}]
14:14:46 INFO - 1499721286129 Marionette DEBUG Closed connection 392
14:14:46 INFO - 1499721286129 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49850
14:14:46 INFO - 1499721286130 Marionette DEBUG Closed connection 393
14:14:46 INFO - 1499721286131 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49851
14:14:46 INFO - 1499721286132 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:46 INFO - 1499721286132 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286134 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286137 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c0b9d6d2-dd25-4b24-bca4-fd93fb8ca92e","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286138 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"}]
14:14:46 INFO - 1499721286139 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:14:46 INFO - 1499721286141 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:14:46 INFO - 1499721286141 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:14:46 INFO - 1499721286149 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"
14:14:46 INFO - 1499721286150 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"
14:14:46 INFO - 1499721286154 Marionette TRACE 394 <- [1,2,null,{}]
14:14:46 INFO - 1499721286157 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:14:46 INFO - 1499721286159 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5407003d-8ead-407a-8cca-45804e84abe5","ELEMENT":"5407003d-8ead-407a-8cca-45804e84abe5"}}]
14:14:46 INFO - 1499721286160 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:14:46 INFO - 1499721286166 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"91831383-25ec-4f60-8813-4309e5d7e123","ELEMENT":"91831383-25ec-4f60-8813-4309e5d7e123"}}]
14:14:46 INFO - 1499721286168 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5407003d-8ead-407a-8cca-45804e84abe5"}]
14:14:46 INFO - 1499721286408 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:14:46 INFO - 1499721286409 Marionette TRACE 394 <- [1,5,null,{}]
14:14:46 INFO - 1499721286410 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"91831383-25ec-4f60-8813-4309e5d7e123"}]
14:14:46 INFO - 1499721286424 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]
14:14:46 INFO - 1499721286426 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:14:46 INFO - 1499721286428 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:14:46 INFO - 1499721286430 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}]
14:14:46 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499721286432 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:14:46 INFO - 1499721286433 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:14:46 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 340ms
14:14:46 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:14:46 INFO - 1499721286435 Marionette TRACE 394 <- [1,9,null,{}]
14:14:46 INFO - 1499721286436 Marionette DEBUG Closed connection 394
14:14:46 INFO - 1499721286437 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49852
14:14:46 INFO - 1499721286438 Marionette DEBUG Closed connection 395
14:14:46 INFO - 1499721286438 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49853
14:14:46 INFO - 1499721286439 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286440 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286442 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286445 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1b07e4dd-4def-41c6-8b91-a0c3ed5a7ec3","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286446 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:14:46 INFO - 1499721286446 Marionette TRACE 396 <- [1,2,null,{}]
14:14:46 INFO - 1499721286447 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:14:46 INFO - 1499721286448 Marionette TRACE 396 <- [1,3,null,{}]
14:14:46 INFO - 1499721286448 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:14:46 INFO - 1499721286449 Marionette TRACE 396 <- [1,4,null,{}]
14:14:46 INFO - 1499721286450 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}]
14:14:46 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499721286453 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:14:46 INFO - 1499721286454 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:14:46 INFO - 1499721286455 Marionette TRACE 396 <- [1,6,null,{}]
14:14:46 INFO - 1499721286456 Marionette DEBUG Closed connection 396
14:14:46 INFO - 1499721286456 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49854
14:14:46 INFO - 1499721286457 Marionette DEBUG Closed connection 397
14:14:46 INFO - 1499721286458 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49855
14:14:46 INFO - 1499721286459 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:14:46 INFO - 1499721286459 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286461 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286464 Marionette TRACE 398 <- [1,1,null,{"sessionId":"72b7fe6c-782a-49e8-8a7b-b42cd5015825","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286465 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:14:46 INFO - 1499721286466 Marionette TRACE 398 <- [1,2,null,{}]
14:14:46 INFO - 1499721286468 Marionette DEBUG Closed connection 398
14:14:46 INFO - 1499721286468 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49856
14:14:46 INFO - 1499721286469 Marionette DEBUG Closed connection 399
14:14:46 INFO - 1499721286470 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49857
14:14:46 INFO - 1499721286470 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:14:46 INFO - 1499721286471 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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}
14:14:46 INFO - 1499721286473 Marionette DEBUG Register listener.js for window 4294967297
14:14:46 INFO - 1499721286476 Marionette TRACE 400 <- [1,1,null,{"sessionId":"94a0b33f-8913-4dde-9cfa-029122543811","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":5260,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp2wx1w2.mozrunner","moz:accessibilityChecks":false}}]
14:14:46 INFO - 1499721286477 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"}]
14:14:46 INFO - 1499721286479 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"
14:14:46 INFO - 1499721286482 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"
14:14:46 INFO - 1499721286482 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"
14:14:46 INFO - 1499721286488 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"
14:14:46 INFO - 1499721286490 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"
14:14:46 INFO - 1499721286493 Marionette TRACE 400 <- [1,2,null,{}]
14:14:46 INFO - 1499721286501 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:14:46 INFO - 1499721286504 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5675bb9a-af87-4de2-996d-579aa8c0a264","ELEMENT":"5675bb9a-af87-4de2-996d-579aa8c0a264"}}]
14:14:46 INFO - 1499721286505 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5675bb9a-af87-4de2-996d-579aa8c0a264"}]
14:14:46 INFO - 1499721286525 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:14:46 INFO - 1499721286526 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5675bb9a-af87-4de2-996d-579aa8c0a264"}]
14:14:46 INFO - 1499721286540 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element