could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:42:21 INFO - 1500309741483 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:42:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500309741486 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:42:21 INFO - 1500309741486 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:42:21 INFO - 1500309741488 Marionette TRACE 352 <- [1,6,null,{}]
09:42:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
09:42:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:42:21 INFO - 1500309741489 Marionette DEBUG Closed connection 352
09:42:21 INFO - 1500309741491 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50123
09:42:21 INFO - 1500309741491 Marionette DEBUG Closed connection 353
09:42:21 INFO - 1500309741492 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50124
09:42:21 INFO - 1500309741493 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:21 INFO - 1500309741494 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741496 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741499 Marionette TRACE 354 <- [1,1,null,{"sessionId":"34a3fcca-aebc-4a78-a285-fe5a178d0d7d","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741509 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:42:21 INFO - 1500309741509 Marionette TRACE 354 <- [1,2,null,{}]
09:42:21 INFO - 1500309741510 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:21 INFO - 1500309741510 Marionette TRACE 354 <- [1,3,null,{}]
09:42:21 INFO - 1500309741511 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:21 INFO - 1500309741512 Marionette TRACE 354 <- [1,4,null,{}]
09:42:21 INFO - 1500309741513 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:42:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500309741516 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:42:21 INFO - 1500309741517 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:42:21 INFO - 1500309741518 Marionette TRACE 354 <- [1,6,null,{}]
09:42:21 INFO - 1500309741520 Marionette DEBUG Closed connection 354
09:42:21 INFO - 1500309741521 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50125
09:42:21 INFO - 1500309741521 Marionette DEBUG Closed connection 355
09:42:21 INFO - 1500309741522 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50126
09:42:21 INFO - 1500309741523 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:21 INFO - 1500309741523 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741525 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741528 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d6e26645-ae8a-4842-8b7b-9832d5451b7e","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741530 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:42:21 INFO - 1500309741531 Marionette TRACE 356 <- [1,2,null,{}]
09:42:21 INFO - 1500309741533 Marionette DEBUG Closed connection 356
09:42:21 INFO - 1500309741533 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50127
09:42:21 INFO - 1500309741534 Marionette DEBUG Closed connection 357
09:42:21 INFO - 1500309741535 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50128
09:42:21 INFO - 1500309741535 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:21 INFO - 1500309741536 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741538 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741540 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c84b660b-be0b-460f-958a-d16a60b39b28","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741542 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49687/clicks.html"}]
09:42:21 INFO - 1500309741543 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49687/hidden.html"
09:42:21 INFO - 1500309741553 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49687/hidden.html"
09:42:21 INFO - 1500309741553 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49687/hidden.html"
09:42:21 INFO - 1500309741572 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49687/clicks.html"
09:42:21 INFO - 1500309741574 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49687/clicks.html"
09:42:21 INFO - 1500309741578 Marionette TRACE 358 <- [1,2,null,{}]
09:42:21 INFO - 1500309741579 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:42:21 INFO - 1500309741589 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db262368-8d16-41b3-87b3-1ca3b7664c7c","ELEMENT":"db262368-8d16-41b3-87b3-1ca3b7664c7c"}}]
09:42:21 INFO - 1500309741592 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"db262368-8d16-41b3-87b3-1ca3b7664c7c"}]
09:42:21 INFO - 1500309741634 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49687/clicks.html"
09:42:21 INFO - 1500309741642 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49687/clicks.html"
09:42:21 INFO - 1500309741642 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49687/clicks.html"
09:42:21 INFO - 1500309741658 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49687/test.html"
09:42:21 INFO - 1500309741661 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49687/test.html"
09:42:21 INFO - 1500309741664 Marionette TRACE 358 <- [1,4,null,{}]
09:42:21 INFO - 1500309741673 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:42:21 INFO - 1500309741677 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0db14dd7-92ac-4a35-b5b5-4246a19d6819","ELEMENT":"0db14dd7-92ac-4a35-b5b5-4246a19d6819"}}]
09:42:21 INFO - 1500309741678 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:42:21 INFO - 1500309741678 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:42:21 INFO - 1500309741680 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:42:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500309741683 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:42:21 INFO - 1500309741684 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:42:21 INFO - 1500309741686 Marionette TRACE 358 <- [1,8,null,{}]
09:42:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 198ms
09:42:21 INFO - TEST-START | test_click.py TestClick.test_container_element
09:42:21 INFO - 1500309741688 Marionette DEBUG Closed connection 358
09:42:21 INFO - 1500309741689 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50131
09:42:21 INFO - 1500309741690 Marionette DEBUG Closed connection 359
09:42:21 INFO - 1500309741691 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50132
09:42:21 INFO - 1500309741692 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:21 INFO - 1500309741693 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741695 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741697 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d0bdd749-a1ef-4131-8c30-7a404d4bec6e","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741699 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:42:21 INFO - 1500309741700 Marionette TRACE 360 <- [1,2,null,{}]
09:42:21 INFO - 1500309741701 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:21 INFO - 1500309741702 Marionette TRACE 360 <- [1,3,null,{}]
09:42:21 INFO - 1500309741702 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:21 INFO - 1500309741703 Marionette TRACE 360 <- [1,4,null,{}]
09:42:21 INFO - 1500309741705 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:42:21 INFO - TEST-START: test_click.py TestClick.test_container_element1500309741707 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:42:21 INFO - 1500309741708 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:42:21 INFO - 1500309741709 Marionette TRACE 360 <- [1,6,null,{}]
09:42:21 INFO - 1500309741711 Marionette DEBUG Closed connection 360
09:42:21 INFO - 1500309741712 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50133
09:42:21 INFO - 1500309741713 Marionette DEBUG Closed connection 361
09:42:21 INFO - 1500309741713 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50134
09:42:21 INFO - 1500309741714 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:21 INFO - 1500309741715 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741717 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741720 Marionette TRACE 362 <- [1,1,null,{"sessionId":"73830632-ee35-450c-971a-412cd87e24ab","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741722 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:42:21 INFO - 1500309741724 Marionette TRACE 362 <- [1,2,null,{}]
09:42:21 INFO - 1500309741726 Marionette DEBUG Closed connection 362
09:42:21 INFO - 1500309741727 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50135
09:42:21 INFO - 1500309741727 Marionette DEBUG Closed connection 363
09:42:21 INFO - 1500309741728 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50136
09:42:21 INFO - 1500309741729 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:21 INFO - 1500309741730 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:21 INFO - 1500309741732 Marionette DEBUG Register listener.js for window 15
09:42:21 INFO - 1500309741734 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d9307ce1-ffa3-4863-808b-fe0271f35928","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:21 INFO - 1500309741736 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:42:21 INFO - 1500309741738 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49687/test.html"
09:42:21 INFO - 1500309741741 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49687/test.html"
09:42:21 INFO - 1500309741741 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49687/test.html"
09:42:21 INFO - 1500309741771 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:42:21 INFO - 1500309741776 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:42:21 INFO - 1500309741780 Marionette TRACE 364 <- [1,2,null,{}]
09:42:21 INFO - 1500309741788 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:42:21 INFO - 1500309741791 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"48b41713-1a45-4429-a9ca-b7900bce9051","ELEMENT":"48b41713-1a45-4429-a9ca-b7900bce9051"}}]
09:42:21 INFO - 1500309741794 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"48b41713-1a45-4429-a9ca-b7900bce9051"}]
09:42:22 INFO - 1500309742031 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:42:22 INFO - 1500309742032 Marionette TRACE 364 <- [1,4,null,{}]
09:42:22 INFO - 1500309742033 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"48b41713-1a45-4429-a9ca-b7900bce9051","name":"selected"}]
09:42:22 INFO - 1500309742037 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:42:22 INFO - 1500309742039 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:42:22 INFO - TEST-END: test_click.py TestClick.test_container_element1500309742041 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:42:22 INFO - 1500309742042 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:42:22 INFO - 1500309742044 Marionette TRACE 364 <- [1,7,null,{}]
09:42:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
09:42:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:42:22 INFO - 1500309742045 Marionette DEBUG Closed connection 364
09:42:22 INFO - 1500309742046 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50139
09:42:22 INFO - 1500309742047 Marionette DEBUG Closed connection 365
09:42:22 INFO - 1500309742048 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50140
09:42:22 INFO - 1500309742050 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742051 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742053 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742056 Marionette TRACE 366 <- [1,1,null,{"sessionId":"25af3a7b-e565-4c3a-b400-f103cd82fcad","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742057 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:42:22 INFO - 1500309742058 Marionette TRACE 366 <- [1,2,null,{}]
09:42:22 INFO - 1500309742058 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:22 INFO - 1500309742059 Marionette TRACE 366 <- [1,3,null,{}]
09:42:22 INFO - 1500309742060 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:22 INFO - 1500309742060 Marionette TRACE 366 <- [1,4,null,{}]
09:42:22 INFO - 1500309742062 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:42:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500309742064 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:42:22 INFO - 1500309742065 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:42:22 INFO - 1500309742066 Marionette TRACE 366 <- [1,6,null,{}]
09:42:22 INFO - 1500309742068 Marionette DEBUG Closed connection 366
09:42:22 INFO - 1500309742069 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50141
09:42:22 INFO - 1500309742070 Marionette DEBUG Closed connection 367
09:42:22 INFO - 1500309742070 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50142
09:42:22 INFO - 1500309742071 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742072 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742082 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742085 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5808e2f5-0fc1-4ae8-9b93-698d79cd6917","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742087 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:42:22 INFO - 1500309742088 Marionette TRACE 368 <- [1,2,null,{}]
09:42:22 INFO - 1500309742090 Marionette DEBUG Closed connection 368
09:42:22 INFO - 1500309742090 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50143
09:42:22 INFO - 1500309742091 Marionette DEBUG Closed connection 369
09:42:22 INFO - 1500309742092 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50144
09:42:22 INFO - 1500309742093 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:22 INFO - 1500309742094 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742096 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742098 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8c4f7d2f-81ba-4aca-9991-ca816e5d50b2","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742100 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:42:22 INFO - 1500309742103 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:42:22 INFO - 1500309742105 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:42:22 INFO - 1500309742106 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:42:22 INFO - 1500309742123 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:42:22 INFO - 1500309742124 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:42:22 INFO - 1500309742127 Marionette TRACE 370 <- [1,2,null,{}]
09:42:22 INFO - 1500309742129 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:42:22 INFO - 1500309742136 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"523628bf-3301-4749-b4fc-5db16f7cb03f","ELEMENT":"523628bf-3301-4749-b4fc-5db16f7cb03f"}}]
09:42:22 INFO - 1500309742139 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"523628bf-3301-4749-b4fc-5db16f7cb03f"}]
09:42:22 INFO - 1500309742377 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:42:22 INFO - 1500309742378 Marionette TRACE 370 <- [1,4,null,{}]
09:42:22 INFO - 1500309742379 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"523628bf-3301-4749-b4fc-5db16f7cb03f","name":"selected"}]
09:42:22 INFO - 1500309742383 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:42:22 INFO - 1500309742385 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:42:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500309742387 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:42:22 INFO - 1500309742388 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:42:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 346ms
09:42:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:42:22 INFO - 1500309742389 Marionette TRACE 370 <- [1,7,null,{}]
09:42:22 INFO - 1500309742391 Marionette DEBUG Closed connection 370
09:42:22 INFO - 1500309742392 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50145
09:42:22 INFO - 1500309742393 Marionette DEBUG Closed connection 371
09:42:22 INFO - 1500309742394 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50146
09:42:22 INFO - 1500309742395 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742395 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742397 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742400 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7da22e5e-287f-491e-ad35-79f5788e2352","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742402 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:42:22 INFO - 1500309742402 Marionette TRACE 372 <- [1,2,null,{}]
09:42:22 INFO - 1500309742403 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:22 INFO - 1500309742404 Marionette TRACE 372 <- [1,3,null,{}]
09:42:22 INFO - 1500309742404 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:22 INFO - 1500309742405 Marionette TRACE 372 <- [1,4,null,{}]
09:42:22 INFO - 1500309742407 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:42:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500309742409 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:42:22 INFO - 1500309742410 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:42:22 INFO - 1500309742411 Marionette TRACE 372 <- [1,6,null,{}]
09:42:22 INFO - 1500309742413 Marionette DEBUG Closed connection 372
09:42:22 INFO - 1500309742413 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50147
09:42:22 INFO - 1500309742414 Marionette DEBUG Closed connection 373
09:42:22 INFO - 1500309742415 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50148
09:42:22 INFO - 1500309742416 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742416 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742418 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742421 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e8a15580-2267-482f-90c0-f95eeae189f0","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742423 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:42:22 INFO - 1500309742424 Marionette TRACE 374 <- [1,2,null,{}]
09:42:22 INFO - 1500309742426 Marionette DEBUG Closed connection 374
09:42:22 INFO - 1500309742426 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50149
09:42:22 INFO - 1500309742427 Marionette DEBUG Closed connection 375
09:42:22 INFO - 1500309742428 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50150
09:42:22 INFO - 1500309742429 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:22 INFO - 1500309742429 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742431 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742434 Marionette TRACE 376 <- [1,1,null,{"sessionId":"37bbce54-2cb8-470d-a4e4-52d4306d0d70","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742436 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:42:22 INFO - 1500309742438 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:42:22 INFO - 1500309742440 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:42:22 INFO - 1500309742441 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:42:22 INFO - 1500309742462 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:42:22 INFO - 1500309742464 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:42:22 INFO - 1500309742474 Marionette TRACE 376 <- [1,2,null,{}]
09:42:22 INFO - 1500309742476 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:42:22 INFO - 1500309742478 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a9224a84-5a43-4777-b59b-fde615845fc8","ELEMENT":"a9224a84-5a43-4777-b59b-fde615845fc8"}}]
09:42:22 INFO - 1500309742479 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a9224a84-5a43-4777-b59b-fde615845fc8"}]
09:42:22 INFO - 1500309742716 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:42:22 INFO - 1500309742717 Marionette TRACE 376 <- [1,4,null,{}]
09:42:22 INFO - 1500309742719 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:42:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500309742722 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:42:22 INFO - 1500309742723 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:42:22 INFO - 1500309742724 Marionette TRACE 376 <- [1,6,null,{}]
09:42:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 334ms
09:42:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:42:22 INFO - 1500309742726 Marionette DEBUG Closed connection 376
09:42:22 INFO - 1500309742727 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50151
09:42:22 INFO - 1500309742727 Marionette DEBUG Closed connection 377
09:42:22 INFO - 1500309742728 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50152
09:42:22 INFO - 1500309742729 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742730 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742732 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742735 Marionette TRACE 378 <- [1,1,null,{"sessionId":"46c2c6a7-90fd-4c51-9ebb-93beb8073541","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742737 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:42:22 INFO - 1500309742737 Marionette TRACE 378 <- [1,2,null,{}]
09:42:22 INFO - 1500309742738 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:22 INFO - 1500309742739 Marionette TRACE 378 <- [1,3,null,{}]
09:42:22 INFO - 1500309742739 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:22 INFO - 1500309742740 Marionette TRACE 378 <- [1,4,null,{}]
09:42:22 INFO - 1500309742741 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:42:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500309742744 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:42:22 INFO - 1500309742745 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:42:22 INFO - 1500309742746 Marionette TRACE 378 <- [1,6,null,{}]
09:42:22 INFO - 1500309742748 Marionette DEBUG Closed connection 378
09:42:22 INFO - 1500309742749 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50153
09:42:22 INFO - 1500309742749 Marionette DEBUG Closed connection 379
09:42:22 INFO - 1500309742750 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50154
09:42:22 INFO - 1500309742751 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:22 INFO - 1500309742752 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742754 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742764 Marionette TRACE 380 <- [1,1,null,{"sessionId":"fd314f23-0d9d-4b85-b3f7-173826f35109","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742767 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:42:22 INFO - 1500309742768 Marionette TRACE 380 <- [1,2,null,{}]
09:42:22 INFO - 1500309742770 Marionette DEBUG Closed connection 380
09:42:22 INFO - 1500309742770 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50155
09:42:22 INFO - 1500309742771 Marionette DEBUG Closed connection 381
09:42:22 INFO - 1500309742772 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50156
09:42:22 INFO - 1500309742773 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:22 INFO - 1500309742773 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:22 INFO - 1500309742775 Marionette DEBUG Register listener.js for window 15
09:42:22 INFO - 1500309742778 Marionette TRACE 382 <- [1,1,null,{"sessionId":"bc2a62e5-a17c-45d5-bdde-ed8b8234690f","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:22 INFO - 1500309742780 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:42:22 INFO - 1500309742781 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:42:22 INFO - 1500309742783 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:42:22 INFO - 1500309742784 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:42:22 INFO - 1500309742794 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:42:22 INFO - 1500309742798 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:42:22 INFO - 1500309742808 Marionette TRACE 382 <- [1,2,null,{}]
09:42:22 INFO - 1500309742809 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:42:22 INFO - 1500309742813 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46a5db01-432e-4bce-bd09-1e83eb0ff809","ELEMENT":"46a5db01-432e-4bce-bd09-1e83eb0ff809"}}]
09:42:22 INFO - 1500309742813 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"46a5db01-432e-4bce-bd09-1e83eb0ff809"}]
09:42:23 INFO - 1500309743053 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:42:23 INFO - 1500309743054 Marionette TRACE 382 <- [1,4,null,{}]
09:42:23 INFO - 1500309743056 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:42:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500309743059 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:42:23 INFO - 1500309743060 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:42:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 337ms
09:42:23 INFO - TEST-START | test_click.py TestClick.test_input_file
09:42:23 INFO - 1500309743061 Marionette TRACE 382 <- [1,6,null,{}]
09:42:23 INFO - 1500309743063 Marionette DEBUG Closed connection 382
09:42:23 INFO - 1500309743064 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50157
09:42:23 INFO - 1500309743065 Marionette DEBUG Closed connection 383
09:42:23 INFO - 1500309743066 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50158
09:42:23 INFO - 1500309743067 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743067 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743069 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743072 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f476b384-e1f0-4024-a754-30a9b4331f01","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743074 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:42:23 INFO - 1500309743074 Marionette TRACE 384 <- [1,2,null,{}]
09:42:23 INFO - 1500309743075 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:23 INFO - 1500309743075 Marionette TRACE 384 <- [1,3,null,{}]
09:42:23 INFO - 1500309743076 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:23 INFO - 1500309743077 Marionette TRACE 384 <- [1,4,null,{}]
09:42:23 INFO - 1500309743078 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:42:23 INFO - TEST-START: test_click.py TestClick.test_input_file1500309743081 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:42:23 INFO - 1500309743081 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:42:23 INFO - 1500309743083 Marionette TRACE 384 <- [1,6,null,{}]
09:42:23 INFO - 1500309743084 Marionette DEBUG Closed connection 384
09:42:23 INFO - 1500309743085 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50159
09:42:23 INFO - 1500309743086 Marionette DEBUG Closed connection 385
09:42:23 INFO - 1500309743087 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50160
09:42:23 INFO - 1500309743088 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743088 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743090 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743093 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cd8df14a-09e8-46e6-b4e0-271cce6b1e9d","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743095 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:42:23 INFO - 1500309743096 Marionette TRACE 386 <- [1,2,null,{}]
09:42:23 INFO - 1500309743097 Marionette DEBUG Closed connection 386
09:42:23 INFO - 1500309743098 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50161
09:42:23 INFO - 1500309743099 Marionette DEBUG Closed connection 387
09:42:23 INFO - 1500309743100 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50162
09:42:23 INFO - 1500309743100 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:23 INFO - 1500309743101 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743103 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743106 Marionette TRACE 388 <- [1,1,null,{"sessionId":"66ebe67e-4057-4456-a2d7-082a2e4f944c","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743108 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:42:23 INFO - 1500309743109 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:42:23 INFO - 1500309743111 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:42:23 INFO - 1500309743111 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:42:23 INFO - 1500309743127 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:42:23 INFO - 1500309743130 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:42:23 INFO - 1500309743133 Marionette TRACE 388 <- [1,2,null,{}]
09:42:23 INFO - 1500309743141 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:42:23 INFO - 1500309743143 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"407c413f-7ec0-4d88-a547-8d016384f4ec","ELEMENT":"407c413f-7ec0-4d88-a547-8d016384f4ec"}}]
09:42:23 INFO - 1500309743144 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"407c413f-7ec0-4d88-a547-8d016384f4ec"}]
09:42:23 INFO - 1500309743170 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:42:23 INFO - 1500309743171 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:42:23 INFO - TEST-END: test_click.py TestClick.test_input_file1500309743174 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:42:23 INFO - 1500309743175 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:42:23 INFO - 1500309743176 Marionette TRACE 388 <- [1,6,null,{}]
09:42:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
09:42:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:42:23 INFO - 1500309743178 Marionette DEBUG Closed connection 388
09:42:23 INFO - 1500309743179 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50163
09:42:23 INFO - 1500309743180 Marionette DEBUG Closed connection 389
09:42:23 INFO - 1500309743180 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50164
09:42:23 INFO - 1500309743181 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743182 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743184 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743187 Marionette TRACE 390 <- [1,1,null,{"sessionId":"57f45f63-c23a-481a-a562-44ccd57107bb","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743189 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:42:23 INFO - 1500309743189 Marionette TRACE 390 <- [1,2,null,{}]
09:42:23 INFO - 1500309743190 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:23 INFO - 1500309743191 Marionette TRACE 390 <- [1,3,null,{}]
09:42:23 INFO - 1500309743191 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:23 INFO - 1500309743192 Marionette TRACE 390 <- [1,4,null,{}]
09:42:23 INFO - 1500309743194 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:42:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500309743196 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:42:23 INFO - 1500309743197 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:42:23 INFO - 1500309743198 Marionette TRACE 390 <- [1,6,null,{}]
09:42:23 INFO - 1500309743200 Marionette DEBUG Closed connection 390
09:42:23 INFO - 1500309743200 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50165
09:42:23 INFO - 1500309743201 Marionette DEBUG Closed connection 391
09:42:23 INFO - 1500309743202 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50166
09:42:23 INFO - 1500309743203 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743204 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743206 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743208 Marionette TRACE 392 <- [1,1,null,{"sessionId":"25f933c1-0255-43e9-b1d9-f6c0083226f6","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743210 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:42:23 INFO - 1500309743211 Marionette TRACE 392 <- [1,2,null,{}]
09:42:23 INFO - 1500309743213 Marionette DEBUG Closed connection 392
09:42:23 INFO - 1500309743213 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50167
09:42:23 INFO - 1500309743214 Marionette DEBUG Closed connection 393
09:42:23 INFO - 1500309743215 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50168
09:42:23 INFO - 1500309743216 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:23 INFO - 1500309743216 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743218 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743229 Marionette TRACE 394 <- [1,1,null,{"sessionId":"97e64aaa-90fb-49e6-be34-346b726659fa","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743231 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:42:23 INFO - 1500309743232 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:42:23 INFO - 1500309743234 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:42:23 INFO - 1500309743235 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:42:23 INFO - 1500309743245 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:42:23 INFO - 1500309743248 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:42:23 INFO - 1500309743258 Marionette TRACE 394 <- [1,2,null,{}]
09:42:23 INFO - 1500309743260 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:42:23 INFO - 1500309743262 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"322ad0d5-3b41-4a90-9e58-10781e2fa95c","ELEMENT":"322ad0d5-3b41-4a90-9e58-10781e2fa95c"}}]
09:42:23 INFO - 1500309743263 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:42:23 INFO - 1500309743265 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"312ee21d-8911-41b1-9107-ff9a0f778188","ELEMENT":"312ee21d-8911-41b1-9107-ff9a0f778188"}}]
09:42:23 INFO - 1500309743266 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"322ad0d5-3b41-4a90-9e58-10781e2fa95c"}]
09:42:23 INFO - 1500309743500 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:42:23 INFO - 1500309743501 Marionette TRACE 394 <- [1,5,null,{}]
09:42:23 INFO - 1500309743502 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"312ee21d-8911-41b1-9107-ff9a0f778188"}]
09:42:23 INFO - 1500309743516 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:42:23 INFO - 1500309743518 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:42:23 INFO - 1500309743521 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:42:23 INFO - 1500309743522 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:42:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500309743524 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:42:23 INFO - 1500309743525 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:42:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 350ms
09:42:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:42:23 INFO - 1500309743526 Marionette TRACE 394 <- [1,9,null,{}]
09:42:23 INFO - 1500309743528 Marionette DEBUG Closed connection 394
09:42:23 INFO - 1500309743529 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50169
09:42:23 INFO - 1500309743530 Marionette DEBUG Closed connection 395
09:42:23 INFO - 1500309743531 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50170
09:42:23 INFO - 1500309743532 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743532 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743534 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743537 Marionette TRACE 396 <- [1,1,null,{"sessionId":"0ed722ca-7ee0-4f55-973b-32d8f83c032b","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743539 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:42:23 INFO - 1500309743539 Marionette TRACE 396 <- [1,2,null,{}]
09:42:23 INFO - 1500309743540 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:42:23 INFO - 1500309743540 Marionette TRACE 396 <- [1,3,null,{}]
09:42:23 INFO - 1500309743541 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:42:23 INFO - 1500309743542 Marionette TRACE 396 <- [1,4,null,{}]
09:42:23 INFO - 1500309743543 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:42:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500309743546 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:42:23 INFO - 1500309743547 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:42:23 INFO - 1500309743549 Marionette TRACE 396 <- [1,6,null,{}]
09:42:23 INFO - 1500309743550 Marionette DEBUG Closed connection 396
09:42:23 INFO - 1500309743551 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50171
09:42:23 INFO - 1500309743552 Marionette DEBUG Closed connection 397
09:42:23 INFO - 1500309743553 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50172
09:42:23 INFO - 1500309743553 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:42:23 INFO - 1500309743554 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743556 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743559 Marionette TRACE 398 <- [1,1,null,{"sessionId":"06e7a6e3-0a91-4314-9c66-d820ee8ae82d","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743561 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:42:23 INFO - 1500309743562 Marionette TRACE 398 <- [1,2,null,{}]
09:42:23 INFO - 1500309743564 Marionette DEBUG Closed connection 398
09:42:23 INFO - 1500309743564 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50173
09:42:23 INFO - 1500309743565 Marionette DEBUG Closed connection 399
09:42:23 INFO - 1500309743566 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50174
09:42:23 INFO - 1500309743567 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:42:23 INFO - 1500309743567 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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}
09:42:23 INFO - 1500309743569 Marionette DEBUG Register listener.js for window 15
09:42:23 INFO - 1500309743573 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4c5d551c-e233-4284-a38a-262336a5371b","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":3504,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpge1n3t.mozrunner","moz:accessibilityChecks":false}}]
09:42:23 INFO - 1500309743578 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:42:23 INFO - 1500309743579 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:42:23 INFO - 1500309743581 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:42:23 INFO - 1500309743582 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:42:23 INFO - 1500309743601 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:42:23 INFO - 1500309743603 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:42:23 INFO - 1500309743606 Marionette TRACE 400 <- [1,2,null,{}]
09:42:23 INFO - 1500309743607 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:42:23 INFO - 1500309743610 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f1466f3-6520-4b1e-a462-25cd4082fce0","ELEMENT":"1f1466f3-6520-4b1e-a462-25cd4082fce0"}}]
09:42:23 INFO - 1500309743611 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1f1466f3-6520-4b1e-a462-25cd4082fce0"}]
09:42:23 INFO - 1500309743638 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:42:23 INFO - 1500309743639 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1f1466f3-6520-4b1e-a462-25cd4082fce0"}]
09:42:23 INFO - 1500309743658 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element