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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:34:12 INFO - 1499128452623 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}]
17:34:12 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499128452627 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:34:12 INFO - 1499128452628 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:34:12 INFO - 1499128452629 Marionette TRACE 352 <- [1,6,null,{}]
17:34:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 190ms
17:34:12 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:34:12 INFO - 1499128452632 Marionette DEBUG Closed connection 352
17:34:12 INFO - 1499128452633 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49780
17:34:12 INFO - 1499128452634 Marionette DEBUG Closed connection 353
17:34:12 INFO - 1499128452634 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
17:34:12 INFO - 1499128452636 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:12 INFO - 1499128452636 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:12 INFO - 1499128452639 Marionette DEBUG Register listener.js for window 12
17:34:12 INFO - 1499128452643 Marionette TRACE 354 <- [1,1,null,{"sessionId":"890960d7-ae11-4813-8d02-0f90d987c7df","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:12 INFO - 1499128452652 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:34:12 INFO - 1499128452652 Marionette TRACE 354 <- [1,2,null,{}]
17:34:12 INFO - 1499128452654 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:12 INFO - 1499128452654 Marionette TRACE 354 <- [1,3,null,{}]
17:34:12 INFO - 1499128452655 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:12 INFO - 1499128452656 Marionette TRACE 354 <- [1,4,null,{}]
17:34:12 INFO - 1499128452657 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}]
17:34:12 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499128452661 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:34:12 INFO - 1499128452662 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:34:12 INFO - 1499128452663 Marionette TRACE 354 <- [1,6,null,{}]
17:34:12 INFO - 1499128452666 Marionette DEBUG Closed connection 354
17:34:12 INFO - 1499128452667 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
17:34:12 INFO - 1499128452667 Marionette DEBUG Closed connection 355
17:34:12 INFO - 1499128452668 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
17:34:12 INFO - 1499128452669 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:12 INFO - 1499128452670 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:12 INFO - 1499128452673 Marionette DEBUG Register listener.js for window 12
17:34:12 INFO - 1499128452677 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f98b71fa-3cd2-4e68-8d85-5a283bc45479","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:12 INFO - 1499128452678 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:34:12 INFO - 1499128452680 Marionette TRACE 356 <- [1,2,null,{}]
17:34:12 INFO - 1499128452682 Marionette DEBUG Closed connection 356
17:34:12 INFO - 1499128452683 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
17:34:12 INFO - 1499128452684 Marionette DEBUG Closed connection 357
17:34:12 INFO - 1499128452684 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
17:34:12 INFO - 1499128452686 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:12 INFO - 1499128452686 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:12 INFO - 1499128452689 Marionette DEBUG Register listener.js for window 12
17:34:12 INFO - 1499128452693 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c691059e-dfaa-4281-85f2-54d34822acd2","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:12 INFO - 1499128452694 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49351/clicks.html"}]
17:34:12 INFO - 1499128452696 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/hidden.html"
17:34:12 INFO - 1499128452703 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/hidden.html"
17:34:12 INFO - 1499128452703 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/hidden.html"
17:34:12 INFO - 1499128452750 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49351/clicks.html"
17:34:12 INFO - 1499128452760 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49351/clicks.html"
17:34:12 INFO - 1499128452770 Marionette TRACE 358 <- [1,2,null,{}]
17:34:12 INFO - 1499128452773 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:34:12 INFO - 1499128452775 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b34595b6-ae77-41e8-908b-c0f4665a04df","ELEMENT":"b34595b6-ae77-41e8-908b-c0f4665a04df"}}]
17:34:12 INFO - 1499128452777 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b34595b6-ae77-41e8-908b-c0f4665a04df"}]
17:34:12 INFO - 1499128452840 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/clicks.html"
17:34:12 INFO - 1499128452853 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/clicks.html"
17:34:12 INFO - 1499128452853 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/clicks.html"
17:34:12 INFO - 1499128452889 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49351/test.html"
17:34:12 INFO - 1499128452893 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49351/test.html"
17:34:12 INFO - 1499128452898 Marionette TRACE 358 <- [1,4,null,{}]
17:34:12 INFO - 1499128452930 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:34:12 INFO - 1499128452933 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c3758d26-4981-4a9d-9fab-26d0d4b8f08b","ELEMENT":"c3758d26-4981-4a9d-9fab-26d0d4b8f08b"}}]
17:34:12 INFO - 1499128452955 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:34:12 INFO - 1499128452957 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:34:12 INFO - 1499128452959 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}]
17:34:12 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499128452963 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:34:12 INFO - 1499128452964 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:34:12 INFO - 1499128452965 Marionette TRACE 358 <- [1,8,null,{}]
17:34:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 336ms
17:34:12 INFO - TEST-START | test_click.py TestClick.test_container_element
17:34:12 INFO - 1499128452968 Marionette DEBUG Closed connection 358
17:34:12 INFO - 1499128452969 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
17:34:12 INFO - 1499128452969 Marionette DEBUG Closed connection 359
17:34:12 INFO - 1499128452970 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
17:34:12 INFO - 1499128452971 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:12 INFO - 1499128452972 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:12 INFO - 1499128452975 Marionette DEBUG Register listener.js for window 12
17:34:12 INFO - 1499128452979 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5bb3a024-7a89-4635-a0f3-ba43a1527aa1","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:12 INFO - 1499128452993 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:34:12 INFO - 1499128452993 Marionette TRACE 360 <- [1,2,null,{}]
17:34:13 INFO - 1499128453007 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:13 INFO - 1499128453008 Marionette TRACE 360 <- [1,3,null,{}]
17:34:13 INFO - 1499128453009 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:13 INFO - 1499128453009 Marionette TRACE 360 <- [1,4,null,{}]
17:34:13 INFO - 1499128453012 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}]
17:34:13 INFO - TEST-START: test_click.py TestClick.test_container_element1499128453016 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:34:13 INFO - 1499128453018 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:34:13 INFO - 1499128453019 Marionette TRACE 360 <- [1,6,null,{}]
17:34:13 INFO - 1499128453022 Marionette DEBUG Closed connection 360
17:34:13 INFO - 1499128453022 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
17:34:13 INFO - 1499128453024 Marionette DEBUG Closed connection 361
17:34:13 INFO - 1499128453025 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
17:34:13 INFO - 1499128453026 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:13 INFO - 1499128453026 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453029 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453033 Marionette TRACE 362 <- [1,1,null,{"sessionId":"febe9eae-a62e-4e9c-934a-f859a720fe45","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453035 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:34:13 INFO - 1499128453036 Marionette TRACE 362 <- [1,2,null,{}]
17:34:13 INFO - 1499128453039 Marionette DEBUG Closed connection 362
17:34:13 INFO - 1499128453040 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
17:34:13 INFO - 1499128453041 Marionette DEBUG Closed connection 363
17:34:13 INFO - 1499128453041 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
17:34:13 INFO - 1499128453043 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:13 INFO - 1499128453044 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453047 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453051 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6d8e08a1-79bf-427f-a36d-f1327226f342","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453053 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"}]
17:34:13 INFO - 1499128453055 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49351/test.html"
17:34:13 INFO - 1499128453058 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49351/test.html"
17:34:13 INFO - 1499128453058 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49351/test.html"
17:34:13 INFO - 1499128453086 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"
17:34:13 INFO - 1499128453092 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"
17:34:13 INFO - 1499128453096 Marionette TRACE 364 <- [1,2,null,{}]
17:34:13 INFO - 1499128453107 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:34:13 INFO - 1499128453110 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f4c2953-cd04-4232-9726-63e1a617e6ef","ELEMENT":"5f4c2953-cd04-4232-9726-63e1a617e6ef"}}]
17:34:13 INFO - 1499128453111 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5f4c2953-cd04-4232-9726-63e1a617e6ef"}]
17:34:13 INFO - 1499128453359 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:34:13 INFO - 1499128453360 Marionette TRACE 364 <- [1,4,null,{}]
17:34:13 INFO - 1499128453361 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5f4c2953-cd04-4232-9726-63e1a617e6ef","name":"selected"}]
17:34:13 INFO - 1499128453366 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:34:13 INFO - 1499128453368 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}]
17:34:13 INFO - TEST-END: test_click.py TestClick.test_container_element1499128453372 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:34:13 INFO - 1499128453373 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:34:13 INFO - 1499128453374 Marionette TRACE 364 <- [1,7,null,{}]
17:34:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 409ms
17:34:13 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:34:13 INFO - 1499128453377 Marionette DEBUG Closed connection 364
17:34:13 INFO - 1499128453378 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
17:34:13 INFO - 1499128453379 Marionette DEBUG Closed connection 365
17:34:13 INFO - 1499128453379 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
17:34:13 INFO - 1499128453381 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:13 INFO - 1499128453382 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453385 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453388 Marionette TRACE 366 <- [1,1,null,{"sessionId":"0695ceaf-aa46-4c85-a850-f2b26566bf65","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453390 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:34:13 INFO - 1499128453391 Marionette TRACE 366 <- [1,2,null,{}]
17:34:13 INFO - 1499128453392 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:13 INFO - 1499128453393 Marionette TRACE 366 <- [1,3,null,{}]
17:34:13 INFO - 1499128453394 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:13 INFO - 1499128453394 Marionette TRACE 366 <- [1,4,null,{}]
17:34:13 INFO - 1499128453396 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}]
17:34:13 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499128453400 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:34:13 INFO - 1499128453401 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:34:13 INFO - 1499128453402 Marionette TRACE 366 <- [1,6,null,{}]
17:34:13 INFO - 1499128453405 Marionette DEBUG Closed connection 366
17:34:13 INFO - 1499128453406 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
17:34:13 INFO - 1499128453407 Marionette DEBUG Closed connection 367
17:34:13 INFO - 1499128453407 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
17:34:13 INFO - 1499128453409 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:13 INFO - 1499128453410 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453413 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453417 Marionette TRACE 368 <- [1,1,null,{"sessionId":"fc2f9ad1-0590-47cb-9f79-33bfe81c8dc4","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453419 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:34:13 INFO - 1499128453421 Marionette TRACE 368 <- [1,2,null,{}]
17:34:13 INFO - 1499128453423 Marionette DEBUG Closed connection 368
17:34:13 INFO - 1499128453424 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
17:34:13 INFO - 1499128453425 Marionette DEBUG Closed connection 369
17:34:13 INFO - 1499128453426 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
17:34:13 INFO - 1499128453427 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:13 INFO - 1499128453428 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453431 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453435 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3ed31172-4b7a-429f-bbfb-0d7c3134f225","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453437 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"}]
17:34:13 INFO - 1499128453439 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"
17:34:13 INFO - 1499128453443 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"
17:34:13 INFO - 1499128453443 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"
17:34:13 INFO - 1499128453469 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"
17:34:13 INFO - 1499128453471 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"
17:34:13 INFO - 1499128453475 Marionette TRACE 370 <- [1,2,null,{}]
17:34:13 INFO - 1499128453477 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:34:13 INFO - 1499128453487 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1612110d-32fc-4994-b97b-1022769127d1","ELEMENT":"1612110d-32fc-4994-b97b-1022769127d1"}}]
17:34:13 INFO - 1499128453489 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1612110d-32fc-4994-b97b-1022769127d1"}]
17:34:13 INFO - 1499128453745 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:34:13 INFO - 1499128453746 Marionette TRACE 370 <- [1,4,null,{}]
17:34:13 INFO - 1499128453747 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1612110d-32fc-4994-b97b-1022769127d1","name":"selected"}]
17:34:13 INFO - 1499128453753 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:34:13 INFO - 1499128453755 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}]
17:34:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499128453758 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:34:13 INFO - 1499128453759 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:34:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 386ms
17:34:13 INFO - 1499128453761 Marionette TRACE 370 <- [1,7,null,{}]
17:34:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:34:13 INFO - 1499128453763 Marionette DEBUG Closed connection 370
17:34:13 INFO - 1499128453765 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
17:34:13 INFO - 1499128453766 Marionette DEBUG Closed connection 371
17:34:13 INFO - 1499128453766 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
17:34:13 INFO - 1499128453767 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:13 INFO - 1499128453768 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453771 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453775 Marionette TRACE 372 <- [1,1,null,{"sessionId":"96f12d49-db92-4793-bc21-5d93bd965b7a","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453777 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:34:13 INFO - 1499128453777 Marionette TRACE 372 <- [1,2,null,{}]
17:34:13 INFO - 1499128453778 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:13 INFO - 1499128453779 Marionette TRACE 372 <- [1,3,null,{}]
17:34:13 INFO - 1499128453780 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:13 INFO - 1499128453780 Marionette TRACE 372 <- [1,4,null,{}]
17:34:13 INFO - 1499128453782 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}]
17:34:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499128453785 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:34:13 INFO - 1499128453786 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:34:13 INFO - 1499128453788 Marionette TRACE 372 <- [1,6,null,{}]
17:34:13 INFO - 1499128453791 Marionette DEBUG Closed connection 372
17:34:13 INFO - 1499128453792 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
17:34:13 INFO - 1499128453792 Marionette DEBUG Closed connection 373
17:34:13 INFO - 1499128453793 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
17:34:13 INFO - 1499128453794 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:13 INFO - 1499128453795 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453798 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453806 Marionette TRACE 374 <- [1,1,null,{"sessionId":"bf8810f8-a324-4fc5-83d0-559fae86c4a1","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453807 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:34:13 INFO - 1499128453808 Marionette TRACE 374 <- [1,2,null,{}]
17:34:13 INFO - 1499128453811 Marionette DEBUG Closed connection 374
17:34:13 INFO - 1499128453812 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
17:34:13 INFO - 1499128453813 Marionette DEBUG Closed connection 375
17:34:13 INFO - 1499128453813 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
17:34:13 INFO - 1499128453816 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:13 INFO - 1499128453817 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:13 INFO - 1499128453820 Marionette DEBUG Register listener.js for window 12
17:34:13 INFO - 1499128453830 Marionette TRACE 376 <- [1,1,null,{"sessionId":"796780dd-19af-45b7-b584-bc799b2eb614","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:13 INFO - 1499128453831 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"}]
17:34:13 INFO - 1499128453834 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"
17:34:13 INFO - 1499128453837 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"
17:34:13 INFO - 1499128453837 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"
17:34:13 INFO - 1499128453858 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"
17:34:13 INFO - 1499128453862 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"
17:34:13 INFO - 1499128453875 Marionette TRACE 376 <- [1,2,null,{}]
17:34:13 INFO - 1499128453877 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:34:13 INFO - 1499128453880 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3bc134e-0a10-4f03-a057-a1d230c9a36d","ELEMENT":"a3bc134e-0a10-4f03-a057-a1d230c9a36d"}}]
17:34:13 INFO - 1499128453883 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a3bc134e-0a10-4f03-a057-a1d230c9a36d"}]
17:34:14 INFO - 1499128454128 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:34:14 INFO - 1499128454129 Marionette TRACE 376 <- [1,4,null,{}]
17:34:14 INFO - 1499128454131 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}]
17:34:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499128454135 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454136 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:34:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 376ms
17:34:14 INFO - 1499128454137 Marionette TRACE 376 <- [1,6,null,{}]
17:34:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:34:14 INFO - 1499128454140 Marionette DEBUG Closed connection 376
17:34:14 INFO - 1499128454142 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
17:34:14 INFO - 1499128454143 Marionette DEBUG Closed connection 377
17:34:14 INFO - 1499128454143 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
17:34:14 INFO - 1499128454144 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454145 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454148 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454152 Marionette TRACE 378 <- [1,1,null,{"sessionId":"337248d4-2497-4978-a76c-f77951246d31","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454153 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:34:14 INFO - 1499128454154 Marionette TRACE 378 <- [1,2,null,{}]
17:34:14 INFO - 1499128454155 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:14 INFO - 1499128454156 Marionette TRACE 378 <- [1,3,null,{}]
17:34:14 INFO - 1499128454157 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:14 INFO - 1499128454157 Marionette TRACE 378 <- [1,4,null,{}]
17:34:14 INFO - 1499128454159 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}]
17:34:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499128454163 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454163 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:34:14 INFO - 1499128454165 Marionette TRACE 378 <- [1,6,null,{}]
17:34:14 INFO - 1499128454167 Marionette DEBUG Closed connection 378
17:34:14 INFO - 1499128454168 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
17:34:14 INFO - 1499128454169 Marionette DEBUG Closed connection 379
17:34:14 INFO - 1499128454170 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
17:34:14 INFO - 1499128454171 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454172 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454175 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454179 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b9c2a288-bf6d-4735-b1fd-8dafdd91fb7d","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454180 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:34:14 INFO - 1499128454182 Marionette TRACE 380 <- [1,2,null,{}]
17:34:14 INFO - 1499128454184 Marionette DEBUG Closed connection 380
17:34:14 INFO - 1499128454185 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
17:34:14 INFO - 1499128454186 Marionette DEBUG Closed connection 381
17:34:14 INFO - 1499128454187 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
17:34:14 INFO - 1499128454188 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:14 INFO - 1499128454189 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454191 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454195 Marionette TRACE 382 <- [1,1,null,{"sessionId":"14f45718-c868-4168-b1d5-f173bbea4a9f","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454197 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"}]
17:34:14 INFO - 1499128454198 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"
17:34:14 INFO - 1499128454201 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"
17:34:14 INFO - 1499128454202 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"
17:34:14 INFO - 1499128454224 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"
17:34:14 INFO - 1499128454227 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"
17:34:14 INFO - 1499128454231 Marionette TRACE 382 <- [1,2,null,{}]
17:34:14 INFO - 1499128454241 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:34:14 INFO - 1499128454244 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eaa43524-008d-4468-a86a-40a8e97ec8a6","ELEMENT":"eaa43524-008d-4468-a86a-40a8e97ec8a6"}}]
17:34:14 INFO - 1499128454245 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"eaa43524-008d-4468-a86a-40a8e97ec8a6"}]
17:34:14 INFO - 1499128454504 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:34:14 INFO - 1499128454505 Marionette TRACE 382 <- [1,4,null,{}]
17:34:14 INFO - 1499128454507 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}]
17:34:14 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499128454510 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454511 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:34:14 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 374ms
17:34:14 INFO - 1499128454513 Marionette TRACE 382 <- [1,6,null,{}]
17:34:14 INFO - TEST-START | test_click.py TestClick.test_input_file
17:34:14 INFO - 1499128454515 Marionette DEBUG Closed connection 382
17:34:14 INFO - 1499128454517 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
17:34:14 INFO - 1499128454517 Marionette DEBUG Closed connection 383
17:34:14 INFO - 1499128454518 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
17:34:14 INFO - 1499128454519 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454520 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454523 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454527 Marionette TRACE 384 <- [1,1,null,{"sessionId":"74cf4551-ba9f-469d-89ca-8da42589fae6","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454528 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:34:14 INFO - 1499128454529 Marionette TRACE 384 <- [1,2,null,{}]
17:34:14 INFO - 1499128454530 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:14 INFO - 1499128454530 Marionette TRACE 384 <- [1,3,null,{}]
17:34:14 INFO - 1499128454531 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:14 INFO - 1499128454532 Marionette TRACE 384 <- [1,4,null,{}]
17:34:14 INFO - 1499128454534 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}]
17:34:14 INFO - TEST-START: test_click.py TestClick.test_input_file1499128454537 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454538 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:34:14 INFO - 1499128454540 Marionette TRACE 384 <- [1,6,null,{}]
17:34:14 INFO - 1499128454542 Marionette DEBUG Closed connection 384
17:34:14 INFO - 1499128454543 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
17:34:14 INFO - 1499128454544 Marionette DEBUG Closed connection 385
17:34:14 INFO - 1499128454544 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
17:34:14 INFO - 1499128454546 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454546 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454549 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454553 Marionette TRACE 386 <- [1,1,null,{"sessionId":"49617252-d400-4333-aee9-e684def9101d","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454555 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:34:14 INFO - 1499128454556 Marionette TRACE 386 <- [1,2,null,{}]
17:34:14 INFO - 1499128454558 Marionette DEBUG Closed connection 386
17:34:14 INFO - 1499128454559 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
17:34:14 INFO - 1499128454560 Marionette DEBUG Closed connection 387
17:34:14 INFO - 1499128454561 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
17:34:14 INFO - 1499128454562 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:14 INFO - 1499128454563 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454566 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454570 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2561e185-6e79-4b25-af0c-58275cef0cd0","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454571 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:34:14 INFO - 1499128454573 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"
17:34:14 INFO - 1499128454576 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"
17:34:14 INFO - 1499128454577 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"
17:34:14 INFO - 1499128454603 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:34:14 INFO - 1499128454605 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:34:14 INFO - 1499128454609 Marionette TRACE 388 <- [1,2,null,{}]
17:34:14 INFO - 1499128454611 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:34:14 INFO - 1499128454622 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7b027c9-f388-4b12-9420-869119f84863","ELEMENT":"a7b027c9-f388-4b12-9420-869119f84863"}}]
17:34:14 INFO - 1499128454624 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a7b027c9-f388-4b12-9420-869119f84863"}]
17:34:14 INFO - 1499128454654 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:34:14 INFO - 1499128454656 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}]
17:34:14 INFO - TEST-END: test_click.py TestClick.test_input_file1499128454660 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454661 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:34:14 INFO - 1499128454662 Marionette TRACE 388 <- [1,6,null,{}]
17:34:14 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 149ms
17:34:14 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:34:14 INFO - 1499128454665 Marionette DEBUG Closed connection 388
17:34:14 INFO - 1499128454666 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
17:34:14 INFO - 1499128454667 Marionette DEBUG Closed connection 389
17:34:14 INFO - 1499128454667 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
17:34:14 INFO - 1499128454669 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454669 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454672 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454676 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9748eff0-e437-45d4-9efd-1b53e54fd976","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454678 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:34:14 INFO - 1499128454678 Marionette TRACE 390 <- [1,2,null,{}]
17:34:14 INFO - 1499128454679 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:14 INFO - 1499128454680 Marionette TRACE 390 <- [1,3,null,{}]
17:34:14 INFO - 1499128454681 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:14 INFO - 1499128454682 Marionette TRACE 390 <- [1,4,null,{}]
17:34:14 INFO - 1499128454684 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}]
17:34:14 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499128454687 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:34:14 INFO - 1499128454688 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:34:14 INFO - 1499128454690 Marionette TRACE 390 <- [1,6,null,{}]
17:34:14 INFO - 1499128454692 Marionette DEBUG Closed connection 390
17:34:14 INFO - 1499128454693 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
17:34:14 INFO - 1499128454694 Marionette DEBUG Closed connection 391
17:34:14 INFO - 1499128454695 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
17:34:14 INFO - 1499128454697 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:14 INFO - 1499128454698 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454701 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454705 Marionette TRACE 392 <- [1,1,null,{"sessionId":"819d8d75-1820-4c9b-952a-84e38cf3c1ec","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454706 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:34:14 INFO - 1499128454708 Marionette TRACE 392 <- [1,2,null,{}]
17:34:14 INFO - 1499128454710 Marionette DEBUG Closed connection 392
17:34:14 INFO - 1499128454711 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
17:34:14 INFO - 1499128454712 Marionette DEBUG Closed connection 393
17:34:14 INFO - 1499128454713 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
17:34:14 INFO - 1499128454714 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:14 INFO - 1499128454715 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:14 INFO - 1499128454718 Marionette DEBUG Register listener.js for window 12
17:34:14 INFO - 1499128454722 Marionette TRACE 394 <- [1,1,null,{"sessionId":"69f1a767-59d5-4f5d-9b14-5cfbc0cc5f4b","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:14 INFO - 1499128454723 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"}]
17:34:14 INFO - 1499128454725 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:34:14 INFO - 1499128454728 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:34:14 INFO - 1499128454728 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:34:14 INFO - 1499128454753 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"
17:34:14 INFO - 1499128454756 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"
17:34:14 INFO - 1499128454760 Marionette TRACE 394 <- [1,2,null,{}]
17:34:14 INFO - 1499128454761 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:34:14 INFO - 1499128454772 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f40cec08-d9d3-416c-a26b-8ebf6deedb87","ELEMENT":"f40cec08-d9d3-416c-a26b-8ebf6deedb87"}}]
17:34:14 INFO - 1499128454773 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:34:14 INFO - 1499128454776 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3007c584-c4ef-4a3b-91f6-bb50fe63040c","ELEMENT":"3007c584-c4ef-4a3b-91f6-bb50fe63040c"}}]
17:34:14 INFO - 1499128454777 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f40cec08-d9d3-416c-a26b-8ebf6deedb87"}]
17:34:15 INFO - 1499128455032 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:34:15 INFO - 1499128455033 Marionette TRACE 394 <- [1,5,null,{}]
17:34:15 INFO - 1499128455034 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3007c584-c4ef-4a3b-91f6-bb50fe63040c"}]
17:34:15 INFO - 1499128455052 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:34:15 INFO - 1499128455054 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:34:15 INFO - 1499128455057 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:34:15 INFO - 1499128455059 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}]
17:34:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499128455062 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:34:15 INFO - 1499128455063 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:34:15 INFO - 1499128455064 Marionette TRACE 394 <- [1,9,null,{}]
17:34:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 402ms
17:34:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:34:15 INFO - 1499128455067 Marionette DEBUG Closed connection 394
17:34:15 INFO - 1499128455067 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
17:34:15 INFO - 1499128455068 Marionette DEBUG Closed connection 395
17:34:15 INFO - 1499128455069 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
17:34:15 INFO - 1499128455070 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:15 INFO - 1499128455071 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:15 INFO - 1499128455074 Marionette DEBUG Register listener.js for window 12
17:34:15 INFO - 1499128455078 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1a1485e4-8ce2-4d19-8d9b-9867a46272be","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:15 INFO - 1499128455079 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:34:15 INFO - 1499128455079 Marionette TRACE 396 <- [1,2,null,{}]
17:34:15 INFO - 1499128455080 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:34:15 INFO - 1499128455081 Marionette TRACE 396 <- [1,3,null,{}]
17:34:15 INFO - 1499128455082 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:34:15 INFO - 1499128455082 Marionette TRACE 396 <- [1,4,null,{}]
17:34:15 INFO - 1499128455084 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}]
17:34:15 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499128455088 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:34:15 INFO - 1499128455089 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:34:15 INFO - 1499128455090 Marionette TRACE 396 <- [1,6,null,{}]
17:34:15 INFO - 1499128455093 Marionette DEBUG Closed connection 396
17:34:15 INFO - 1499128455093 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
17:34:15 INFO - 1499128455094 Marionette DEBUG Closed connection 397
17:34:15 INFO - 1499128455095 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
17:34:15 INFO - 1499128455096 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:34:15 INFO - 1499128455097 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:15 INFO - 1499128455100 Marionette DEBUG Register listener.js for window 12
17:34:15 INFO - 1499128455104 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cf95999b-a408-45a9-a802-a8b493f0ca1d","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:15 INFO - 1499128455105 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:34:15 INFO - 1499128455106 Marionette TRACE 398 <- [1,2,null,{}]
17:34:15 INFO - 1499128455109 Marionette DEBUG Closed connection 398
17:34:15 INFO - 1499128455110 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
17:34:15 INFO - 1499128455111 Marionette DEBUG Closed connection 399
17:34:15 INFO - 1499128455111 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
17:34:15 INFO - 1499128455113 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:34:15 INFO - 1499128455113 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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}
17:34:15 INFO - 1499128455116 Marionette DEBUG Register listener.js for window 12
17:34:15 INFO - 1499128455120 Marionette TRACE 400 <- [1,1,null,{"sessionId":"0cf15ca9-0ea2-4e55-a8ab-effb44e3047c","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":1644,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4imbq8.mozrunner","moz:accessibilityChecks":false}}]
17:34:15 INFO - 1499128455121 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"}]
17:34:15 INFO - 1499128455123 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"
17:34:15 INFO - 1499128455126 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"
17:34:15 INFO - 1499128455126 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"
17:34:15 INFO - 1499128455149 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"
17:34:15 INFO - 1499128455151 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"
17:34:15 INFO - 1499128455155 Marionette TRACE 400 <- [1,2,null,{}]
17:34:15 INFO - 1499128455156 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:34:15 INFO - 1499128455159 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6c9ef0c-59cc-46fe-ba63-f2ebdd4a6a02","ELEMENT":"c6c9ef0c-59cc-46fe-ba63-f2ebdd4a6a02"}}]
17:34:15 INFO - 1499128455160 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c6c9ef0c-59cc-46fe-ba63-f2ebdd4a6a02"}]
17:34:15 INFO - 1499128455191 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:34:15 INFO - 1499128455193 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c6c9ef0c-59cc-46fe-ba63-f2ebdd4a6a02"}]
17:34:15 INFO - 1499128455216 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element