could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:05:03 INFO - 1499537103355 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}]
11:05:03 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499537103359 Marionette TRACE 352 <- [1,5,null,{"value":null}]
11:05:03 INFO - 1499537103360 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
11:05:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 141ms
11:05:03 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:05:03 INFO - 1499537103361 Marionette TRACE 352 <- [1,6,null,{}]
11:05:03 INFO - 1499537103363 Marionette DEBUG Closed connection 352
11:05:03 INFO - 1499537103364 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49803
11:05:03 INFO - 1499537103365 Marionette DEBUG Closed connection 353
11:05:03 INFO - 1499537103366 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49804
11:05:03 INFO - 1499537103367 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103367 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103370 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103374 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7d832a50-c42e-455a-a007-61217df73806","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103375 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
11:05:03 INFO - 1499537103376 Marionette TRACE 354 <- [1,2,null,{}]
11:05:03 INFO - 1499537103377 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:03 INFO - 1499537103378 Marionette TRACE 354 <- [1,3,null,{}]
11:05:03 INFO - 1499537103378 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:03 INFO - 1499537103379 Marionette TRACE 354 <- [1,4,null,{}]
11:05:03 INFO - 1499537103381 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}]
11:05:03 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499537103384 Marionette TRACE 354 <- [1,5,null,{"value":null}]
11:05:03 INFO - 1499537103385 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
11:05:03 INFO - 1499537103386 Marionette TRACE 354 <- [1,6,null,{}]
11:05:03 INFO - 1499537103388 Marionette DEBUG Closed connection 354
11:05:03 INFO - 1499537103389 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49805
11:05:03 INFO - 1499537103390 Marionette DEBUG Closed connection 355
11:05:03 INFO - 1499537103391 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49806
11:05:03 INFO - 1499537103392 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103392 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103395 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103398 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1ed5056f-c942-48a6-9dc6-4ebb159c8960","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103400 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
11:05:03 INFO - 1499537103401 Marionette TRACE 356 <- [1,2,null,{}]
11:05:03 INFO - 1499537103403 Marionette DEBUG Closed connection 356
11:05:03 INFO - 1499537103404 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49807
11:05:03 INFO - 1499537103404 Marionette DEBUG Closed connection 357
11:05:03 INFO - 1499537103405 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49808
11:05:03 INFO - 1499537103406 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:03 INFO - 1499537103407 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103410 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103413 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e16114cd-2737-4c07-b61d-4f1a17a5f104","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103414 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49359/clicks.html"}]
11:05:03 INFO - 1499537103416 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/hidden.html"
11:05:03 INFO - 1499537103430 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/hidden.html"
11:05:03 INFO - 1499537103430 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/hidden.html"
11:05:03 INFO - 1499537103437 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49359/clicks.html"
11:05:03 INFO - 1499537103443 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49359/clicks.html"
11:05:03 INFO - 1499537103453 Marionette TRACE 358 <- [1,2,null,{}]
11:05:03 INFO - 1499537103466 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:05:03 INFO - 1499537103469 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d38e976c-35f5-43ad-9034-d0bb93d0d5bd","ELEMENT":"d38e976c-35f5-43ad-9034-d0bb93d0d5bd"}}]
11:05:03 INFO - 1499537103471 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d38e976c-35f5-43ad-9034-d0bb93d0d5bd"}]
11:05:03 INFO - 1499537103513 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/clicks.html"
11:05:03 INFO - 1499537103523 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/clicks.html"
11:05:03 INFO - 1499537103523 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/clicks.html"
11:05:03 INFO - 1499537103533 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49359/test.html"
11:05:03 INFO - 1499537103540 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49359/test.html"
11:05:03 INFO - 1499537103544 Marionette TRACE 358 <- [1,4,null,{}]
11:05:03 INFO - 1499537103549 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:05:03 INFO - 1499537103553 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3736807b-fe77-4c60-87c6-e53e3fec700f","ELEMENT":"3736807b-fe77-4c60-87c6-e53e3fec700f"}}]
11:05:03 INFO - 1499537103554 Marionette TRACE 358 -> [0,6,"getTitle",{}]
11:05:03 INFO - 1499537103554 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
11:05:03 INFO - 1499537103556 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}]
11:05:03 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499537103576 Marionette TRACE 358 <- [1,7,null,{"value":null}]
11:05:03 INFO - 1499537103577 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
11:05:03 INFO - 1499537103579 Marionette TRACE 358 <- [1,8,null,{}]
11:05:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 217ms
11:05:03 INFO - TEST-START | test_click.py TestClick.test_container_element
11:05:03 INFO - 1499537103580 Marionette DEBUG Closed connection 358
11:05:03 INFO - 1499537103581 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49811
11:05:03 INFO - 1499537103582 Marionette DEBUG Closed connection 359
11:05:03 INFO - 1499537103583 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49812
11:05:03 INFO - 1499537103584 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103584 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103587 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103591 Marionette TRACE 360 <- [1,1,null,{"sessionId":"919f5726-a571-402a-acf5-fe6b52457e32","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103592 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:05:03 INFO - 1499537103593 Marionette TRACE 360 <- [1,2,null,{}]
11:05:03 INFO - 1499537103594 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:03 INFO - 1499537103594 Marionette TRACE 360 <- [1,3,null,{}]
11:05:03 INFO - 1499537103595 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:03 INFO - 1499537103596 Marionette TRACE 360 <- [1,4,null,{}]
11:05:03 INFO - 1499537103598 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}]
11:05:03 INFO - TEST-START: test_click.py TestClick.test_container_element1499537103601 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:05:03 INFO - 1499537103602 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:05:03 INFO - 1499537103603 Marionette TRACE 360 <- [1,6,null,{}]
11:05:03 INFO - 1499537103605 Marionette DEBUG Closed connection 360
11:05:03 INFO - 1499537103606 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49813
11:05:03 INFO - 1499537103607 Marionette DEBUG Closed connection 361
11:05:03 INFO - 1499537103608 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49814
11:05:03 INFO - 1499537103609 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103610 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103612 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103616 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1e0062b1-46cf-47e2-b649-bc936ea6d0fe","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103617 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:05:03 INFO - 1499537103618 Marionette TRACE 362 <- [1,2,null,{}]
11:05:03 INFO - 1499537103620 Marionette DEBUG Closed connection 362
11:05:03 INFO - 1499537103621 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49815
11:05:03 INFO - 1499537103622 Marionette DEBUG Closed connection 363
11:05:03 INFO - 1499537103623 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49816
11:05:03 INFO - 1499537103624 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:03 INFO - 1499537103624 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103627 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103631 Marionette TRACE 364 <- [1,1,null,{"sessionId":"aeccba05-506e-4270-9edc-b523abb908fa","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103632 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"}]
11:05:03 INFO - 1499537103634 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/test.html"
11:05:03 INFO - 1499537103637 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/test.html"
11:05:03 INFO - 1499537103637 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/test.html"
11:05:03 INFO - 1499537103654 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"
11:05:03 INFO - 1499537103659 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"
11:05:03 INFO - 1499537103663 Marionette TRACE 364 <- [1,2,null,{}]
11:05:03 INFO - 1499537103668 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:05:03 INFO - 1499537103678 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"78367fe0-bcfb-4cf3-a81d-4428e4a7e682","ELEMENT":"78367fe0-bcfb-4cf3-a81d-4428e4a7e682"}}]
11:05:03 INFO - 1499537103679 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"78367fe0-bcfb-4cf3-a81d-4428e4a7e682"}]
11:05:03 INFO - 1499537103921 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:05:03 INFO - 1499537103922 Marionette TRACE 364 <- [1,4,null,{}]
11:05:03 INFO - 1499537103923 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"78367fe0-bcfb-4cf3-a81d-4428e4a7e682","name":"selected"}]
11:05:03 INFO - 1499537103928 Marionette TRACE 364 <- [1,5,null,{"value":true}]
11:05:03 INFO - 1499537103930 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}]
11:05:03 INFO - TEST-END: test_click.py TestClick.test_container_element1499537103933 Marionette TRACE 364 <- [1,6,null,{"value":null}]
11:05:03 INFO - 1499537103934 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
11:05:03 INFO - 1499537103936 Marionette TRACE 364 <- [1,7,null,{}]
11:05:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
11:05:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:05:03 INFO - 1499537103938 Marionette DEBUG Closed connection 364
11:05:03 INFO - 1499537103938 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49817
11:05:03 INFO - 1499537103939 Marionette DEBUG Closed connection 365
11:05:03 INFO - 1499537103940 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49818
11:05:03 INFO - 1499537103941 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103942 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103945 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103949 Marionette TRACE 366 <- [1,1,null,{"sessionId":"847d0dac-820d-4319-81da-c16f5ed7b96d","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103950 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:05:03 INFO - 1499537103950 Marionette TRACE 366 <- [1,2,null,{}]
11:05:03 INFO - 1499537103951 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:03 INFO - 1499537103952 Marionette TRACE 366 <- [1,3,null,{}]
11:05:03 INFO - 1499537103953 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:03 INFO - 1499537103953 Marionette TRACE 366 <- [1,4,null,{}]
11:05:03 INFO - 1499537103955 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}]
11:05:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499537103958 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:05:03 INFO - 1499537103959 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:05:03 INFO - 1499537103960 Marionette TRACE 366 <- [1,6,null,{}]
11:05:03 INFO - 1499537103962 Marionette DEBUG Closed connection 366
11:05:03 INFO - 1499537103963 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49819
11:05:03 INFO - 1499537103964 Marionette DEBUG Closed connection 367
11:05:03 INFO - 1499537103965 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49820
11:05:03 INFO - 1499537103966 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:03 INFO - 1499537103966 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103969 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103973 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0026ab3b-9cbe-4d4d-8335-33fc64ee2705","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103974 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:05:03 INFO - 1499537103975 Marionette TRACE 368 <- [1,2,null,{}]
11:05:03 INFO - 1499537103977 Marionette DEBUG Closed connection 368
11:05:03 INFO - 1499537103978 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49821
11:05:03 INFO - 1499537103979 Marionette DEBUG Closed connection 369
11:05:03 INFO - 1499537103980 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49822
11:05:03 INFO - 1499537103981 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:03 INFO - 1499537103981 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:03 INFO - 1499537103984 Marionette DEBUG Register listener.js for window 4294967297
11:05:03 INFO - 1499537103988 Marionette TRACE 370 <- [1,1,null,{"sessionId":"67d510e5-2230-4a02-9a88-630f69c8a3f9","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:03 INFO - 1499537103989 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"}]
11:05:03 INFO - 1499537103992 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"
11:05:03 INFO - 1499537103994 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"
11:05:03 INFO - 1499537103994 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"
11:05:04 INFO - 1499537104001 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"
11:05:04 INFO - 1499537104005 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"
11:05:04 INFO - 1499537104014 Marionette TRACE 370 <- [1,2,null,{}]
11:05:04 INFO - 1499537104019 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:05:04 INFO - 1499537104022 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fec1aa0d-38db-4920-af7e-06471818f95b","ELEMENT":"fec1aa0d-38db-4920-af7e-06471818f95b"}}]
11:05:04 INFO - 1499537104028 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fec1aa0d-38db-4920-af7e-06471818f95b"}]
11:05:04 INFO - 1499537104271 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:05:04 INFO - 1499537104272 Marionette TRACE 370 <- [1,4,null,{}]
11:05:04 INFO - 1499537104273 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fec1aa0d-38db-4920-af7e-06471818f95b","name":"selected"}]
11:05:04 INFO - 1499537104278 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:05:04 INFO - 1499537104280 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}]
11:05:04 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499537104283 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:05:04 INFO - 1499537104284 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:05:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 350ms
11:05:04 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:05:04 INFO - 1499537104285 Marionette TRACE 370 <- [1,7,null,{}]
11:05:04 INFO - 1499537104287 Marionette DEBUG Closed connection 370
11:05:04 INFO - 1499537104288 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49823
11:05:04 INFO - 1499537104289 Marionette DEBUG Closed connection 371
11:05:04 INFO - 1499537104290 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49824
11:05:04 INFO - 1499537104291 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:04 INFO - 1499537104292 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104295 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104306 Marionette TRACE 372 <- [1,1,null,{"sessionId":"230d52c4-52b7-478d-88a3-6b6c09423b28","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104307 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:05:04 INFO - 1499537104308 Marionette TRACE 372 <- [1,2,null,{}]
11:05:04 INFO - 1499537104309 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:04 INFO - 1499537104309 Marionette TRACE 372 <- [1,3,null,{}]
11:05:04 INFO - 1499537104310 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:04 INFO - 1499537104311 Marionette TRACE 372 <- [1,4,null,{}]
11:05:04 INFO - 1499537104313 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}]
11:05:04 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499537104316 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:05:04 INFO - 1499537104317 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:05:04 INFO - 1499537104318 Marionette TRACE 372 <- [1,6,null,{}]
11:05:04 INFO - 1499537104320 Marionette DEBUG Closed connection 372
11:05:04 INFO - 1499537104321 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49825
11:05:04 INFO - 1499537104321 Marionette DEBUG Closed connection 373
11:05:04 INFO - 1499537104322 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49826
11:05:04 INFO - 1499537104324 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:04 INFO - 1499537104324 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104327 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104330 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c6cba4fb-8a1e-42ea-82d7-8e3dca9200df","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104332 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:05:04 INFO - 1499537104333 Marionette TRACE 374 <- [1,2,null,{}]
11:05:04 INFO - 1499537104335 Marionette DEBUG Closed connection 374
11:05:04 INFO - 1499537104336 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49827
11:05:04 INFO - 1499537104336 Marionette DEBUG Closed connection 375
11:05:04 INFO - 1499537104337 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49828
11:05:04 INFO - 1499537104338 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:04 INFO - 1499537104339 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104342 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104345 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6d4aaf1f-46d8-4bcf-9337-903bda426767","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104347 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"}]
11:05:04 INFO - 1499537104349 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"
11:05:04 INFO - 1499537104351 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"
11:05:04 INFO - 1499537104351 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"
11:05:04 INFO - 1499537104361 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"
11:05:04 INFO - 1499537104362 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"
11:05:04 INFO - 1499537104370 Marionette TRACE 376 <- [1,2,null,{}]
11:05:04 INFO - 1499537104379 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:05:04 INFO - 1499537104384 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e2248af-37a8-4115-bee3-5a421eb35430","ELEMENT":"6e2248af-37a8-4115-bee3-5a421eb35430"}}]
11:05:04 INFO - 1499537104385 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6e2248af-37a8-4115-bee3-5a421eb35430"}]
11:05:04 INFO - 1499537104626 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:05:04 INFO - 1499537104627 Marionette TRACE 376 <- [1,4,null,{}]
11:05:04 INFO - 1499537104629 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}]
11:05:04 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499537104632 Marionette TRACE 376 <- [1,5,null,{"value":null}]
11:05:04 INFO - 1499537104633 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
11:05:04 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
11:05:04 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:05:04 INFO - 1499537104635 Marionette TRACE 376 <- [1,6,null,{}]
11:05:04 INFO - 1499537104637 Marionette DEBUG Closed connection 376
11:05:04 INFO - 1499537104637 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49829
11:05:04 INFO - 1499537104638 Marionette DEBUG Closed connection 377
11:05:04 INFO - 1499537104639 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49830
11:05:04 INFO - 1499537104640 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:04 INFO - 1499537104641 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104644 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104647 Marionette TRACE 378 <- [1,1,null,{"sessionId":"739852f2-37fb-4083-af68-436903998a10","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104648 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:05:04 INFO - 1499537104649 Marionette TRACE 378 <- [1,2,null,{}]
11:05:04 INFO - 1499537104650 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:04 INFO - 1499537104650 Marionette TRACE 378 <- [1,3,null,{}]
11:05:04 INFO - 1499537104651 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:04 INFO - 1499537104652 Marionette TRACE 378 <- [1,4,null,{}]
11:05:04 INFO - 1499537104654 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}]
11:05:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499537104656 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:05:04 INFO - 1499537104657 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:05:04 INFO - 1499537104659 Marionette TRACE 378 <- [1,6,null,{}]
11:05:04 INFO - 1499537104661 Marionette DEBUG Closed connection 378
11:05:04 INFO - 1499537104661 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49831
11:05:04 INFO - 1499537104662 Marionette DEBUG Closed connection 379
11:05:04 INFO - 1499537104663 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49832
11:05:04 INFO - 1499537104664 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:04 INFO - 1499537104665 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104667 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104671 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c850d70b-864b-4828-8561-5b265c255928","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104672 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:05:04 INFO - 1499537104673 Marionette TRACE 380 <- [1,2,null,{}]
11:05:04 INFO - 1499537104675 Marionette DEBUG Closed connection 380
11:05:04 INFO - 1499537104676 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49833
11:05:04 INFO - 1499537104677 Marionette DEBUG Closed connection 381
11:05:04 INFO - 1499537104678 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49834
11:05:04 INFO - 1499537104679 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:04 INFO - 1499537104679 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104682 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104685 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ac76e32e-490b-44ff-a7f9-32d9179e41ed","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104687 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"}]
11:05:04 INFO - 1499537104689 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"
11:05:04 INFO - 1499537104691 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"
11:05:04 INFO - 1499537104691 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"
11:05:04 INFO - 1499537104698 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"
11:05:04 INFO - 1499537104702 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"
11:05:04 INFO - 1499537104704 Marionette TRACE 382 <- [1,2,null,{}]
11:05:04 INFO - 1499537104714 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:05:04 INFO - 1499537104717 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1805a20e-d8f6-49b0-8df8-4f3d34b626f3","ELEMENT":"1805a20e-d8f6-49b0-8df8-4f3d34b626f3"}}]
11:05:04 INFO - 1499537104718 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1805a20e-d8f6-49b0-8df8-4f3d34b626f3"}]
11:05:04 INFO - 1499537104967 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:05:04 INFO - 1499537104968 Marionette TRACE 382 <- [1,4,null,{}]
11:05:04 INFO - 1499537104970 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}]
11:05:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499537104973 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:05:04 INFO - 1499537104974 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:05:04 INFO - 1499537104976 Marionette TRACE 382 <- [1,6,null,{}]
11:05:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
11:05:04 INFO - TEST-START | test_click.py TestClick.test_input_file
11:05:04 INFO - 1499537104978 Marionette DEBUG Closed connection 382
11:05:04 INFO - 1499537104978 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49835
11:05:04 INFO - 1499537104979 Marionette DEBUG Closed connection 383
11:05:04 INFO - 1499537104980 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49836
11:05:04 INFO - 1499537104981 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:04 INFO - 1499537104982 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:04 INFO - 1499537104985 Marionette DEBUG Register listener.js for window 4294967297
11:05:04 INFO - 1499537104988 Marionette TRACE 384 <- [1,1,null,{"sessionId":"01f3cadf-e6d5-4cc7-9f2b-a50da1aa2ccf","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:04 INFO - 1499537104989 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:05:04 INFO - 1499537104990 Marionette TRACE 384 <- [1,2,null,{}]
11:05:04 INFO - 1499537104991 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:04 INFO - 1499537104992 Marionette TRACE 384 <- [1,3,null,{}]
11:05:04 INFO - 1499537104992 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:04 INFO - 1499537104993 Marionette TRACE 384 <- [1,4,null,{}]
11:05:04 INFO - 1499537104995 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}]
11:05:05 INFO - TEST-START: test_click.py TestClick.test_input_file1499537104998 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:05:05 INFO - 1499537104999 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:05:05 INFO - 1499537105001 Marionette TRACE 384 <- [1,6,null,{}]
11:05:05 INFO - 1499537105002 Marionette DEBUG Closed connection 384
11:05:05 INFO - 1499537105003 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49837
11:05:05 INFO - 1499537105004 Marionette DEBUG Closed connection 385
11:05:05 INFO - 1499537105005 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49838
11:05:05 INFO - 1499537105006 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:05 INFO - 1499537105006 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105009 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105012 Marionette TRACE 386 <- [1,1,null,{"sessionId":"585c9b6d-2066-4464-a5f4-c8de3b87e9a2","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105014 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:05:05 INFO - 1499537105015 Marionette TRACE 386 <- [1,2,null,{}]
11:05:05 INFO - 1499537105017 Marionette DEBUG Closed connection 386
11:05:05 INFO - 1499537105018 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49839
11:05:05 INFO - 1499537105019 Marionette DEBUG Closed connection 387
11:05:05 INFO - 1499537105020 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49840
11:05:05 INFO - 1499537105021 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:05 INFO - 1499537105021 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105024 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105028 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ddb4bdfb-5902-43b0-993e-ba338f44ba00","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105029 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:05:05 INFO - 1499537105031 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"
11:05:05 INFO - 1499537105033 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"
11:05:05 INFO - 1499537105033 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"
11:05:05 INFO - 1499537105044 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:05:05 INFO - 1499537105046 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:05:05 INFO - 1499537105059 Marionette TRACE 388 <- [1,2,null,{}]
11:05:05 INFO - 1499537105062 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:05:05 INFO - 1499537105068 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"404c3151-21b9-41db-98bf-ec20f8ed55d1","ELEMENT":"404c3151-21b9-41db-98bf-ec20f8ed55d1"}}]
11:05:05 INFO - 1499537105069 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"404c3151-21b9-41db-98bf-ec20f8ed55d1"}]
11:05:05 INFO - 1499537105097 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:05:05 INFO - 1499537105099 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}]
11:05:05 INFO - TEST-END: test_click.py TestClick.test_input_file1499537105107 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:05:05 INFO - 1499537105108 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:05:05 INFO - 1499537105110 Marionette TRACE 388 <- [1,6,null,{}]
11:05:05 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
11:05:05 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:05:05 INFO - 1499537105112 Marionette DEBUG Closed connection 388
11:05:05 INFO - 1499537105112 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49841
11:05:05 INFO - 1499537105113 Marionette DEBUG Closed connection 389
11:05:05 INFO - 1499537105114 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49842
11:05:05 INFO - 1499537105115 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:05 INFO - 1499537105116 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105119 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105122 Marionette TRACE 390 <- [1,1,null,{"sessionId":"249115cd-09f8-40b5-845d-dceff0e389c6","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105124 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:05:05 INFO - 1499537105124 Marionette TRACE 390 <- [1,2,null,{}]
11:05:05 INFO - 1499537105125 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:05 INFO - 1499537105126 Marionette TRACE 390 <- [1,3,null,{}]
11:05:05 INFO - 1499537105126 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:05 INFO - 1499537105127 Marionette TRACE 390 <- [1,4,null,{}]
11:05:05 INFO - 1499537105129 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}]
11:05:05 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499537105132 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:05:05 INFO - 1499537105133 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:05:05 INFO - 1499537105134 Marionette TRACE 390 <- [1,6,null,{}]
11:05:05 INFO - 1499537105136 Marionette DEBUG Closed connection 390
11:05:05 INFO - 1499537105137 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49843
11:05:05 INFO - 1499537105138 Marionette DEBUG Closed connection 391
11:05:05 INFO - 1499537105138 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49844
11:05:05 INFO - 1499537105139 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:05 INFO - 1499537105140 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105143 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105146 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c1e6b018-e45e-45e2-ae0e-d7b5f7a6cba2","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105148 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:05:05 INFO - 1499537105149 Marionette TRACE 392 <- [1,2,null,{}]
11:05:05 INFO - 1499537105151 Marionette DEBUG Closed connection 392
11:05:05 INFO - 1499537105151 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49845
11:05:05 INFO - 1499537105152 Marionette DEBUG Closed connection 393
11:05:05 INFO - 1499537105153 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49846
11:05:05 INFO - 1499537105154 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:05 INFO - 1499537105155 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105158 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105161 Marionette TRACE 394 <- [1,1,null,{"sessionId":"1efc2414-b789-4f52-827c-0e6c6629bdf7","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105162 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"}]
11:05:05 INFO - 1499537105164 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:05:05 INFO - 1499537105166 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:05:05 INFO - 1499537105167 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:05:05 INFO - 1499537105183 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"
11:05:05 INFO - 1499537105184 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"
11:05:05 INFO - 1499537105186 Marionette TRACE 394 <- [1,2,null,{}]
11:05:05 INFO - 1499537105195 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:05:05 INFO - 1499537105207 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68f98ffa-89ae-44c4-8b30-c5878f22444c","ELEMENT":"68f98ffa-89ae-44c4-8b30-c5878f22444c"}}]
11:05:05 INFO - 1499537105208 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:05:05 INFO - 1499537105223 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6a1c28b4-e473-4be1-b178-6f85d3d68aea","ELEMENT":"6a1c28b4-e473-4be1-b178-6f85d3d68aea"}}]
11:05:05 INFO - 1499537105224 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"68f98ffa-89ae-44c4-8b30-c5878f22444c"}]
11:05:05 INFO - 1499537105464 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:05:05 INFO - 1499537105465 Marionette TRACE 394 <- [1,5,null,{}]
11:05:05 INFO - 1499537105466 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6a1c28b4-e473-4be1-b178-6f85d3d68aea"}]
11:05:05 INFO - 1499537105484 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
11:05:05 INFO - 1499537105486 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:05:05 INFO - 1499537105489 Marionette TRACE 394 <- [1,7,null,{"value":false}]
11:05:05 INFO - 1499537105491 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}]
11:05:05 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499537105494 Marionette TRACE 394 <- [1,8,null,{"value":null}]
11:05:05 INFO - 1499537105495 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
11:05:05 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 387ms
11:05:05 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:05:05 INFO - 1499537105496 Marionette TRACE 394 <- [1,9,null,{}]
11:05:05 INFO - 1499537105498 Marionette DEBUG Closed connection 394
11:05:05 INFO - 1499537105499 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49849
11:05:05 INFO - 1499537105500 Marionette DEBUG Closed connection 395
11:05:05 INFO - 1499537105501 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49850
11:05:05 INFO - 1499537105502 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:05 INFO - 1499537105502 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105505 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105509 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a1ba7f25-97da-48af-82c8-ffe987501acf","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105510 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:05:05 INFO - 1499537105510 Marionette TRACE 396 <- [1,2,null,{}]
11:05:05 INFO - 1499537105511 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:05:05 INFO - 1499537105512 Marionette TRACE 396 <- [1,3,null,{}]
11:05:05 INFO - 1499537105513 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:05:05 INFO - 1499537105513 Marionette TRACE 396 <- [1,4,null,{}]
11:05:05 INFO - 1499537105515 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}]
11:05:05 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499537105518 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:05:05 INFO - 1499537105519 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:05:05 INFO - 1499537105520 Marionette TRACE 396 <- [1,6,null,{}]
11:05:05 INFO - 1499537105522 Marionette DEBUG Closed connection 396
11:05:05 INFO - 1499537105523 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49851
11:05:05 INFO - 1499537105524 Marionette DEBUG Closed connection 397
11:05:05 INFO - 1499537105524 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49852
11:05:05 INFO - 1499537105525 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:05:05 INFO - 1499537105526 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105529 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105532 Marionette TRACE 398 <- [1,1,null,{"sessionId":"aa8042ab-2259-4e0f-9601-cda7bc3f2573","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105533 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:05:05 INFO - 1499537105535 Marionette TRACE 398 <- [1,2,null,{}]
11:05:05 INFO - 1499537105537 Marionette DEBUG Closed connection 398
11:05:05 INFO - 1499537105537 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49853
11:05:05 INFO - 1499537105538 Marionette DEBUG Closed connection 399
11:05:05 INFO - 1499537105539 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49854
11:05:05 INFO - 1499537105540 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:05:05 INFO - 1499537105541 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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}
11:05:05 INFO - 1499537105543 Marionette DEBUG Register listener.js for window 4294967297
11:05:05 INFO - 1499537105547 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6b3470f9-e56d-4fe7-a885-d90a9cf58d8b","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":3372,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpkos2pz.mozrunner","moz:accessibilityChecks":false}}]
11:05:05 INFO - 1499537105548 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"}]
11:05:05 INFO - 1499537105550 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"
11:05:05 INFO - 1499537105552 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"
11:05:05 INFO - 1499537105552 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"
11:05:05 INFO - 1499537105562 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"
11:05:05 INFO - 1499537105563 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"
11:05:05 INFO - 1499537105572 Marionette TRACE 400 <- [1,2,null,{}]
11:05:05 INFO - 1499537105579 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:05:05 INFO - 1499537105584 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01d4054f-1f55-4615-ae88-f012e49bf92d","ELEMENT":"01d4054f-1f55-4615-ae88-f012e49bf92d"}}]
11:05:05 INFO - 1499537105585 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"01d4054f-1f55-4615-ae88-f012e49bf92d"}]
11:05:05 INFO - 1499537105613 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
11:05:05 INFO - 1499537105614 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"01d4054f-1f55-4615-ae88-f012e49bf92d"}]
11:05:05 INFO - 1499537105632 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element