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]
05:20:52 INFO - 1499257252340 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}]
05:20:52 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499257252344 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:20:52 INFO - 1499257252345 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:20:52 INFO - 1499257252346 Marionette TRACE 352 <- [1,6,null,{}]
05:20:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 159ms
05:20:52 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:20:52 INFO - 1499257252348 Marionette DEBUG Closed connection 352
05:20:52 INFO - 1499257252349 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49809
05:20:52 INFO - 1499257252350 Marionette DEBUG Closed connection 353
05:20:52 INFO - 1499257252351 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49810
05:20:52 INFO - 1499257252352 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252353 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252356 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252359 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8f6ee43c-7cc4-4ec5-bb39-20c22a7cd955","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252361 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:20:52 INFO - 1499257252361 Marionette TRACE 354 <- [1,2,null,{}]
05:20:52 INFO - 1499257252362 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:52 INFO - 1499257252363 Marionette TRACE 354 <- [1,3,null,{}]
05:20:52 INFO - 1499257252364 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:52 INFO - 1499257252364 Marionette TRACE 354 <- [1,4,null,{}]
05:20:52 INFO - 1499257252366 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}]
05:20:52 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499257252369 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:20:52 INFO - 1499257252370 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:20:52 INFO - 1499257252372 Marionette TRACE 354 <- [1,6,null,{}]
05:20:52 INFO - 1499257252374 Marionette DEBUG Closed connection 354
05:20:52 INFO - 1499257252375 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49811
05:20:52 INFO - 1499257252376 Marionette DEBUG Closed connection 355
05:20:52 INFO - 1499257252376 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49812
05:20:52 INFO - 1499257252377 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252378 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252381 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252385 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a575557a-9418-48f9-949a-16f38552f50a","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252386 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:20:52 INFO - 1499257252387 Marionette TRACE 356 <- [1,2,null,{}]
05:20:52 INFO - 1499257252389 Marionette DEBUG Closed connection 356
05:20:52 INFO - 1499257252390 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49813
05:20:52 INFO - 1499257252391 Marionette DEBUG Closed connection 357
05:20:52 INFO - 1499257252392 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49814
05:20:52 INFO - 1499257252393 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:52 INFO - 1499257252394 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252397 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252400 Marionette TRACE 358 <- [1,1,null,{"sessionId":"fe822316-c89d-48fd-b400-81ad58292d9d","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252402 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49365/clicks.html"}]
05:20:52 INFO - 1499257252404 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/hidden.html"
05:20:52 INFO - 1499257252413 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/hidden.html"
05:20:52 INFO - 1499257252413 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/hidden.html"
05:20:52 INFO - 1499257252427 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49365/clicks.html"
05:20:52 INFO - 1499257252429 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49365/clicks.html"
05:20:52 INFO - 1499257252434 Marionette TRACE 358 <- [1,2,null,{}]
05:20:52 INFO - 1499257252444 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:20:52 INFO - 1499257252451 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b8e5271-39e5-4df9-bc1d-957266c44beb","ELEMENT":"1b8e5271-39e5-4df9-bc1d-957266c44beb"}}]
05:20:52 INFO - 1499257252452 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1b8e5271-39e5-4df9-bc1d-957266c44beb"}]
05:20:52 INFO - 1499257252499 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/clicks.html"
05:20:52 INFO - 1499257252512 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/clicks.html"
05:20:52 INFO - 1499257252512 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/clicks.html"
05:20:52 INFO - 1499257252526 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49365/test.html"
05:20:52 INFO - 1499257252530 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49365/test.html"
05:20:52 INFO - 1499257252532 Marionette TRACE 358 <- [1,4,null,{}]
05:20:52 INFO - 1499257252536 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:20:52 INFO - 1499257252543 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"07358ba8-404f-4e21-942c-8968910e66c4","ELEMENT":"07358ba8-404f-4e21-942c-8968910e66c4"}}]
05:20:52 INFO - 1499257252545 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:20:52 INFO - 1499257252545 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:20:52 INFO - 1499257252547 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}]
05:20:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499257252551 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:20:52 INFO - 1499257252552 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:20:52 INFO - 1499257252554 Marionette TRACE 358 <- [1,8,null,{}]
05:20:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 208ms
05:20:52 INFO - TEST-START | test_click.py TestClick.test_container_element
05:20:52 INFO - 1499257252556 Marionette DEBUG Closed connection 358
05:20:52 INFO - 1499257252557 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49817
05:20:52 INFO - 1499257252558 Marionette DEBUG Closed connection 359
05:20:52 INFO - 1499257252559 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49818
05:20:52 INFO - 1499257252560 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252561 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252564 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252568 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9319cdfc-7be2-49eb-9695-3dd368949911","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252569 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:20:52 INFO - 1499257252570 Marionette TRACE 360 <- [1,2,null,{}]
05:20:52 INFO - 1499257252571 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:52 INFO - 1499257252572 Marionette TRACE 360 <- [1,3,null,{}]
05:20:52 INFO - 1499257252573 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:52 INFO - 1499257252573 Marionette TRACE 360 <- [1,4,null,{}]
05:20:52 INFO - 1499257252575 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}]
05:20:52 INFO - TEST-START: test_click.py TestClick.test_container_element1499257252578 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:20:52 INFO - 1499257252580 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:20:52 INFO - 1499257252581 Marionette TRACE 360 <- [1,6,null,{}]
05:20:52 INFO - 1499257252583 Marionette DEBUG Closed connection 360
05:20:52 INFO - 1499257252584 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49819
05:20:52 INFO - 1499257252585 Marionette DEBUG Closed connection 361
05:20:52 INFO - 1499257252586 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49820
05:20:52 INFO - 1499257252587 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252588 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252591 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252594 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1a01433b-6bf6-405e-93b9-2b0246949268","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252596 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:20:52 INFO - 1499257252597 Marionette TRACE 362 <- [1,2,null,{}]
05:20:52 INFO - 1499257252599 Marionette DEBUG Closed connection 362
05:20:52 INFO - 1499257252600 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49821
05:20:52 INFO - 1499257252601 Marionette DEBUG Closed connection 363
05:20:52 INFO - 1499257252602 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49822
05:20:52 INFO - 1499257252603 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:52 INFO - 1499257252603 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252606 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252610 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0f6b8d40-0f71-4b65-89dd-ec9c490fd4fc","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252611 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"}]
05:20:52 INFO - 1499257252613 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/test.html"
05:20:52 INFO - 1499257252616 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/test.html"
05:20:52 INFO - 1499257252616 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/test.html"
05:20:52 INFO - 1499257252626 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"
05:20:52 INFO - 1499257252634 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"
05:20:52 INFO - 1499257252644 Marionette TRACE 364 <- [1,2,null,{}]
05:20:52 INFO - 1499257252650 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:52 INFO - 1499257252659 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"588b429c-f272-4955-bbae-f24b1e862614","ELEMENT":"588b429c-f272-4955-bbae-f24b1e862614"}}]
05:20:52 INFO - 1499257252661 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"588b429c-f272-4955-bbae-f24b1e862614"}]
05:20:52 INFO - 1499257252909 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:52 INFO - 1499257252910 Marionette TRACE 364 <- [1,4,null,{}]
05:20:52 INFO - 1499257252911 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"588b429c-f272-4955-bbae-f24b1e862614","name":"selected"}]
05:20:52 INFO - 1499257252916 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:20:52 INFO - 1499257252918 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}]
05:20:52 INFO - TEST-END: test_click.py TestClick.test_container_element1499257252922 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:20:52 INFO - 1499257252923 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:20:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
05:20:52 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:20:52 INFO - 1499257252925 Marionette TRACE 364 <- [1,7,null,{}]
05:20:52 INFO - 1499257252926 Marionette DEBUG Closed connection 364
05:20:52 INFO - 1499257252927 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49823
05:20:52 INFO - 1499257252928 Marionette DEBUG Closed connection 365
05:20:52 INFO - 1499257252929 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49824
05:20:52 INFO - 1499257252930 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252931 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252934 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252938 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3f2318af-1ce0-49de-a8b6-8b7e73d39b27","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252939 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:20:52 INFO - 1499257252939 Marionette TRACE 366 <- [1,2,null,{}]
05:20:52 INFO - 1499257252940 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:52 INFO - 1499257252941 Marionette TRACE 366 <- [1,3,null,{}]
05:20:52 INFO - 1499257252942 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:52 INFO - 1499257252942 Marionette TRACE 366 <- [1,4,null,{}]
05:20:52 INFO - 1499257252944 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}]
05:20:52 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499257252947 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:20:52 INFO - 1499257252948 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:20:52 INFO - 1499257252950 Marionette TRACE 366 <- [1,6,null,{}]
05:20:52 INFO - 1499257252952 Marionette DEBUG Closed connection 366
05:20:52 INFO - 1499257252953 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49825
05:20:52 INFO - 1499257252954 Marionette DEBUG Closed connection 367
05:20:52 INFO - 1499257252955 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49826
05:20:52 INFO - 1499257252956 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:52 INFO - 1499257252956 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252959 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252963 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0463268f-2d6c-4bf6-9c4d-093e64b2dffa","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252964 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:20:52 INFO - 1499257252966 Marionette TRACE 368 <- [1,2,null,{}]
05:20:52 INFO - 1499257252968 Marionette DEBUG Closed connection 368
05:20:52 INFO - 1499257252969 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49827
05:20:52 INFO - 1499257252970 Marionette DEBUG Closed connection 369
05:20:52 INFO - 1499257252972 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49828
05:20:52 INFO - 1499257252973 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:52 INFO - 1499257252974 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:52 INFO - 1499257252977 Marionette DEBUG Register listener.js for window 4294967297
05:20:52 INFO - 1499257252980 Marionette TRACE 370 <- [1,1,null,{"sessionId":"e7cd9bd2-1bbc-497b-91fc-0f4a47978b4a","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:52 INFO - 1499257252982 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"}]
05:20:52 INFO - 1499257252984 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"
05:20:52 INFO - 1499257252986 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"
05:20:52 INFO - 1499257252987 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"
05:20:53 INFO - 1499257252995 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"
05:20:53 INFO - 1499257252999 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"
05:20:53 INFO - 1499257253003 Marionette TRACE 370 <- [1,2,null,{}]
05:20:53 INFO - 1499257253014 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:53 INFO - 1499257253026 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14928288-a8ab-4608-847d-a21540ea79e4","ELEMENT":"14928288-a8ab-4608-847d-a21540ea79e4"}}]
05:20:53 INFO - 1499257253028 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"14928288-a8ab-4608-847d-a21540ea79e4"}]
05:20:53 INFO - 1499257253277 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:53 INFO - 1499257253278 Marionette TRACE 370 <- [1,4,null,{}]
05:20:53 INFO - 1499257253279 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"14928288-a8ab-4608-847d-a21540ea79e4","name":"selected"}]
05:20:53 INFO - 1499257253285 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:20:53 INFO - 1499257253287 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}]
05:20:53 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499257253290 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:20:53 INFO - 1499257253291 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:20:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
05:20:53 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:20:53 INFO - 1499257253293 Marionette TRACE 370 <- [1,7,null,{}]
05:20:53 INFO - 1499257253295 Marionette DEBUG Closed connection 370
05:20:53 INFO - 1499257253296 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49829
05:20:53 INFO - 1499257253297 Marionette DEBUG Closed connection 371
05:20:53 INFO - 1499257253298 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49830
05:20:53 INFO - 1499257253299 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:53 INFO - 1499257253299 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253303 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253306 Marionette TRACE 372 <- [1,1,null,{"sessionId":"083fff72-6202-4415-bd23-cfe2ae46d73b","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253308 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:20:53 INFO - 1499257253308 Marionette TRACE 372 <- [1,2,null,{}]
05:20:53 INFO - 1499257253309 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:53 INFO - 1499257253310 Marionette TRACE 372 <- [1,3,null,{}]
05:20:53 INFO - 1499257253311 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:53 INFO - 1499257253312 Marionette TRACE 372 <- [1,4,null,{}]
05:20:53 INFO - 1499257253314 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}]
05:20:53 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499257253317 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:20:53 INFO - 1499257253318 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:20:53 INFO - 1499257253320 Marionette TRACE 372 <- [1,6,null,{}]
05:20:53 INFO - 1499257253321 Marionette DEBUG Closed connection 372
05:20:53 INFO - 1499257253322 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49831
05:20:53 INFO - 1499257253323 Marionette DEBUG Closed connection 373
05:20:53 INFO - 1499257253324 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49832
05:20:53 INFO - 1499257253325 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:53 INFO - 1499257253326 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253329 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253333 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4d91f89e-f9d6-49e9-bf90-02588e2e687c","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253337 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:20:53 INFO - 1499257253338 Marionette TRACE 374 <- [1,2,null,{}]
05:20:53 INFO - 1499257253340 Marionette DEBUG Closed connection 374
05:20:53 INFO - 1499257253341 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49833
05:20:53 INFO - 1499257253342 Marionette DEBUG Closed connection 375
05:20:53 INFO - 1499257253343 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49834
05:20:53 INFO - 1499257253344 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:53 INFO - 1499257253345 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253348 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253351 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7b421d80-c892-4369-a057-1c441e9ed372","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253352 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"}]
05:20:53 INFO - 1499257253355 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"
05:20:53 INFO - 1499257253357 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"
05:20:53 INFO - 1499257253357 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"
05:20:53 INFO - 1499257253365 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"
05:20:53 INFO - 1499257253368 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"
05:20:53 INFO - 1499257253379 Marionette TRACE 376 <- [1,2,null,{}]
05:20:53 INFO - 1499257253384 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:20:53 INFO - 1499257253391 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5046be75-a047-4174-a529-bf4b4c0db120","ELEMENT":"5046be75-a047-4174-a529-bf4b4c0db120"}}]
05:20:53 INFO - 1499257253393 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5046be75-a047-4174-a529-bf4b4c0db120"}]
05:20:53 INFO - 1499257253635 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:53 INFO - 1499257253636 Marionette TRACE 376 <- [1,4,null,{}]
05:20:53 INFO - 1499257253638 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}]
05:20:53 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499257253641 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:20:53 INFO - 1499257253642 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:20:53 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 351ms
05:20:53 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:20:53 INFO - 1499257253644 Marionette TRACE 376 <- [1,6,null,{}]
05:20:53 INFO - 1499257253646 Marionette DEBUG Closed connection 376
05:20:53 INFO - 1499257253647 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49837
05:20:53 INFO - 1499257253648 Marionette DEBUG Closed connection 377
05:20:53 INFO - 1499257253648 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49838
05:20:53 INFO - 1499257253649 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:53 INFO - 1499257253650 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253654 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253657 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d23f7863-1734-4090-b95f-436adb4ff86c","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253659 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:20:53 INFO - 1499257253659 Marionette TRACE 378 <- [1,2,null,{}]
05:20:53 INFO - 1499257253660 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:53 INFO - 1499257253660 Marionette TRACE 378 <- [1,3,null,{}]
05:20:53 INFO - 1499257253661 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:53 INFO - 1499257253662 Marionette TRACE 378 <- [1,4,null,{}]
05:20:53 INFO - 1499257253664 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}]
05:20:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499257253667 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:20:53 INFO - 1499257253668 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:20:53 INFO - 1499257253670 Marionette TRACE 378 <- [1,6,null,{}]
05:20:53 INFO - 1499257253671 Marionette DEBUG Closed connection 378
05:20:53 INFO - 1499257253672 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49839
05:20:53 INFO - 1499257253673 Marionette DEBUG Closed connection 379
05:20:53 INFO - 1499257253674 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49840
05:20:53 INFO - 1499257253675 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:53 INFO - 1499257253676 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253679 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253682 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9d35a03c-32b3-49dc-b498-602011a0d696","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253693 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:20:53 INFO - 1499257253695 Marionette TRACE 380 <- [1,2,null,{}]
05:20:53 INFO - 1499257253697 Marionette DEBUG Closed connection 380
05:20:53 INFO - 1499257253698 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49841
05:20:53 INFO - 1499257253699 Marionette DEBUG Closed connection 381
05:20:53 INFO - 1499257253699 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49842
05:20:53 INFO - 1499257253700 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:53 INFO - 1499257253701 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:53 INFO - 1499257253704 Marionette DEBUG Register listener.js for window 4294967297
05:20:53 INFO - 1499257253708 Marionette TRACE 382 <- [1,1,null,{"sessionId":"88a81ea0-9898-4afe-b1f9-a1df8f6637d0","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:53 INFO - 1499257253709 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"}]
05:20:53 INFO - 1499257253711 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"
05:20:53 INFO - 1499257253714 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"
05:20:53 INFO - 1499257253714 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"
05:20:53 INFO - 1499257253724 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"
05:20:53 INFO - 1499257253725 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"
05:20:53 INFO - 1499257253734 Marionette TRACE 382 <- [1,2,null,{}]
05:20:53 INFO - 1499257253743 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:20:53 INFO - 1499257253749 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b891c5d0-a2c8-4326-a839-654274f5cb97","ELEMENT":"b891c5d0-a2c8-4326-a839-654274f5cb97"}}]
05:20:53 INFO - 1499257253750 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b891c5d0-a2c8-4326-a839-654274f5cb97"}]
05:20:54 INFO - 1499257254004 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:54 INFO - 1499257254005 Marionette TRACE 382 <- [1,4,null,{}]
05:20:54 INFO - 1499257254007 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}]
05:20:54 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499257254010 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:20:54 INFO - 1499257254011 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:20:54 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
05:20:54 INFO - TEST-START | test_click.py TestClick.test_input_file
05:20:54 INFO - 1499257254013 Marionette TRACE 382 <- [1,6,null,{}]
05:20:54 INFO - 1499257254015 Marionette DEBUG Closed connection 382
05:20:54 INFO - 1499257254016 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49843
05:20:54 INFO - 1499257254016 Marionette DEBUG Closed connection 383
05:20:54 INFO - 1499257254017 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49844
05:20:54 INFO - 1499257254018 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254019 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254022 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254026 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d37d10e7-7ea4-4dc6-b19d-45e02758fa3a","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254027 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:20:54 INFO - 1499257254027 Marionette TRACE 384 <- [1,2,null,{}]
05:20:54 INFO - 1499257254028 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:54 INFO - 1499257254029 Marionette TRACE 384 <- [1,3,null,{}]
05:20:54 INFO - 1499257254030 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:54 INFO - 1499257254030 Marionette TRACE 384 <- [1,4,null,{}]
05:20:54 INFO - 1499257254032 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}]
05:20:54 INFO - TEST-START: test_click.py TestClick.test_input_file1499257254035 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:20:54 INFO - 1499257254036 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:20:54 INFO - 1499257254038 Marionette TRACE 384 <- [1,6,null,{}]
05:20:54 INFO - 1499257254040 Marionette DEBUG Closed connection 384
05:20:54 INFO - 1499257254041 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49845
05:20:54 INFO - 1499257254042 Marionette DEBUG Closed connection 385
05:20:54 INFO - 1499257254042 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49846
05:20:54 INFO - 1499257254044 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254044 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254047 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254051 Marionette TRACE 386 <- [1,1,null,{"sessionId":"874f5a54-b9f1-4d83-bb1d-993865caedc9","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254053 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:20:54 INFO - 1499257254054 Marionette TRACE 386 <- [1,2,null,{}]
05:20:54 INFO - 1499257254056 Marionette DEBUG Closed connection 386
05:20:54 INFO - 1499257254057 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49847
05:20:54 INFO - 1499257254058 Marionette DEBUG Closed connection 387
05:20:54 INFO - 1499257254059 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49848
05:20:54 INFO - 1499257254060 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:54 INFO - 1499257254061 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254064 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254067 Marionette TRACE 388 <- [1,1,null,{"sessionId":"15141d69-f9ca-434e-b3bd-684b94998e6e","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254068 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:20:54 INFO - 1499257254075 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"
05:20:54 INFO - 1499257254078 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"
05:20:54 INFO - 1499257254079 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"
05:20:54 INFO - 1499257254087 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:54 INFO - 1499257254093 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:54 INFO - 1499257254107 Marionette TRACE 388 <- [1,2,null,{}]
05:20:54 INFO - 1499257254111 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:20:54 INFO - 1499257254117 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d5878b7-e69c-45be-921c-9fb84856e0b1","ELEMENT":"6d5878b7-e69c-45be-921c-9fb84856e0b1"}}]
05:20:54 INFO - 1499257254119 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6d5878b7-e69c-45be-921c-9fb84856e0b1"}]
05:20:54 INFO - 1499257254149 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]
05:20:54 INFO - 1499257254151 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}]
05:20:54 INFO - TEST-END: test_click.py TestClick.test_input_file1499257254155 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:20:54 INFO - 1499257254156 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:20:54 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
05:20:54 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:20:54 INFO - 1499257254158 Marionette TRACE 388 <- [1,6,null,{}]
05:20:54 INFO - 1499257254160 Marionette DEBUG Closed connection 388
05:20:54 INFO - 1499257254160 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49849
05:20:54 INFO - 1499257254161 Marionette DEBUG Closed connection 389
05:20:54 INFO - 1499257254162 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49850
05:20:54 INFO - 1499257254163 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254164 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254167 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254171 Marionette TRACE 390 <- [1,1,null,{"sessionId":"ea7d2767-e5ea-4503-809e-fac20e38bd7f","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254172 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:20:54 INFO - 1499257254173 Marionette TRACE 390 <- [1,2,null,{}]
05:20:54 INFO - 1499257254173 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:54 INFO - 1499257254174 Marionette TRACE 390 <- [1,3,null,{}]
05:20:54 INFO - 1499257254175 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:54 INFO - 1499257254175 Marionette TRACE 390 <- [1,4,null,{}]
05:20:54 INFO - 1499257254177 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}]
05:20:54 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499257254180 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:20:54 INFO - 1499257254181 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:20:54 INFO - 1499257254183 Marionette TRACE 390 <- [1,6,null,{}]
05:20:54 INFO - 1499257254185 Marionette DEBUG Closed connection 390
05:20:54 INFO - 1499257254186 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49851
05:20:54 INFO - 1499257254187 Marionette DEBUG Closed connection 391
05:20:54 INFO - 1499257254188 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49852
05:20:54 INFO - 1499257254189 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254189 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254192 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254196 Marionette TRACE 392 <- [1,1,null,{"sessionId":"9d625472-d2f9-4752-ac33-b3f89c157559","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254197 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:20:54 INFO - 1499257254199 Marionette TRACE 392 <- [1,2,null,{}]
05:20:54 INFO - 1499257254201 Marionette DEBUG Closed connection 392
05:20:54 INFO - 1499257254201 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49853
05:20:54 INFO - 1499257254202 Marionette DEBUG Closed connection 393
05:20:54 INFO - 1499257254203 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49854
05:20:54 INFO - 1499257254204 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:54 INFO - 1499257254205 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254208 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254212 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c5274bff-db1f-4b93-92ca-5f0550a5f396","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254213 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"}]
05:20:54 INFO - 1499257254215 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:54 INFO - 1499257254218 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:54 INFO - 1499257254218 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:54 INFO - 1499257254226 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"
05:20:54 INFO - 1499257254229 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"
05:20:54 INFO - 1499257254234 Marionette TRACE 394 <- [1,2,null,{}]
05:20:54 INFO - 1499257254247 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:20:54 INFO - 1499257254250 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24082344-d0b7-4e1a-a58c-2237509cc26c","ELEMENT":"24082344-d0b7-4e1a-a58c-2237509cc26c"}}]
05:20:54 INFO - 1499257254252 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:20:54 INFO - 1499257254269 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a8d0b159-966f-4759-8bc9-74fe1634afbe","ELEMENT":"a8d0b159-966f-4759-8bc9-74fe1634afbe"}}]
05:20:54 INFO - 1499257254273 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"24082344-d0b7-4e1a-a58c-2237509cc26c"}]
05:20:54 INFO - 1499257254516 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:54 INFO - 1499257254518 Marionette TRACE 394 <- [1,5,null,{}]
05:20:54 INFO - 1499257254524 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a8d0b159-966f-4759-8bc9-74fe1634afbe"}]
05:20:54 INFO - 1499257254546 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]
05:20:54 INFO - 1499257254549 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:20:54 INFO - 1499257254557 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:20:54 INFO - 1499257254566 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}]
05:20:54 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499257254571 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:20:54 INFO - 1499257254572 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:20:54 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 418ms
05:20:54 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:20:54 INFO - 1499257254576 Marionette TRACE 394 <- [1,9,null,{}]
05:20:54 INFO - 1499257254579 Marionette DEBUG Closed connection 394
05:20:54 INFO - 1499257254580 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49855
05:20:54 INFO - 1499257254583 Marionette DEBUG Closed connection 395
05:20:54 INFO - 1499257254584 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49856
05:20:54 INFO - 1499257254585 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254589 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254592 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254598 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a12db607-4884-4d2d-95a0-7098e419f7b6","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254600 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:20:54 INFO - 1499257254601 Marionette TRACE 396 <- [1,2,null,{}]
05:20:54 INFO - 1499257254603 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:54 INFO - 1499257254604 Marionette TRACE 396 <- [1,3,null,{}]
05:20:54 INFO - 1499257254605 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:54 INFO - 1499257254606 Marionette TRACE 396 <- [1,4,null,{}]
05:20:54 INFO - 1499257254609 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}]
05:20:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499257254613 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:20:54 INFO - 1499257254615 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:20:54 INFO - 1499257254617 Marionette TRACE 396 <- [1,6,null,{}]
05:20:54 INFO - 1499257254619 Marionette DEBUG Closed connection 396
05:20:54 INFO - 1499257254620 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49857
05:20:54 INFO - 1499257254622 Marionette DEBUG Closed connection 397
05:20:54 INFO - 1499257254623 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49858
05:20:54 INFO - 1499257254626 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:54 INFO - 1499257254628 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254631 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254636 Marionette TRACE 398 <- [1,1,null,{"sessionId":"67b6a5f0-775a-4c5e-b36c-b626b078f63b","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254638 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:20:54 INFO - 1499257254640 Marionette TRACE 398 <- [1,2,null,{}]
05:20:54 INFO - 1499257254642 Marionette DEBUG Closed connection 398
05:20:54 INFO - 1499257254643 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49859
05:20:54 INFO - 1499257254645 Marionette DEBUG Closed connection 399
05:20:54 INFO - 1499257254645 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49860
05:20:54 INFO - 1499257254647 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:54 INFO - 1499257254648 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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}
05:20:54 INFO - 1499257254651 Marionette DEBUG Register listener.js for window 4294967297
05:20:54 INFO - 1499257254656 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b93008cf-6b9a-4f5a-b16b-75b6e7ac5922","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":1996,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppr6rrs.mozrunner","moz:accessibilityChecks":false}}]
05:20:54 INFO - 1499257254657 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"}]
05:20:54 INFO - 1499257254660 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"
05:20:54 INFO - 1499257254662 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"
05:20:54 INFO - 1499257254663 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"
05:20:54 INFO - 1499257254679 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"
05:20:54 INFO - 1499257254681 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"
05:20:54 INFO - 1499257254694 Marionette TRACE 400 <- [1,2,null,{}]
05:20:54 INFO - 1499257254698 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:20:54 INFO - 1499257254702 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cae5218b-2c8a-429c-a49b-5f96fab711ce","ELEMENT":"cae5218b-2c8a-429c-a49b-5f96fab711ce"}}]
05:20:54 INFO - 1499257254703 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cae5218b-2c8a-429c-a49b-5f96fab711ce"}]
05:20:54 INFO - 1499257254733 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:20:54 INFO - 1499257254735 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"cae5218b-2c8a-429c-a49b-5f96fab711ce"}]
05:20:54 INFO - 1499257254754 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element