could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:57:58 INFO - 1499338678371 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}]
03:57:58 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499338678374 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:57:58 INFO - 1499338678375 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:57:58 INFO - 1499338678376 Marionette TRACE 352 <- [1,6,null,{}]
03:57:58 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 155ms
03:57:58 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:57:58 INFO - 1499338678378 Marionette DEBUG Closed connection 352
03:57:58 INFO - 1499338678379 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49878
03:57:58 INFO - 1499338678380 Marionette DEBUG Closed connection 353
03:57:58 INFO - 1499338678380 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49879
03:57:58 INFO - 1499338678381 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678382 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678385 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678388 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b142f8c9-333d-4623-a2c9-884b339944a2","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678390 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:57:58 INFO - 1499338678390 Marionette TRACE 354 <- [1,2,null,{}]
03:57:58 INFO - 1499338678391 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:57:58 INFO - 1499338678392 Marionette TRACE 354 <- [1,3,null,{}]
03:57:58 INFO - 1499338678393 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:57:58 INFO - 1499338678393 Marionette TRACE 354 <- [1,4,null,{}]
03:57:58 INFO - 1499338678395 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}]
03:57:58 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499338678398 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:57:58 INFO - 1499338678399 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:57:58 INFO - 1499338678400 Marionette TRACE 354 <- [1,6,null,{}]
03:57:58 INFO - 1499338678402 Marionette DEBUG Closed connection 354
03:57:58 INFO - 1499338678403 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49880
03:57:58 INFO - 1499338678403 Marionette DEBUG Closed connection 355
03:57:58 INFO - 1499338678404 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49881
03:57:58 INFO - 1499338678405 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678406 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678409 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678412 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f17c4798-32d3-4640-bd55-f20b4d99f289","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678413 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:57:58 INFO - 1499338678415 Marionette TRACE 356 <- [1,2,null,{}]
03:57:58 INFO - 1499338678417 Marionette DEBUG Closed connection 356
03:57:58 INFO - 1499338678417 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49882
03:57:58 INFO - 1499338678418 Marionette DEBUG Closed connection 357
03:57:58 INFO - 1499338678419 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49883
03:57:58 INFO - 1499338678420 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:57:58 INFO - 1499338678421 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678423 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678427 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d00323e4-e2ff-4b49-9a3f-8191d24b3f9e","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678428 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49432/clicks.html"}]
03:57:58 INFO - 1499338678430 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/hidden.html"
03:57:58 INFO - 1499338678438 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/hidden.html"
03:57:58 INFO - 1499338678439 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/hidden.html"
03:57:58 INFO - 1499338678452 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49432/clicks.html"
03:57:58 INFO - 1499338678454 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49432/clicks.html"
03:57:58 INFO - 1499338678458 Marionette TRACE 358 <- [1,2,null,{}]
03:57:58 INFO - 1499338678466 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:57:58 INFO - 1499338678471 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4cbc0e29-9ea3-4cd2-8be4-3747303dbac8","ELEMENT":"4cbc0e29-9ea3-4cd2-8be4-3747303dbac8"}}]
03:57:58 INFO - 1499338678472 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4cbc0e29-9ea3-4cd2-8be4-3747303dbac8"}]
03:57:58 INFO - 1499338678515 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/clicks.html"
03:57:58 INFO - 1499338678525 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/clicks.html"
03:57:58 INFO - 1499338678526 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/clicks.html"
03:57:58 INFO - 1499338678535 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49432/test.html"
03:57:58 INFO - 1499338678542 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49432/test.html"
03:57:58 INFO - 1499338678543 Marionette TRACE 358 <- [1,4,null,{}]
03:57:58 INFO - 1499338678551 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:57:58 INFO - 1499338678556 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb4cffc4-3bae-4bdc-bd7e-ef18d36fecf0","ELEMENT":"fb4cffc4-3bae-4bdc-bd7e-ef18d36fecf0"}}]
03:57:58 INFO - 1499338678557 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:57:58 INFO - 1499338678558 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:57:58 INFO - 1499338678559 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}]
03:57:58 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499338678562 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:57:58 INFO - 1499338678563 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:57:58 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 189ms
03:57:58 INFO - TEST-START | test_click.py TestClick.test_container_element
03:57:58 INFO - 1499338678565 Marionette TRACE 358 <- [1,8,null,{}]
03:57:58 INFO - 1499338678567 Marionette DEBUG Closed connection 358
03:57:58 INFO - 1499338678568 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49886
03:57:58 INFO - 1499338678569 Marionette DEBUG Closed connection 359
03:57:58 INFO - 1499338678570 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49887
03:57:58 INFO - 1499338678571 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678571 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678574 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678578 Marionette TRACE 360 <- [1,1,null,{"sessionId":"088381bb-da4c-4f39-8b10-8fb4bbd0dd5a","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678579 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:57:58 INFO - 1499338678580 Marionette TRACE 360 <- [1,2,null,{}]
03:57:58 INFO - 1499338678581 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:57:58 INFO - 1499338678581 Marionette TRACE 360 <- [1,3,null,{}]
03:57:58 INFO - 1499338678582 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:57:58 INFO - 1499338678583 Marionette TRACE 360 <- [1,4,null,{}]
03:57:58 INFO - 1499338678585 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}]
03:57:58 INFO - TEST-START: test_click.py TestClick.test_container_element1499338678588 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:57:58 INFO - 1499338678589 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:57:58 INFO - 1499338678590 Marionette TRACE 360 <- [1,6,null,{}]
03:57:58 INFO - 1499338678592 Marionette DEBUG Closed connection 360
03:57:58 INFO - 1499338678593 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49888
03:57:58 INFO - 1499338678594 Marionette DEBUG Closed connection 361
03:57:58 INFO - 1499338678594 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49889
03:57:58 INFO - 1499338678596 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678596 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678599 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678602 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3f3cf196-0095-4778-8c05-0c58899f6493","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678603 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:57:58 INFO - 1499338678605 Marionette TRACE 362 <- [1,2,null,{}]
03:57:58 INFO - 1499338678606 Marionette DEBUG Closed connection 362
03:57:58 INFO - 1499338678607 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49890
03:57:58 INFO - 1499338678608 Marionette DEBUG Closed connection 363
03:57:58 INFO - 1499338678609 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49891
03:57:58 INFO - 1499338678610 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:57:58 INFO - 1499338678611 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678613 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678616 Marionette TRACE 364 <- [1,1,null,{"sessionId":"c24a5db3-a307-4e83-97dd-b7402122d4fb","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678618 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"}]
03:57:58 INFO - 1499338678620 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49432/test.html"
03:57:58 INFO - 1499338678622 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49432/test.html"
03:57:58 INFO - 1499338678622 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49432/test.html"
03:57:58 INFO - 1499338678635 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"
03:57:58 INFO - 1499338678636 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"
03:57:58 INFO - 1499338678650 Marionette TRACE 364 <- [1,2,null,{}]
03:57:58 INFO - 1499338678655 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:57:58 INFO - 1499338678659 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da5874ef-2263-4fe9-8da0-38b0b2a424a3","ELEMENT":"da5874ef-2263-4fe9-8da0-38b0b2a424a3"}}]
03:57:58 INFO - 1499338678660 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"da5874ef-2263-4fe9-8da0-38b0b2a424a3"}]
03:57:58 INFO - 1499338678911 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:57:58 INFO - 1499338678912 Marionette TRACE 364 <- [1,4,null,{}]
03:57:58 INFO - 1499338678913 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"da5874ef-2263-4fe9-8da0-38b0b2a424a3","name":"selected"}]
03:57:58 INFO - 1499338678918 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:57:58 INFO - 1499338678920 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}]
03:57:58 INFO - TEST-END: test_click.py TestClick.test_container_element1499338678923 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:57:58 INFO - 1499338678924 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:57:58 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 360ms
03:57:58 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:57:58 INFO - 1499338678926 Marionette TRACE 364 <- [1,7,null,{}]
03:57:58 INFO - 1499338678927 Marionette DEBUG Closed connection 364
03:57:58 INFO - 1499338678930 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49892
03:57:58 INFO - 1499338678931 Marionette DEBUG Closed connection 365
03:57:58 INFO - 1499338678932 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49893
03:57:58 INFO - 1499338678933 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678933 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678936 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678940 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7cf6927a-8cd1-409f-801e-9294e853f642","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678941 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:57:58 INFO - 1499338678941 Marionette TRACE 366 <- [1,2,null,{}]
03:57:58 INFO - 1499338678942 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:57:58 INFO - 1499338678943 Marionette TRACE 366 <- [1,3,null,{}]
03:57:58 INFO - 1499338678944 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:57:58 INFO - 1499338678944 Marionette TRACE 366 <- [1,4,null,{}]
03:57:58 INFO - 1499338678946 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}]
03:57:58 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499338678949 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:57:58 INFO - 1499338678950 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:57:58 INFO - 1499338678951 Marionette TRACE 366 <- [1,6,null,{}]
03:57:58 INFO - 1499338678953 Marionette DEBUG Closed connection 366
03:57:58 INFO - 1499338678954 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49894
03:57:58 INFO - 1499338678955 Marionette DEBUG Closed connection 367
03:57:58 INFO - 1499338678956 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49895
03:57:58 INFO - 1499338678957 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:58 INFO - 1499338678957 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678960 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678963 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6888c142-47b8-488c-a9cf-1d8c10f09d8f","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678964 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:57:58 INFO - 1499338678966 Marionette TRACE 368 <- [1,2,null,{}]
03:57:58 INFO - 1499338678967 Marionette DEBUG Closed connection 368
03:57:58 INFO - 1499338678968 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49896
03:57:58 INFO - 1499338678969 Marionette DEBUG Closed connection 369
03:57:58 INFO - 1499338678970 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49897
03:57:58 INFO - 1499338678971 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:57:58 INFO - 1499338678972 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:58 INFO - 1499338678974 Marionette DEBUG Register listener.js for window 4294967297
03:57:58 INFO - 1499338678978 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a0ac8fc9-b58b-40f6-b9e1-e0a931cdc04c","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:58 INFO - 1499338678979 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"}]
03:57:58 INFO - 1499338678981 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"
03:57:58 INFO - 1499338678984 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"
03:57:58 INFO - 1499338678985 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"
03:57:58 INFO - 1499338678992 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"
03:57:58 INFO - 1499338678996 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"
03:57:59 INFO - 1499338679004 Marionette TRACE 370 <- [1,2,null,{}]
03:57:59 INFO - 1499338679008 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:57:59 INFO - 1499338679016 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fec768f7-5dc1-4c06-a3df-2d5c4b13b921","ELEMENT":"fec768f7-5dc1-4c06-a3df-2d5c4b13b921"}}]
03:57:59 INFO - 1499338679017 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fec768f7-5dc1-4c06-a3df-2d5c4b13b921"}]
03:57:59 INFO - 1499338679263 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:57:59 INFO - 1499338679264 Marionette TRACE 370 <- [1,4,null,{}]
03:57:59 INFO - 1499338679265 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fec768f7-5dc1-4c06-a3df-2d5c4b13b921","name":"selected"}]
03:57:59 INFO - 1499338679270 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:57:59 INFO - 1499338679272 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}]
03:57:59 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499338679275 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:57:59 INFO - 1499338679276 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:57:59 INFO - 1499338679277 Marionette TRACE 370 <- [1,7,null,{}]
03:57:59 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 351ms
03:57:59 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:57:59 INFO - 1499338679279 Marionette DEBUG Closed connection 370
03:57:59 INFO - 1499338679280 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49898
03:57:59 INFO - 1499338679281 Marionette DEBUG Closed connection 371
03:57:59 INFO - 1499338679282 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49899
03:57:59 INFO - 1499338679283 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:59 INFO - 1499338679283 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679286 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679290 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7a3bb75f-a85b-426a-9206-d1d2f6fc8cea","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679291 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:57:59 INFO - 1499338679291 Marionette TRACE 372 <- [1,2,null,{}]
03:57:59 INFO - 1499338679292 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:57:59 INFO - 1499338679293 Marionette TRACE 372 <- [1,3,null,{}]
03:57:59 INFO - 1499338679294 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:57:59 INFO - 1499338679294 Marionette TRACE 372 <- [1,4,null,{}]
03:57:59 INFO - 1499338679296 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}]
03:57:59 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499338679299 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:57:59 INFO - 1499338679300 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:57:59 INFO - 1499338679302 Marionette TRACE 372 <- [1,6,null,{}]
03:57:59 INFO - 1499338679303 Marionette DEBUG Closed connection 372
03:57:59 INFO - 1499338679304 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49900
03:57:59 INFO - 1499338679305 Marionette DEBUG Closed connection 373
03:57:59 INFO - 1499338679305 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49901
03:57:59 INFO - 1499338679307 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:59 INFO - 1499338679307 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679310 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679314 Marionette TRACE 374 <- [1,1,null,{"sessionId":"560508f0-2eea-457c-a582-c28718fcb3d5","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679315 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:57:59 INFO - 1499338679316 Marionette TRACE 374 <- [1,2,null,{}]
03:57:59 INFO - 1499338679318 Marionette DEBUG Closed connection 374
03:57:59 INFO - 1499338679319 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49902
03:57:59 INFO - 1499338679320 Marionette DEBUG Closed connection 375
03:57:59 INFO - 1499338679320 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49903
03:57:59 INFO - 1499338679322 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:57:59 INFO - 1499338679322 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679325 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679328 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7840ea4b-cbf6-40a4-b997-ed7206339e1b","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679331 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"}]
03:57:59 INFO - 1499338679333 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"
03:57:59 INFO - 1499338679335 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"
03:57:59 INFO - 1499338679335 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"
03:57:59 INFO - 1499338679344 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"
03:57:59 INFO - 1499338679346 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"
03:57:59 INFO - 1499338679355 Marionette TRACE 376 <- [1,2,null,{}]
03:57:59 INFO - 1499338679359 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:57:59 INFO - 1499338679362 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab074e16-4ad5-4061-b2cb-76b9c3619d68","ELEMENT":"ab074e16-4ad5-4061-b2cb-76b9c3619d68"}}]
03:57:59 INFO - 1499338679370 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ab074e16-4ad5-4061-b2cb-76b9c3619d68"}]
03:57:59 INFO - 1499338679616 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:57:59 INFO - 1499338679617 Marionette TRACE 376 <- [1,4,null,{}]
03:57:59 INFO - 1499338679619 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}]
03:57:59 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499338679622 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:57:59 INFO - 1499338679623 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:57:59 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 347ms
03:57:59 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:57:59 INFO - 1499338679625 Marionette TRACE 376 <- [1,6,null,{}]
03:57:59 INFO - 1499338679626 Marionette DEBUG Closed connection 376
03:57:59 INFO - 1499338679627 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49904
03:57:59 INFO - 1499338679628 Marionette DEBUG Closed connection 377
03:57:59 INFO - 1499338679629 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49905
03:57:59 INFO - 1499338679630 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:59 INFO - 1499338679631 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679634 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679637 Marionette TRACE 378 <- [1,1,null,{"sessionId":"03673a94-edeb-4d2c-9fd0-a4f2b6b84280","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679638 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:57:59 INFO - 1499338679639 Marionette TRACE 378 <- [1,2,null,{}]
03:57:59 INFO - 1499338679639 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:57:59 INFO - 1499338679640 Marionette TRACE 378 <- [1,3,null,{}]
03:57:59 INFO - 1499338679641 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:57:59 INFO - 1499338679641 Marionette TRACE 378 <- [1,4,null,{}]
03:57:59 INFO - 1499338679643 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}]
03:57:59 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499338679646 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:57:59 INFO - 1499338679647 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:57:59 INFO - 1499338679648 Marionette TRACE 378 <- [1,6,null,{}]
03:57:59 INFO - 1499338679650 Marionette DEBUG Closed connection 378
03:57:59 INFO - 1499338679651 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49906
03:57:59 INFO - 1499338679652 Marionette DEBUG Closed connection 379
03:57:59 INFO - 1499338679653 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49907
03:57:59 INFO - 1499338679654 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:59 INFO - 1499338679654 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679657 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679660 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0bac4cf0-df00-4ea7-bda2-e257577eb378","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679661 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:57:59 INFO - 1499338679663 Marionette TRACE 380 <- [1,2,null,{}]
03:57:59 INFO - 1499338679664 Marionette DEBUG Closed connection 380
03:57:59 INFO - 1499338679665 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49908
03:57:59 INFO - 1499338679666 Marionette DEBUG Closed connection 381
03:57:59 INFO - 1499338679667 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49909
03:57:59 INFO - 1499338679668 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:57:59 INFO - 1499338679677 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679681 Marionette DEBUG Register listener.js for window 4294967297
03:57:59 INFO - 1499338679684 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dc2ade16-88ea-4bc9-8f15-bdd60199f01b","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:57:59 INFO - 1499338679686 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"}]
03:57:59 INFO - 1499338679688 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"
03:57:59 INFO - 1499338679690 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"
03:57:59 INFO - 1499338679690 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"
03:57:59 INFO - 1499338679702 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"
03:57:59 INFO - 1499338679704 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"
03:57:59 INFO - 1499338679709 Marionette TRACE 382 <- [1,2,null,{}]
03:57:59 INFO - 1499338679717 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:57:59 INFO - 1499338679723 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2be535aa-06d7-4fc7-b9e2-1817f8839dca","ELEMENT":"2be535aa-06d7-4fc7-b9e2-1817f8839dca"}}]
03:57:59 INFO - 1499338679724 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2be535aa-06d7-4fc7-b9e2-1817f8839dca"}]
03:57:59 INFO - 1499338679976 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:57:59 INFO - 1499338679977 Marionette TRACE 382 <- [1,4,null,{}]
03:57:59 INFO - 1499338679979 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}]
03:57:59 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499338679982 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:57:59 INFO - 1499338679983 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:57:59 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 361ms
03:57:59 INFO - TEST-START | test_click.py TestClick.test_input_file
03:57:59 INFO - 1499338679985 Marionette TRACE 382 <- [1,6,null,{}]
03:57:59 INFO - 1499338679987 Marionette DEBUG Closed connection 382
03:57:59 INFO - 1499338679987 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49910
03:57:59 INFO - 1499338679988 Marionette DEBUG Closed connection 383
03:57:59 INFO - 1499338679989 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49911
03:57:59 INFO - 1499338679990 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:57:59 INFO - 1499338679991 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:57:59 INFO - 1499338679994 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338679997 Marionette TRACE 384 <- [1,1,null,{"sessionId":"671015e6-6c35-449b-97ad-4323a015ba84","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338679999 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:58:00 INFO - 1499338679999 Marionette TRACE 384 <- [1,2,null,{}]
03:58:00 INFO - 1499338680000 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:58:00 INFO - 1499338680001 Marionette TRACE 384 <- [1,3,null,{}]
03:58:00 INFO - 1499338680002 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:58:00 INFO - 1499338680002 Marionette TRACE 384 <- [1,4,null,{}]
03:58:00 INFO - 1499338680004 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}]
03:58:00 INFO - TEST-START: test_click.py TestClick.test_input_file1499338680007 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:58:00 INFO - 1499338680009 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:58:00 INFO - 1499338680010 Marionette TRACE 384 <- [1,6,null,{}]
03:58:00 INFO - 1499338680012 Marionette DEBUG Closed connection 384
03:58:00 INFO - 1499338680013 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49912
03:58:00 INFO - 1499338680014 Marionette DEBUG Closed connection 385
03:58:00 INFO - 1499338680014 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49913
03:58:00 INFO - 1499338680016 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:58:00 INFO - 1499338680016 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680019 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680022 Marionette TRACE 386 <- [1,1,null,{"sessionId":"9448ef55-e0b5-4e21-8497-caeadda37b64","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680024 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:58:00 INFO - 1499338680025 Marionette TRACE 386 <- [1,2,null,{}]
03:58:00 INFO - 1499338680027 Marionette DEBUG Closed connection 386
03:58:00 INFO - 1499338680028 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49914
03:58:00 INFO - 1499338680029 Marionette DEBUG Closed connection 387
03:58:00 INFO - 1499338680029 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49915
03:58:00 INFO - 1499338680030 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:58:00 INFO - 1499338680031 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680034 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680037 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c88def60-659a-4934-a5a5-2ba4d00ba9eb","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680038 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:58:00 INFO - 1499338680040 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"
03:58:00 INFO - 1499338680043 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"
03:58:00 INFO - 1499338680043 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"
03:58:00 INFO - 1499338680050 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:58:00 INFO - 1499338680055 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:58:00 INFO - 1499338680059 Marionette TRACE 388 <- [1,2,null,{}]
03:58:00 INFO - 1499338680073 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:58:00 INFO - 1499338680077 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c0a8130-9b36-4774-b3a2-50c11e71e7bd","ELEMENT":"6c0a8130-9b36-4774-b3a2-50c11e71e7bd"}}]
03:58:00 INFO - 1499338680078 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6c0a8130-9b36-4774-b3a2-50c11e71e7bd"}]
03:58:00 INFO - 1499338680108 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:58:00 INFO - 1499338680110 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}]
03:58:00 INFO - TEST-END: test_click.py TestClick.test_input_file1499338680114 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:58:00 INFO - 1499338680115 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:58:00 INFO - 1499338680116 Marionette TRACE 388 <- [1,6,null,{}]
03:58:00 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 131ms
03:58:00 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:58:00 INFO - 1499338680118 Marionette DEBUG Closed connection 388
03:58:00 INFO - 1499338680119 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49916
03:58:00 INFO - 1499338680120 Marionette DEBUG Closed connection 389
03:58:00 INFO - 1499338680121 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49917
03:58:00 INFO - 1499338680122 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:58:00 INFO - 1499338680122 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680125 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680128 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8bbc9cbf-1694-4e16-b120-6d2dd252fc54","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680130 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:58:00 INFO - 1499338680130 Marionette TRACE 390 <- [1,2,null,{}]
03:58:00 INFO - 1499338680131 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:58:00 INFO - 1499338680132 Marionette TRACE 390 <- [1,3,null,{}]
03:58:00 INFO - 1499338680133 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:58:00 INFO - 1499338680133 Marionette TRACE 390 <- [1,4,null,{}]
03:58:00 INFO - 1499338680135 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}]
03:58:00 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499338680138 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:58:00 INFO - 1499338680139 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:58:00 INFO - 1499338680140 Marionette TRACE 390 <- [1,6,null,{}]
03:58:00 INFO - 1499338680142 Marionette DEBUG Closed connection 390
03:58:00 INFO - 1499338680143 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49918
03:58:00 INFO - 1499338680144 Marionette DEBUG Closed connection 391
03:58:00 INFO - 1499338680145 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49919
03:58:00 INFO - 1499338680146 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:58:00 INFO - 1499338680146 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680149 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680153 Marionette TRACE 392 <- [1,1,null,{"sessionId":"eb0a17f6-19d5-4397-b032-9e6153199490","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680154 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:58:00 INFO - 1499338680155 Marionette TRACE 392 <- [1,2,null,{}]
03:58:00 INFO - 1499338680157 Marionette DEBUG Closed connection 392
03:58:00 INFO - 1499338680158 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49920
03:58:00 INFO - 1499338680159 Marionette DEBUG Closed connection 393
03:58:00 INFO - 1499338680159 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49921
03:58:00 INFO - 1499338680160 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:58:00 INFO - 1499338680161 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680164 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680167 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3f04561d-cf0e-4cd1-bee8-e165243c4b2e","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680168 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"}]
03:58:00 INFO - 1499338680170 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:58:00 INFO - 1499338680173 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:58:00 INFO - 1499338680173 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:58:00 INFO - 1499338680183 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"
03:58:00 INFO - 1499338680185 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"
03:58:00 INFO - 1499338680192 Marionette TRACE 394 <- [1,2,null,{}]
03:58:00 INFO - 1499338680201 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:58:00 INFO - 1499338680205 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"48fe63c7-4bc5-4055-b2da-8b3711e211d1","ELEMENT":"48fe63c7-4bc5-4055-b2da-8b3711e211d1"}}]
03:58:00 INFO - 1499338680207 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:58:00 INFO - 1499338680209 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0df9c32f-1554-4517-965d-2ba27246f01d","ELEMENT":"0df9c32f-1554-4517-965d-2ba27246f01d"}}]
03:58:00 INFO - 1499338680210 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"48fe63c7-4bc5-4055-b2da-8b3711e211d1"}]
03:58:00 INFO - 1499338680451 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:58:00 INFO - 1499338680452 Marionette TRACE 394 <- [1,5,null,{}]
03:58:00 INFO - 1499338680453 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0df9c32f-1554-4517-965d-2ba27246f01d"}]
03:58:00 INFO - 1499338680471 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:58:00 INFO - 1499338680473 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:58:00 INFO - 1499338680476 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:58:00 INFO - 1499338680478 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}]
03:58:00 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499338680481 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:58:00 INFO - 1499338680482 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:58:00 INFO - 1499338680483 Marionette TRACE 394 <- [1,9,null,{}]
03:58:00 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 367ms
03:58:00 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:58:00 INFO - 1499338680485 Marionette DEBUG Closed connection 394
03:58:00 INFO - 1499338680486 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49922
03:58:00 INFO - 1499338680487 Marionette DEBUG Closed connection 395
03:58:00 INFO - 1499338680487 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49923
03:58:00 INFO - 1499338680489 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:58:00 INFO - 1499338680489 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680492 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680496 Marionette TRACE 396 <- [1,1,null,{"sessionId":"529a2e5c-203e-4303-baac-808d0b3c163e","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680497 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:58:00 INFO - 1499338680497 Marionette TRACE 396 <- [1,2,null,{}]
03:58:00 INFO - 1499338680498 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:58:00 INFO - 1499338680499 Marionette TRACE 396 <- [1,3,null,{}]
03:58:00 INFO - 1499338680500 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:58:00 INFO - 1499338680500 Marionette TRACE 396 <- [1,4,null,{}]
03:58:00 INFO - 1499338680502 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}]
03:58:00 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499338680505 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:58:00 INFO - 1499338680506 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:58:00 INFO - 1499338680507 Marionette TRACE 396 <- [1,6,null,{}]
03:58:00 INFO - 1499338680509 Marionette DEBUG Closed connection 396
03:58:00 INFO - 1499338680510 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49924
03:58:00 INFO - 1499338680511 Marionette DEBUG Closed connection 397
03:58:00 INFO - 1499338680512 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49925
03:58:00 INFO - 1499338680513 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:58:00 INFO - 1499338680513 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680516 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680519 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f517f891-4e32-4b1c-9e8f-030844fefdbf","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680520 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:58:00 INFO - 1499338680522 Marionette TRACE 398 <- [1,2,null,{}]
03:58:00 INFO - 1499338680523 Marionette DEBUG Closed connection 398
03:58:00 INFO - 1499338680524 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49926
03:58:00 INFO - 1499338680525 Marionette DEBUG Closed connection 399
03:58:00 INFO - 1499338680526 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49927
03:58:00 INFO - 1499338680527 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:58:00 INFO - 1499338680527 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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}
03:58:00 INFO - 1499338680531 Marionette DEBUG Register listener.js for window 4294967297
03:58:00 INFO - 1499338680534 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6d0f11ee-f463-4597-9665-2a9b6b16fceb","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":3452,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppd939u.mozrunner","moz:accessibilityChecks":false}}]
03:58:00 INFO - 1499338680535 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"}]
03:58:00 INFO - 1499338680537 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"
03:58:00 INFO - 1499338680539 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"
03:58:00 INFO - 1499338680539 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"
03:58:00 INFO - 1499338680549 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"
03:58:00 INFO - 1499338680551 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"
03:58:00 INFO - 1499338680560 Marionette TRACE 400 <- [1,2,null,{}]
03:58:00 INFO - 1499338680570 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:58:00 INFO - 1499338680576 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50dc13ee-2fb5-4a89-b5ce-fa9c2d91d50e","ELEMENT":"50dc13ee-2fb5-4a89-b5ce-fa9c2d91d50e"}}]
03:58:00 INFO - 1499338680578 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"50dc13ee-2fb5-4a89-b5ce-fa9c2d91d50e"}]
03:58:00 INFO - 1499338680608 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:58:00 INFO - 1499338680609 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"50dc13ee-2fb5-4a89-b5ce-fa9c2d91d50e"}]
03:58:00 INFO - 1499338680627 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element