could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
09:18:47 INFO - 1498753127462 Marionette TRACE 359 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:18:47 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498753127465 Marionette TRACE 359 <- [1,5,null,{"value":null}]
09:18:47 INFO - 1498753127465 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
09:18:47 INFO - 1498753127467 Marionette TRACE 359 <- [1,6,null,{}]
09:18:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 139ms
09:18:47 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:18:47 INFO - 1498753127468 Marionette DEBUG Closed connection 359
09:18:47 INFO - 1498753127469 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49916
09:18:47 INFO - 1498753127470 Marionette DEBUG Closed connection 360
09:18:47 INFO - 1498753127471 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49917
09:18:47 INFO - 1498753127472 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:47 INFO - 1498753127472 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127475 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127478 Marionette TRACE 361 <- [1,1,null,{"sessionId":"c9b699dc-e0b1-4172-84ff-dc6d8679f3d3","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127480 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
09:18:47 INFO - 1498753127480 Marionette TRACE 361 <- [1,2,null,{}]
09:18:47 INFO - 1498753127481 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:47 INFO - 1498753127482 Marionette TRACE 361 <- [1,3,null,{}]
09:18:47 INFO - 1498753127482 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:47 INFO - 1498753127483 Marionette TRACE 361 <- [1,4,null,{}]
09:18:47 INFO - 1498753127485 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:18:47 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498753127487 Marionette TRACE 361 <- [1,5,null,{"value":null}]
09:18:47 INFO - 1498753127488 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
09:18:47 INFO - 1498753127490 Marionette TRACE 361 <- [1,6,null,{}]
09:18:47 INFO - 1498753127491 Marionette DEBUG Closed connection 361
09:18:47 INFO - 1498753127492 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49918
09:18:47 INFO - 1498753127493 Marionette DEBUG Closed connection 362
09:18:47 INFO - 1498753127494 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49919
09:18:47 INFO - 1498753127494 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:47 INFO - 1498753127495 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127497 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127500 Marionette TRACE 363 <- [1,1,null,{"sessionId":"b7ba7ac3-2bca-4163-a93d-eef066e92b76","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127501 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
09:18:47 INFO - 1498753127502 Marionette TRACE 363 <- [1,2,null,{}]
09:18:47 INFO - 1498753127504 Marionette DEBUG Closed connection 363
09:18:47 INFO - 1498753127505 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49920
09:18:47 INFO - 1498753127505 Marionette DEBUG Closed connection 364
09:18:47 INFO - 1498753127506 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49921
09:18:47 INFO - 1498753127507 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:47 INFO - 1498753127508 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127509 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127512 Marionette TRACE 365 <- [1,1,null,{"sessionId":"ba21f4e5-d307-4da4-875b-71b699c55585","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127513 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:49518/clicks.html"}]
09:18:47 INFO - 1498753127515 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49518/hidden.html"
09:18:47 INFO - 1498753127523 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49518/hidden.html"
09:18:47 INFO - 1498753127523 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49518/hidden.html"
09:18:47 INFO - 1498753127535 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49518/clicks.html"
09:18:47 INFO - 1498753127543 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49518/clicks.html"
09:18:47 INFO - 1498753127546 Marionette TRACE 365 <- [1,2,null,{}]
09:18:47 INFO - 1498753127548 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:18:47 INFO - 1498753127550 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d99fffbb-3b04-4040-9600-c6ae10a2372f","ELEMENT":"d99fffbb-3b04-4040-9600-c6ae10a2372f"}}]
09:18:47 INFO - 1498753127551 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"d99fffbb-3b04-4040-9600-c6ae10a2372f"}]
09:18:47 INFO - 1498753127599 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49518/clicks.html"
09:18:47 INFO - 1498753127610 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49518/clicks.html"
09:18:47 INFO - 1498753127610 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49518/clicks.html"
09:18:47 INFO - 1498753127628 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49518/test.html"
09:18:47 INFO - 1498753127629 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49518/test.html"
09:18:47 INFO - 1498753127631 Marionette TRACE 365 <- [1,4,null,{}]
09:18:47 INFO - 1498753127633 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:18:47 INFO - 1498753127635 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7317d2c2-9354-4f6b-8fa5-66edc13b7e07","ELEMENT":"7317d2c2-9354-4f6b-8fa5-66edc13b7e07"}}]
09:18:47 INFO - 1498753127643 Marionette TRACE 365 -> [0,6,"getTitle",{}]
09:18:47 INFO - 1498753127645 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
09:18:47 INFO - 1498753127647 Marionette TRACE 365 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:18:47 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498753127650 Marionette TRACE 365 <- [1,7,null,{"value":null}]
09:18:47 INFO - 1498753127651 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
09:18:47 INFO - 1498753127652 Marionette TRACE 365 <- [1,8,null,{}]
09:18:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 185ms
09:18:47 INFO - TEST-START | test_click.py TestClick.test_container_element
09:18:47 INFO - 1498753127654 Marionette DEBUG Closed connection 365
09:18:47 INFO - 1498753127655 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49924
09:18:47 INFO - 1498753127657 Marionette DEBUG Closed connection 366
09:18:47 INFO - 1498753127657 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49925
09:18:47 INFO - 1498753127660 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:47 INFO - 1498753127660 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127662 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127665 Marionette TRACE 367 <- [1,1,null,{"sessionId":"c30334b7-dc9d-4c8b-bc82-f8db3bcb5a07","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127666 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
09:18:47 INFO - 1498753127667 Marionette TRACE 367 <- [1,2,null,{}]
09:18:47 INFO - 1498753127667 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:47 INFO - 1498753127668 Marionette TRACE 367 <- [1,3,null,{}]
09:18:47 INFO - 1498753127669 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:47 INFO - 1498753127669 Marionette TRACE 367 <- [1,4,null,{}]
09:18:47 INFO - 1498753127685 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:18:47 INFO - TEST-START: test_click.py TestClick.test_container_element1498753127688 Marionette TRACE 367 <- [1,5,null,{"value":null}]
09:18:47 INFO - 1498753127689 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
09:18:47 INFO - 1498753127690 Marionette TRACE 367 <- [1,6,null,{}]
09:18:47 INFO - 1498753127692 Marionette DEBUG Closed connection 367
09:18:47 INFO - 1498753127693 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49926
09:18:47 INFO - 1498753127693 Marionette DEBUG Closed connection 368
09:18:47 INFO - 1498753127694 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49927
09:18:47 INFO - 1498753127695 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:47 INFO - 1498753127696 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127698 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127701 Marionette TRACE 369 <- [1,1,null,{"sessionId":"d19de5c8-aeba-4b5f-8c0b-be2a9711a409","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127702 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
09:18:47 INFO - 1498753127703 Marionette TRACE 369 <- [1,2,null,{}]
09:18:47 INFO - 1498753127705 Marionette DEBUG Closed connection 369
09:18:47 INFO - 1498753127705 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49928
09:18:47 INFO - 1498753127706 Marionette DEBUG Closed connection 370
09:18:47 INFO - 1498753127707 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49929
09:18:47 INFO - 1498753127708 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:47 INFO - 1498753127708 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:47 INFO - 1498753127710 Marionette DEBUG Register listener.js for window 7
09:18:47 INFO - 1498753127713 Marionette TRACE 371 <- [1,1,null,{"sessionId":"2afcfc09-3445-4664-b646-47d011b94f6f","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:47 INFO - 1498753127714 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:18:47 INFO - 1498753127716 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49518/test.html"
09:18:47 INFO - 1498753127718 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49518/test.html"
09:18:47 INFO - 1498753127718 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49518/test.html"
09:18:47 INFO - 1498753127740 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:47 INFO - 1498753127744 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:47 INFO - 1498753127747 Marionette TRACE 371 <- [1,2,null,{}]
09:18:47 INFO - 1498753127751 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:18:47 INFO - 1498753127760 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"19e4f8e1-230a-41e7-8b93-78fe06214eda","ELEMENT":"19e4f8e1-230a-41e7-8b93-78fe06214eda"}}]
09:18:47 INFO - 1498753127768 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"19e4f8e1-230a-41e7-8b93-78fe06214eda"}]
09:18:48 INFO - 1498753128008 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:18:48 INFO - 1498753128009 Marionette TRACE 371 <- [1,4,null,{}]
09:18:48 INFO - 1498753128010 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"19e4f8e1-230a-41e7-8b93-78fe06214eda","name":"selected"}]
09:18:48 INFO - 1498753128015 Marionette TRACE 371 <- [1,5,null,{"value":true}]
09:18:48 INFO - 1498753128017 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:18:48 INFO - TEST-END: test_click.py TestClick.test_container_element1498753128020 Marionette TRACE 371 <- [1,6,null,{"value":null}]
09:18:48 INFO - 1498753128022 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
09:18:48 INFO - 1498753128023 Marionette TRACE 371 <- [1,7,null,{}]
09:18:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 370ms
09:18:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:18:48 INFO - 1498753128024 Marionette DEBUG Closed connection 371
09:18:48 INFO - 1498753128026 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49930
09:18:48 INFO - 1498753128026 Marionette DEBUG Closed connection 372
09:18:48 INFO - 1498753128027 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49931
09:18:48 INFO - 1498753128028 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128028 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128030 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128033 Marionette TRACE 373 <- [1,1,null,{"sessionId":"b0cd6deb-7e75-4d7b-9eba-60c325b52a4f","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128034 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
09:18:48 INFO - 1498753128035 Marionette TRACE 373 <- [1,2,null,{}]
09:18:48 INFO - 1498753128036 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:48 INFO - 1498753128036 Marionette TRACE 373 <- [1,3,null,{}]
09:18:48 INFO - 1498753128037 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:48 INFO - 1498753128037 Marionette TRACE 373 <- [1,4,null,{}]
09:18:48 INFO - 1498753128039 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:18:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498753128042 Marionette TRACE 373 <- [1,5,null,{"value":null}]
09:18:48 INFO - 1498753128043 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
09:18:48 INFO - 1498753128044 Marionette TRACE 373 <- [1,6,null,{}]
09:18:48 INFO - 1498753128046 Marionette DEBUG Closed connection 373
09:18:48 INFO - 1498753128047 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49932
09:18:48 INFO - 1498753128047 Marionette DEBUG Closed connection 374
09:18:48 INFO - 1498753128048 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49933
09:18:48 INFO - 1498753128049 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128050 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128051 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128054 Marionette TRACE 375 <- [1,1,null,{"sessionId":"b8a68fa8-4a1a-42ea-968a-a5783c2cea3e","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128055 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
09:18:48 INFO - 1498753128056 Marionette TRACE 375 <- [1,2,null,{}]
09:18:48 INFO - 1498753128058 Marionette DEBUG Closed connection 375
09:18:48 INFO - 1498753128058 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49934
09:18:48 INFO - 1498753128059 Marionette DEBUG Closed connection 376
09:18:48 INFO - 1498753128060 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49935
09:18:48 INFO - 1498753128061 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:48 INFO - 1498753128061 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128063 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128066 Marionette TRACE 377 <- [1,1,null,{"sessionId":"208506a4-3ca9-4a77-b347-5ae65ec53c0b","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128067 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:18:48 INFO - 1498753128068 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128070 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128070 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128080 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128084 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128087 Marionette TRACE 377 <- [1,2,null,{}]
09:18:48 INFO - 1498753128094 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:18:48 INFO - 1498753128096 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"068f9f02-f689-4247-b558-333a6e283052","ELEMENT":"068f9f02-f689-4247-b558-333a6e283052"}}]
09:18:48 INFO - 1498753128097 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"068f9f02-f689-4247-b558-333a6e283052"}]
09:18:48 INFO - 1498753128342 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:18:48 INFO - 1498753128343 Marionette TRACE 377 <- [1,4,null,{}]
09:18:48 INFO - 1498753128344 Marionette TRACE 377 -> [0,5,"getElementProperty",{"id":"068f9f02-f689-4247-b558-333a6e283052","name":"selected"}]
09:18:48 INFO - 1498753128348 Marionette TRACE 377 <- [1,5,null,{"value":true}]
09:18:48 INFO - 1498753128350 Marionette TRACE 377 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:18:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498753128352 Marionette TRACE 377 <- [1,6,null,{"value":null}]
09:18:48 INFO - 1498753128353 Marionette TRACE 377 -> [0,7,"deleteSession",{}]
09:18:48 INFO - 1498753128354 Marionette TRACE 377 <- [1,7,null,{}]
09:18:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
09:18:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:18:48 INFO - 1498753128356 Marionette DEBUG Closed connection 377
09:18:48 INFO - 1498753128357 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49936
09:18:48 INFO - 1498753128358 Marionette DEBUG Closed connection 378
09:18:48 INFO - 1498753128359 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49937
09:18:48 INFO - 1498753128360 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128360 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128362 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128365 Marionette TRACE 379 <- [1,1,null,{"sessionId":"48199f45-0846-421a-a762-7286b11a019b","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128367 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
09:18:48 INFO - 1498753128367 Marionette TRACE 379 <- [1,2,null,{}]
09:18:48 INFO - 1498753128368 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:48 INFO - 1498753128368 Marionette TRACE 379 <- [1,3,null,{}]
09:18:48 INFO - 1498753128369 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:48 INFO - 1498753128370 Marionette TRACE 379 <- [1,4,null,{}]
09:18:48 INFO - 1498753128371 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:18:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498753128374 Marionette TRACE 379 <- [1,5,null,{"value":null}]
09:18:48 INFO - 1498753128375 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
09:18:48 INFO - 1498753128376 Marionette TRACE 379 <- [1,6,null,{}]
09:18:48 INFO - 1498753128378 Marionette DEBUG Closed connection 379
09:18:48 INFO - 1498753128378 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49938
09:18:48 INFO - 1498753128379 Marionette DEBUG Closed connection 380
09:18:48 INFO - 1498753128380 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49939
09:18:48 INFO - 1498753128381 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128381 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128383 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128386 Marionette TRACE 381 <- [1,1,null,{"sessionId":"be0f2ba1-a75d-48ea-9a0a-f302fc2e36e0","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128387 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
09:18:48 INFO - 1498753128388 Marionette TRACE 381 <- [1,2,null,{}]
09:18:48 INFO - 1498753128390 Marionette DEBUG Closed connection 381
09:18:48 INFO - 1498753128391 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49940
09:18:48 INFO - 1498753128392 Marionette DEBUG Closed connection 382
09:18:48 INFO - 1498753128392 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49941
09:18:48 INFO - 1498753128393 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:48 INFO - 1498753128394 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128396 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128399 Marionette TRACE 383 <- [1,1,null,{"sessionId":"ff543ff3-bd6b-418d-b76a-7fea8963bfca","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128400 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:18:48 INFO - 1498753128401 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128403 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128403 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128423 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:18:48 INFO - 1498753128425 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:18:48 INFO - 1498753128428 Marionette TRACE 383 <- [1,2,null,{}]
09:18:48 INFO - 1498753128430 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:18:48 INFO - 1498753128432 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80374b7c-e4ee-4321-aeed-791d48023970","ELEMENT":"80374b7c-e4ee-4321-aeed-791d48023970"}}]
09:18:48 INFO - 1498753128433 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"80374b7c-e4ee-4321-aeed-791d48023970"}]
09:18:48 INFO - 1498753128671 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:18:48 INFO - 1498753128672 Marionette TRACE 383 <- [1,4,null,{}]
09:18:48 INFO - 1498753128674 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:18:48 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498753128677 Marionette TRACE 383 <- [1,5,null,{"value":null}]
09:18:48 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 376ms
09:18:48 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:18:48 INFO - 1498753128727 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
09:18:48 INFO - 1498753128730 Marionette TRACE 383 <- [1,6,null,{}]
09:18:48 INFO - 1498753128733 Marionette DEBUG Closed connection 383
09:18:48 INFO - 1498753128735 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49942
09:18:48 INFO - 1498753128736 Marionette DEBUG Closed connection 384
09:18:48 INFO - 1498753128737 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49943
09:18:48 INFO - 1498753128739 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128741 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128743 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128749 Marionette TRACE 385 <- [1,1,null,{"sessionId":"aac00716-5fee-4f22-bae1-301124e75bd1","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128758 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
09:18:48 INFO - 1498753128760 Marionette TRACE 385 <- [1,2,null,{}]
09:18:48 INFO - 1498753128761 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:48 INFO - 1498753128763 Marionette TRACE 385 <- [1,3,null,{}]
09:18:48 INFO - 1498753128764 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:48 INFO - 1498753128765 Marionette TRACE 385 <- [1,4,null,{}]
09:18:48 INFO - 1498753128767 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:18:48 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498753128771 Marionette TRACE 385 <- [1,5,null,{"value":null}]
09:18:48 INFO - 1498753128772 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
09:18:48 INFO - 1498753128774 Marionette TRACE 385 <- [1,6,null,{}]
09:18:48 INFO - 1498753128776 Marionette DEBUG Closed connection 385
09:18:48 INFO - 1498753128777 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49944
09:18:48 INFO - 1498753128778 Marionette DEBUG Closed connection 386
09:18:48 INFO - 1498753128779 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49945
09:18:48 INFO - 1498753128780 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:48 INFO - 1498753128781 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128784 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128792 Marionette TRACE 387 <- [1,1,null,{"sessionId":"d928f3df-e23e-44d6-8d25-cd8bfc52ea64","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128794 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
09:18:48 INFO - 1498753128795 Marionette TRACE 387 <- [1,2,null,{}]
09:18:48 INFO - 1498753128797 Marionette DEBUG Closed connection 387
09:18:48 INFO - 1498753128798 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49946
09:18:48 INFO - 1498753128799 Marionette DEBUG Closed connection 388
09:18:48 INFO - 1498753128800 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49947
09:18:48 INFO - 1498753128801 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:48 INFO - 1498753128802 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:48 INFO - 1498753128805 Marionette DEBUG Register listener.js for window 7
09:18:48 INFO - 1498753128809 Marionette TRACE 389 <- [1,1,null,{"sessionId":"6a01ace7-c0cc-464f-ba46-808905269644","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:48 INFO - 1498753128812 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:18:48 INFO - 1498753128814 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:18:48 INFO - 1498753128817 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:18:48 INFO - 1498753128817 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:18:48 INFO - 1498753128836 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128842 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:48 INFO - 1498753128846 Marionette TRACE 389 <- [1,2,null,{}]
09:18:48 INFO - 1498753128848 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:18:48 INFO - 1498753128851 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b50d06c9-6371-4d74-b621-98625c65619c","ELEMENT":"b50d06c9-6371-4d74-b621-98625c65619c"}}]
09:18:48 INFO - 1498753128852 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"b50d06c9-6371-4d74-b621-98625c65619c"}]
09:18:49 INFO - 1498753129105 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:18:49 INFO - 1498753129106 Marionette TRACE 389 <- [1,4,null,{}]
09:18:49 INFO - 1498753129110 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:18:49 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498753129113 Marionette TRACE 389 <- [1,5,null,{"value":null}]
09:18:49 INFO - 1498753129115 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
09:18:49 INFO - 1498753129117 Marionette TRACE 389 <- [1,6,null,{}]
09:18:49 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 386ms
09:18:49 INFO - TEST-START | test_click.py TestClick.test_input_file
09:18:49 INFO - 1498753129119 Marionette DEBUG Closed connection 389
09:18:49 INFO - 1498753129120 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49948
09:18:49 INFO - 1498753129151 Marionette DEBUG Closed connection 390
09:18:49 INFO - 1498753129151 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49949
09:18:49 INFO - 1498753129154 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129157 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129160 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129167 Marionette TRACE 391 <- [1,1,null,{"sessionId":"a8bcdd5f-aaf2-4138-983e-f013b940c0a2","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129172 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
09:18:49 INFO - 1498753129174 Marionette TRACE 391 <- [1,2,null,{}]
09:18:49 INFO - 1498753129175 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:49 INFO - 1498753129176 Marionette TRACE 391 <- [1,3,null,{}]
09:18:49 INFO - 1498753129177 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:49 INFO - 1498753129178 Marionette TRACE 391 <- [1,4,null,{}]
09:18:49 INFO - 1498753129179 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:18:49 INFO - TEST-START: test_click.py TestClick.test_input_file1498753129183 Marionette TRACE 391 <- [1,5,null,{"value":null}]
09:18:49 INFO - 1498753129184 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
09:18:49 INFO - 1498753129186 Marionette TRACE 391 <- [1,6,null,{}]
09:18:49 INFO - 1498753129188 Marionette DEBUG Closed connection 391
09:18:49 INFO - 1498753129189 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49950
09:18:49 INFO - 1498753129193 Marionette DEBUG Closed connection 392
09:18:49 INFO - 1498753129214 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49951
09:18:49 INFO - 1498753129215 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129216 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129219 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129223 Marionette TRACE 393 <- [1,1,null,{"sessionId":"87557b6b-d55c-4573-bd8b-25a9f5d3b372","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129232 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
09:18:49 INFO - 1498753129234 Marionette TRACE 393 <- [1,2,null,{}]
09:18:49 INFO - 1498753129242 Marionette DEBUG Closed connection 393
09:18:49 INFO - 1498753129243 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49952
09:18:49 INFO - 1498753129245 Marionette DEBUG Closed connection 394
09:18:49 INFO - 1498753129245 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49953
09:18:49 INFO - 1498753129265 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:49 INFO - 1498753129266 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129268 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129272 Marionette TRACE 395 <- [1,1,null,{"sessionId":"e4f47e97-887e-4de4-8353-889abbec9fdc","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129274 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:18:49 INFO - 1498753129275 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:49 INFO - 1498753129278 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:49 INFO - 1498753129279 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:18:49 INFO - 1498753129295 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:18:49 INFO - 1498753129299 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:18:49 INFO - 1498753129303 Marionette TRACE 395 <- [1,2,null,{}]
09:18:49 INFO - 1498753129311 Marionette TRACE 395 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:18:49 INFO - 1498753129315 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7991aae9-9961-45d3-9d9e-75866d45806e","ELEMENT":"7991aae9-9961-45d3-9d9e-75866d45806e"}}]
09:18:49 INFO - 1498753129315 Marionette TRACE 395 -> [0,4,"clickElement",{"id":"7991aae9-9961-45d3-9d9e-75866d45806e"}]
09:18:49 INFO - 1498753129342 Marionette TRACE 395 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
09:18:49 INFO - 1498753129345 Marionette TRACE 395 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:18:49 INFO - TEST-END: test_click.py TestClick.test_input_file1498753129348 Marionette TRACE 395 <- [1,5,null,{"value":null}]
09:18:49 INFO - 1498753129349 Marionette TRACE 395 -> [0,6,"deleteSession",{}]
09:18:49 INFO - 1498753129350 Marionette TRACE 395 <- [1,6,null,{}]
09:18:49 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 233ms
09:18:49 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:18:49 INFO - 1498753129353 Marionette DEBUG Closed connection 395
09:18:49 INFO - 1498753129353 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49954
09:18:49 INFO - 1498753129355 Marionette DEBUG Closed connection 396
09:18:49 INFO - 1498753129356 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49955
09:18:49 INFO - 1498753129385 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129386 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129388 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129392 Marionette TRACE 397 <- [1,1,null,{"sessionId":"7b15fdcb-5027-4004-987e-d22c5003fcff","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129393 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
09:18:49 INFO - 1498753129394 Marionette TRACE 397 <- [1,2,null,{}]
09:18:49 INFO - 1498753129395 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:49 INFO - 1498753129395 Marionette TRACE 397 <- [1,3,null,{}]
09:18:49 INFO - 1498753129396 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:49 INFO - 1498753129397 Marionette TRACE 397 <- [1,4,null,{}]
09:18:49 INFO - 1498753129399 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:18:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498753129402 Marionette TRACE 397 <- [1,5,null,{"value":null}]
09:18:49 INFO - 1498753129410 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
09:18:49 INFO - 1498753129411 Marionette TRACE 397 <- [1,6,null,{}]
09:18:49 INFO - 1498753129413 Marionette DEBUG Closed connection 397
09:18:49 INFO - 1498753129414 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49956
09:18:49 INFO - 1498753129415 Marionette DEBUG Closed connection 398
09:18:49 INFO - 1498753129416 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49957
09:18:49 INFO - 1498753129417 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129418 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129419 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129424 Marionette TRACE 399 <- [1,1,null,{"sessionId":"1306b4cf-316e-47a3-88b5-bb2695c6639d","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129425 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
09:18:49 INFO - 1498753129427 Marionette TRACE 399 <- [1,2,null,{}]
09:18:49 INFO - 1498753129429 Marionette DEBUG Closed connection 399
09:18:49 INFO - 1498753129431 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49958
09:18:49 INFO - 1498753129432 Marionette DEBUG Closed connection 400
09:18:49 INFO - 1498753129432 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49959
09:18:49 INFO - 1498753129433 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:49 INFO - 1498753129434 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129436 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129440 Marionette TRACE 401 <- [1,1,null,{"sessionId":"633af3eb-f626-4427-9708-35e56a456baa","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129441 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:18:49 INFO - 1498753129442 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:18:49 INFO - 1498753129445 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:18:49 INFO - 1498753129446 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:18:49 INFO - 1498753129467 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:18:49 INFO - 1498753129471 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:18:49 INFO - 1498753129482 Marionette TRACE 401 <- [1,2,null,{}]
09:18:49 INFO - 1498753129483 Marionette TRACE 401 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:18:49 INFO - 1498753129485 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b976483-9ea7-483e-b783-3a8fc9c575e1","ELEMENT":"7b976483-9ea7-483e-b783-3a8fc9c575e1"}}]
09:18:49 INFO - 1498753129487 Marionette TRACE 401 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:18:49 INFO - 1498753129490 Marionette TRACE 401 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f533ea44-e3b5-4835-89eb-63c7daef941c","ELEMENT":"f533ea44-e3b5-4835-89eb-63c7daef941c"}}]
09:18:49 INFO - 1498753129492 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"7b976483-9ea7-483e-b783-3a8fc9c575e1"}]
09:18:49 INFO - 1498753129730 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:18:49 INFO - 1498753129731 Marionette TRACE 401 <- [1,5,null,{}]
09:18:49 INFO - 1498753129732 Marionette TRACE 401 -> [0,6,"clickElement",{"id":"f533ea44-e3b5-4835-89eb-63c7daef941c"}]
09:18:49 INFO - 1498753129748 Marionette TRACE 401 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
09:18:49 INFO - 1498753129750 Marionette TRACE 401 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:18:49 INFO - 1498753129753 Marionette TRACE 401 <- [1,7,null,{"value":false}]
09:18:49 INFO - 1498753129754 Marionette TRACE 401 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:18:49 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498753129757 Marionette TRACE 401 <- [1,8,null,{"value":null}]
09:18:49 INFO - 1498753129758 Marionette TRACE 401 -> [0,9,"deleteSession",{}]
09:18:49 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 408ms
09:18:49 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:18:49 INFO - 1498753129759 Marionette TRACE 401 <- [1,9,null,{}]
09:18:49 INFO - 1498753129761 Marionette DEBUG Closed connection 401
09:18:49 INFO - 1498753129762 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49960
09:18:49 INFO - 1498753129763 Marionette DEBUG Closed connection 402
09:18:49 INFO - 1498753129763 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49961
09:18:49 INFO - 1498753129764 Marionette TRACE 403 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129765 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129767 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129770 Marionette TRACE 403 <- [1,1,null,{"sessionId":"8efeac9c-5056-4dc1-8b2a-5190a2790e00","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129771 Marionette TRACE 403 -> [0,2,"setTimeouts",{"script":30000}]
09:18:49 INFO - 1498753129772 Marionette TRACE 403 <- [1,2,null,{}]
09:18:49 INFO - 1498753129773 Marionette TRACE 403 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:18:49 INFO - 1498753129773 Marionette TRACE 403 <- [1,3,null,{}]
09:18:49 INFO - 1498753129774 Marionette TRACE 403 -> [0,4,"setTimeouts",{"implicit":0}]
09:18:49 INFO - 1498753129774 Marionette TRACE 403 <- [1,4,null,{}]
09:18:49 INFO - 1498753129776 Marionette TRACE 403 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:18:49 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498753129779 Marionette TRACE 403 <- [1,5,null,{"value":null}]
09:18:49 INFO - 1498753129780 Marionette TRACE 403 -> [0,6,"deleteSession",{}]
09:18:49 INFO - 1498753129781 Marionette TRACE 403 <- [1,6,null,{}]
09:18:49 INFO - 1498753129783 Marionette DEBUG Closed connection 403
09:18:49 INFO - 1498753129783 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49962
09:18:49 INFO - 1498753129784 Marionette DEBUG Closed connection 404
09:18:49 INFO - 1498753129785 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49963
09:18:49 INFO - 1498753129787 Marionette TRACE 405 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:18:49 INFO - 1498753129788 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129790 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129793 Marionette TRACE 405 <- [1,1,null,{"sessionId":"7a8fceaa-369e-4277-b106-879361f41dd5","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129794 Marionette TRACE 405 -> [0,2,"deleteSession",{}]
09:18:49 INFO - 1498753129795 Marionette TRACE 405 <- [1,2,null,{}]
09:18:49 INFO - 1498753129797 Marionette DEBUG Closed connection 405
09:18:49 INFO - 1498753129798 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49964
09:18:49 INFO - 1498753129799 Marionette DEBUG Closed connection 406
09:18:49 INFO - 1498753129799 Marionette DEBUG Accepted connection 407 from 127.0.0.1:49965
09:18:49 INFO - 1498753129800 Marionette TRACE 407 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:18:49 INFO - 1498753129801 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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}
09:18:49 INFO - 1498753129803 Marionette DEBUG Register listener.js for window 7
09:18:49 INFO - 1498753129807 Marionette TRACE 407 <- [1,1,null,{"sessionId":"6ff511c7-792f-40b0-a10c-ed7c4bedf6d5","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":1232,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmp9vxmdc.mozrunner","moz:accessibilityChecks":false}}]
09:18:49 INFO - 1498753129810 Marionette TRACE 407 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:18:49 INFO - 1498753129811 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:18:49 INFO - 1498753129814 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:18:49 INFO - 1498753129814 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:18:49 INFO - 1498753129841 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:18:49 INFO - 1498753129843 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:18:49 INFO - 1498753129846 Marionette TRACE 407 <- [1,2,null,{}]
09:18:49 INFO - 1498753129851 Marionette TRACE 407 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:18:49 INFO - 1498753129860 Marionette TRACE 407 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99b000ea-c76e-4975-b149-14efad00d886","ELEMENT":"99b000ea-c76e-4975-b149-14efad00d886"}}]
09:18:49 INFO - 1498753129861 Marionette TRACE 407 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"99b000ea-c76e-4975-b149-14efad00d886"}]
09:18:49 INFO - 1498753129883 Marionette TRACE 407 <- [1,4,null,{"value":"none"}]
09:18:49 INFO - 1498753129884 Marionette TRACE 407 -> [0,5,"clickElement",{"id":"99b000ea-c76e-4975-b149-14efad00d886"}]
09:18:49 INFO - 1498753129899 Marionette TRACE 407 <- [1,5,{"error":"element click intercepted","message":"Element