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:1330:5\n"},null]
02:15:07 INFO - 1499073307337 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}]
02:15:07 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 171ms
02:15:07 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:15:07 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499073307341 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:15:07 INFO - 1499073307342 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:15:07 INFO - 1499073307344 Marionette TRACE 352 <- [1,6,null,{}]
02:15:07 INFO - 1499073307345 Marionette DEBUG Closed connection 352
02:15:07 INFO - 1499073307346 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49924
02:15:07 INFO - 1499073307347 Marionette DEBUG Closed connection 353
02:15:07 INFO - 1499073307348 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49925
02:15:07 INFO - 1499073307349 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307350 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307353 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307356 Marionette TRACE 354 <- [1,1,null,{"sessionId":"289f682c-5b36-4a9c-ac30-5650a2cbaccd","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307358 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:15:07 INFO - 1499073307358 Marionette TRACE 354 <- [1,2,null,{}]
02:15:07 INFO - 1499073307359 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:07 INFO - 1499073307360 Marionette TRACE 354 <- [1,3,null,{}]
02:15:07 INFO - 1499073307361 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:07 INFO - 1499073307362 Marionette TRACE 354 <- [1,4,null,{}]
02:15:07 INFO - 1499073307363 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}]
02:15:07 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499073307367 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:15:07 INFO - 1499073307368 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:15:07 INFO - 1499073307370 Marionette TRACE 354 <- [1,6,null,{}]
02:15:07 INFO - 1499073307371 Marionette DEBUG Closed connection 354
02:15:07 INFO - 1499073307372 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49926
02:15:07 INFO - 1499073307373 Marionette DEBUG Closed connection 355
02:15:07 INFO - 1499073307374 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49927
02:15:07 INFO - 1499073307375 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307375 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307379 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307382 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5df28ab6-5a2c-4091-82b8-0d16c64fa925","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307384 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:15:07 INFO - 1499073307385 Marionette TRACE 356 <- [1,2,null,{}]
02:15:07 INFO - 1499073307387 Marionette DEBUG Closed connection 356
02:15:07 INFO - 1499073307388 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49928
02:15:07 INFO - 1499073307389 Marionette DEBUG Closed connection 357
02:15:07 INFO - 1499073307390 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49929
02:15:07 INFO - 1499073307391 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:07 INFO - 1499073307392 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307395 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307398 Marionette TRACE 358 <- [1,1,null,{"sessionId":"613201c8-85c9-4da0-aaf8-7cf739e8a1ee","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307400 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49479/clicks.html"}]
02:15:07 INFO - 1499073307402 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49479/hidden.html"
02:15:07 INFO - 1499073307411 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49479/hidden.html"
02:15:07 INFO - 1499073307411 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49479/hidden.html"
02:15:07 INFO - 1499073307419 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49479/clicks.html"
02:15:07 INFO - 1499073307426 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49479/clicks.html"
02:15:07 INFO - 1499073307430 Marionette TRACE 358 <- [1,2,null,{}]
02:15:07 INFO - 1499073307440 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:15:07 INFO - 1499073307444 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2be50617-daa9-459b-9d79-edc51df7d8d7","ELEMENT":"2be50617-daa9-459b-9d79-edc51df7d8d7"}}]
02:15:07 INFO - 1499073307445 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"2be50617-daa9-459b-9d79-edc51df7d8d7"}]
02:15:07 INFO - 1499073307492 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49479/clicks.html"
02:15:07 INFO - 1499073307504 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49479/clicks.html"
02:15:07 INFO - 1499073307504 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49479/clicks.html"
02:15:07 INFO - 1499073307514 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49479/test.html"
02:15:07 INFO - 1499073307522 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49479/test.html"
02:15:07 INFO - 1499073307523 Marionette TRACE 358 <- [1,4,null,{}]
02:15:07 INFO - 1499073307528 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:15:07 INFO - 1499073307537 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f18af203-2095-4d24-9afa-6ff621c710cb","ELEMENT":"f18af203-2095-4d24-9afa-6ff621c710cb"}}]
02:15:07 INFO - 1499073307539 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:15:07 INFO - 1499073307542 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:15:07 INFO - 1499073307545 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}]
02:15:07 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499073307549 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:15:07 INFO - 1499073307550 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:15:07 INFO - 1499073307552 Marionette TRACE 358 <- [1,8,null,{}]
02:15:07 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 208ms
02:15:07 INFO - TEST-START | test_click.py TestClick.test_container_element
02:15:07 INFO - 1499073307554 Marionette DEBUG Closed connection 358
02:15:07 INFO - 1499073307555 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49932
02:15:07 INFO - 1499073307556 Marionette DEBUG Closed connection 359
02:15:07 INFO - 1499073307557 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49933
02:15:07 INFO - 1499073307558 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307559 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307562 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307567 Marionette TRACE 360 <- [1,1,null,{"sessionId":"51828d5c-4ec4-4f3b-b758-08054779e7fd","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307569 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:15:07 INFO - 1499073307569 Marionette TRACE 360 <- [1,2,null,{}]
02:15:07 INFO - 1499073307571 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:07 INFO - 1499073307571 Marionette TRACE 360 <- [1,3,null,{}]
02:15:07 INFO - 1499073307572 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:07 INFO - 1499073307573 Marionette TRACE 360 <- [1,4,null,{}]
02:15:07 INFO - 1499073307575 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}]
02:15:07 INFO - TEST-START: test_click.py TestClick.test_container_element1499073307578 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:15:07 INFO - 1499073307580 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:15:07 INFO - 1499073307582 Marionette TRACE 360 <- [1,6,null,{}]
02:15:07 INFO - 1499073307583 Marionette DEBUG Closed connection 360
02:15:07 INFO - 1499073307584 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49934
02:15:07 INFO - 1499073307585 Marionette DEBUG Closed connection 361
02:15:07 INFO - 1499073307586 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49935
02:15:07 INFO - 1499073307587 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307588 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307592 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307596 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bcc1fc45-5b32-412d-b75d-a73b950df113","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307597 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:15:07 INFO - 1499073307599 Marionette TRACE 362 <- [1,2,null,{}]
02:15:07 INFO - 1499073307601 Marionette DEBUG Closed connection 362
02:15:07 INFO - 1499073307601 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49936
02:15:07 INFO - 1499073307602 Marionette DEBUG Closed connection 363
02:15:07 INFO - 1499073307603 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49937
02:15:07 INFO - 1499073307604 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:07 INFO - 1499073307605 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307609 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307613 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6bc3c2b0-21de-4520-ab32-8094db9ad3c4","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307614 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"}]
02:15:07 INFO - 1499073307617 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49479/test.html"
02:15:07 INFO - 1499073307619 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49479/test.html"
02:15:07 INFO - 1499073307620 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49479/test.html"
02:15:07 INFO - 1499073307639 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"
02:15:07 INFO - 1499073307641 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"
02:15:07 INFO - 1499073307653 Marionette TRACE 364 <- [1,2,null,{}]
02:15:07 INFO - 1499073307659 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:15:07 INFO - 1499073307662 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9dfe2734-b643-48b5-9ff6-419a20779f1a","ELEMENT":"9dfe2734-b643-48b5-9ff6-419a20779f1a"}}]
02:15:07 INFO - 1499073307663 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"9dfe2734-b643-48b5-9ff6-419a20779f1a"}]
02:15:07 INFO - 1499073307913 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:15:07 INFO - 1499073307914 Marionette TRACE 364 <- [1,4,null,{}]
02:15:07 INFO - 1499073307916 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"9dfe2734-b643-48b5-9ff6-419a20779f1a","name":"selected"}]
02:15:07 INFO - 1499073307921 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:15:07 INFO - 1499073307924 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}]
02:15:07 INFO - TEST-END: test_click.py TestClick.test_container_element1499073307927 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:15:07 INFO - 1499073307929 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:15:07 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 378ms
02:15:07 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:15:07 INFO - 1499073307930 Marionette TRACE 364 <- [1,7,null,{}]
02:15:07 INFO - 1499073307932 Marionette DEBUG Closed connection 364
02:15:07 INFO - 1499073307933 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49938
02:15:07 INFO - 1499073307934 Marionette DEBUG Closed connection 365
02:15:07 INFO - 1499073307935 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49939
02:15:07 INFO - 1499073307936 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307937 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307940 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307944 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fbb0ccc4-36a2-42dc-abf1-bfe91d2568cc","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307945 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:15:07 INFO - 1499073307946 Marionette TRACE 366 <- [1,2,null,{}]
02:15:07 INFO - 1499073307947 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:07 INFO - 1499073307947 Marionette TRACE 366 <- [1,3,null,{}]
02:15:07 INFO - 1499073307949 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:07 INFO - 1499073307949 Marionette TRACE 366 <- [1,4,null,{}]
02:15:07 INFO - 1499073307951 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}]
02:15:07 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499073307955 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:15:07 INFO - 1499073307956 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:15:07 INFO - 1499073307958 Marionette TRACE 366 <- [1,6,null,{}]
02:15:07 INFO - 1499073307959 Marionette DEBUG Closed connection 366
02:15:07 INFO - 1499073307960 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49940
02:15:07 INFO - 1499073307961 Marionette DEBUG Closed connection 367
02:15:07 INFO - 1499073307962 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49941
02:15:07 INFO - 1499073307963 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:07 INFO - 1499073307964 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307967 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307971 Marionette TRACE 368 <- [1,1,null,{"sessionId":"70af9845-4126-4f3e-b37e-a865d95d9e02","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307972 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:15:07 INFO - 1499073307974 Marionette TRACE 368 <- [1,2,null,{}]
02:15:07 INFO - 1499073307975 Marionette DEBUG Closed connection 368
02:15:07 INFO - 1499073307976 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49942
02:15:07 INFO - 1499073307977 Marionette DEBUG Closed connection 369
02:15:07 INFO - 1499073307978 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49943
02:15:07 INFO - 1499073307979 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:07 INFO - 1499073307980 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:07 INFO - 1499073307983 Marionette DEBUG Register listener.js for window 4294967297
02:15:07 INFO - 1499073307987 Marionette TRACE 370 <- [1,1,null,{"sessionId":"6ab397f9-f113-4765-9a02-279695be07a1","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:07 INFO - 1499073307988 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"}]
02:15:07 INFO - 1499073307991 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"
02:15:07 INFO - 1499073307993 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"
02:15:07 INFO - 1499073307994 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"
02:15:08 INFO - 1499073308004 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"
02:15:08 INFO - 1499073308006 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"
02:15:08 INFO - 1499073308011 Marionette TRACE 370 <- [1,2,null,{}]
02:15:08 INFO - 1499073308024 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:15:08 INFO - 1499073308030 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0af220ee-2d94-48dd-bfb4-4df37a828dd3","ELEMENT":"0af220ee-2d94-48dd-bfb4-4df37a828dd3"}}]
02:15:08 INFO - 1499073308032 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"0af220ee-2d94-48dd-bfb4-4df37a828dd3"}]
02:15:08 INFO - 1499073308283 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:15:08 INFO - 1499073308284 Marionette TRACE 370 <- [1,4,null,{}]
02:15:08 INFO - 1499073308285 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"0af220ee-2d94-48dd-bfb4-4df37a828dd3","name":"selected"}]
02:15:08 INFO - 1499073308291 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:15:08 INFO - 1499073308293 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}]
02:15:08 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499073308297 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:15:08 INFO - 1499073308298 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:15:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 370ms
02:15:08 INFO - 1499073308300 Marionette TRACE 370 <- [1,7,null,{}]
02:15:08 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:15:08 INFO - 1499073308302 Marionette DEBUG Closed connection 370
02:15:08 INFO - 1499073308304 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49944
02:15:08 INFO - 1499073308305 Marionette DEBUG Closed connection 371
02:15:08 INFO - 1499073308305 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49945
02:15:08 INFO - 1499073308307 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:08 INFO - 1499073308308 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308311 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308315 Marionette TRACE 372 <- [1,1,null,{"sessionId":"78869948-cde9-4934-8a77-c7426af36778","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308316 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:15:08 INFO - 1499073308317 Marionette TRACE 372 <- [1,2,null,{}]
02:15:08 INFO - 1499073308318 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:08 INFO - 1499073308318 Marionette TRACE 372 <- [1,3,null,{}]
02:15:08 INFO - 1499073308319 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:08 INFO - 1499073308320 Marionette TRACE 372 <- [1,4,null,{}]
02:15:08 INFO - 1499073308322 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}]
02:15:08 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499073308325 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:15:08 INFO - 1499073308327 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:15:08 INFO - 1499073308329 Marionette TRACE 372 <- [1,6,null,{}]
02:15:08 INFO - 1499073308330 Marionette DEBUG Closed connection 372
02:15:08 INFO - 1499073308331 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49946
02:15:08 INFO - 1499073308332 Marionette DEBUG Closed connection 373
02:15:08 INFO - 1499073308333 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49947
02:15:08 INFO - 1499073308335 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:08 INFO - 1499073308336 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308339 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308343 Marionette TRACE 374 <- [1,1,null,{"sessionId":"18e9afcf-82f4-4a1b-ad55-0f4456eadb0b","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308344 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:15:08 INFO - 1499073308346 Marionette TRACE 374 <- [1,2,null,{}]
02:15:08 INFO - 1499073308348 Marionette DEBUG Closed connection 374
02:15:08 INFO - 1499073308349 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49948
02:15:08 INFO - 1499073308349 Marionette DEBUG Closed connection 375
02:15:08 INFO - 1499073308350 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49949
02:15:08 INFO - 1499073308352 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:08 INFO - 1499073308352 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308356 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308359 Marionette TRACE 376 <- [1,1,null,{"sessionId":"32bdf4e5-7de7-4c2e-b0f6-48aabc806dda","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308361 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"}]
02:15:08 INFO - 1499073308363 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"
02:15:08 INFO - 1499073308366 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"
02:15:08 INFO - 1499073308366 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"
02:15:08 INFO - 1499073308373 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"
02:15:08 INFO - 1499073308377 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"
02:15:08 INFO - 1499073308381 Marionette TRACE 376 <- [1,2,null,{}]
02:15:08 INFO - 1499073308393 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:15:08 INFO - 1499073308397 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c4eae72-fc31-4d40-9f89-c33d0d201d43","ELEMENT":"6c4eae72-fc31-4d40-9f89-c33d0d201d43"}}]
02:15:08 INFO - 1499073308404 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6c4eae72-fc31-4d40-9f89-c33d0d201d43"}]
02:15:08 INFO - 1499073308652 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:15:08 INFO - 1499073308653 Marionette TRACE 376 <- [1,4,null,{}]
02:15:08 INFO - 1499073308656 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}]
02:15:08 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499073308659 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:15:08 INFO - 1499073308661 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:15:08 INFO - 1499073308662 Marionette TRACE 376 <- [1,6,null,{}]
02:15:08 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 361ms
02:15:08 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:15:08 INFO - 1499073308664 Marionette DEBUG Closed connection 376
02:15:08 INFO - 1499073308665 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49950
02:15:08 INFO - 1499073308666 Marionette DEBUG Closed connection 377
02:15:08 INFO - 1499073308667 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49951
02:15:08 INFO - 1499073308668 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:08 INFO - 1499073308669 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308672 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308676 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f0e4bcc7-8340-4bd7-b4da-a33a74eea8d6","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308677 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:15:08 INFO - 1499073308678 Marionette TRACE 378 <- [1,2,null,{}]
02:15:08 INFO - 1499073308679 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:08 INFO - 1499073308680 Marionette TRACE 378 <- [1,3,null,{}]
02:15:08 INFO - 1499073308681 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:08 INFO - 1499073308682 Marionette TRACE 378 <- [1,4,null,{}]
02:15:08 INFO - 1499073308684 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}]
02:15:08 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499073308688 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:15:08 INFO - 1499073308689 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:15:08 INFO - 1499073308691 Marionette TRACE 378 <- [1,6,null,{}]
02:15:08 INFO - 1499073308693 Marionette DEBUG Closed connection 378
02:15:08 INFO - 1499073308694 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49952
02:15:08 INFO - 1499073308695 Marionette DEBUG Closed connection 379
02:15:08 INFO - 1499073308696 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49953
02:15:08 INFO - 1499073308697 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:08 INFO - 1499073308698 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308701 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308715 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0fbf56fe-9ffb-4a4e-8ea7-d362fc64e4ed","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308719 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:15:08 INFO - 1499073308721 Marionette TRACE 380 <- [1,2,null,{}]
02:15:08 INFO - 1499073308723 Marionette DEBUG Closed connection 380
02:15:08 INFO - 1499073308723 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49954
02:15:08 INFO - 1499073308724 Marionette DEBUG Closed connection 381
02:15:08 INFO - 1499073308725 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49955
02:15:08 INFO - 1499073308726 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:08 INFO - 1499073308727 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:08 INFO - 1499073308730 Marionette DEBUG Register listener.js for window 4294967297
02:15:08 INFO - 1499073308734 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1f313105-0758-48a3-8f3e-b7cbac3f03fc","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:08 INFO - 1499073308736 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"}]
02:15:08 INFO - 1499073308738 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"
02:15:08 INFO - 1499073308740 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"
02:15:08 INFO - 1499073308740 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"
02:15:08 INFO - 1499073308747 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"
02:15:08 INFO - 1499073308752 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"
02:15:08 INFO - 1499073308761 Marionette TRACE 382 <- [1,2,null,{}]
02:15:08 INFO - 1499073308771 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:15:08 INFO - 1499073308777 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce743703-c246-4d52-a623-9bc33c56048e","ELEMENT":"ce743703-c246-4d52-a623-9bc33c56048e"}}]
02:15:08 INFO - 1499073308779 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ce743703-c246-4d52-a623-9bc33c56048e"}]
02:15:09 INFO - 1499073309033 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:15:09 INFO - 1499073309034 Marionette TRACE 382 <- [1,4,null,{}]
02:15:09 INFO - 1499073309036 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}]
02:15:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499073309040 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:15:09 INFO - 1499073309041 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:15:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 381ms
02:15:09 INFO - 1499073309043 Marionette TRACE 382 <- [1,6,null,{}]
02:15:09 INFO - TEST-START | test_click.py TestClick.test_input_file
02:15:09 INFO - 1499073309045 Marionette DEBUG Closed connection 382
02:15:09 INFO - 1499073309047 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49956
02:15:09 INFO - 1499073309048 Marionette DEBUG Closed connection 383
02:15:09 INFO - 1499073309049 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49957
02:15:09 INFO - 1499073309050 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309051 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309054 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309058 Marionette TRACE 384 <- [1,1,null,{"sessionId":"512818f2-99aa-4649-9389-6a98bcd0c9b4","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309059 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:15:09 INFO - 1499073309060 Marionette TRACE 384 <- [1,2,null,{}]
02:15:09 INFO - 1499073309061 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:09 INFO - 1499073309061 Marionette TRACE 384 <- [1,3,null,{}]
02:15:09 INFO - 1499073309062 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:09 INFO - 1499073309063 Marionette TRACE 384 <- [1,4,null,{}]
02:15:09 INFO - 1499073309065 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}]
02:15:09 INFO - TEST-START: test_click.py TestClick.test_input_file1499073309068 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:15:09 INFO - 1499073309069 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:15:09 INFO - 1499073309071 Marionette TRACE 384 <- [1,6,null,{}]
02:15:09 INFO - 1499073309073 Marionette DEBUG Closed connection 384
02:15:09 INFO - 1499073309073 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49958
02:15:09 INFO - 1499073309074 Marionette DEBUG Closed connection 385
02:15:09 INFO - 1499073309075 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49959
02:15:09 INFO - 1499073309077 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309077 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309082 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309085 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5b2608d2-9cb4-4bbc-af58-b057b5546023","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309087 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:15:09 INFO - 1499073309089 Marionette TRACE 386 <- [1,2,null,{}]
02:15:09 INFO - 1499073309090 Marionette DEBUG Closed connection 386
02:15:09 INFO - 1499073309091 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49960
02:15:09 INFO - 1499073309092 Marionette DEBUG Closed connection 387
02:15:09 INFO - 1499073309093 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49961
02:15:09 INFO - 1499073309094 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:09 INFO - 1499073309095 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309098 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309102 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e5e9de54-a523-4ef2-8898-897fbedd1a37","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309103 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:15:09 INFO - 1499073309106 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"
02:15:09 INFO - 1499073309111 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"
02:15:09 INFO - 1499073309112 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"
02:15:09 INFO - 1499073309119 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:15:09 INFO - 1499073309124 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:15:09 INFO - 1499073309130 Marionette TRACE 388 <- [1,2,null,{}]
02:15:09 INFO - 1499073309146 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:15:09 INFO - 1499073309152 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2cf79c9-b788-42f1-af5f-c8eb468bc3aa","ELEMENT":"f2cf79c9-b788-42f1-af5f-c8eb468bc3aa"}}]
02:15:09 INFO - 1499073309153 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f2cf79c9-b788-42f1-af5f-c8eb468bc3aa"}]
02:15:09 INFO - 1499073309191 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:1330:5\n"},null]
02:15:09 INFO - 1499073309193 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}]
02:15:09 INFO - TEST-END: test_click.py TestClick.test_input_file1499073309196 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:15:09 INFO - 1499073309198 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:15:09 INFO - 1499073309200 Marionette TRACE 388 <- [1,6,null,{}]
02:15:09 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 155ms
02:15:09 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:15:09 INFO - 1499073309202 Marionette DEBUG Closed connection 388
02:15:09 INFO - 1499073309203 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49962
02:15:09 INFO - 1499073309203 Marionette DEBUG Closed connection 389
02:15:09 INFO - 1499073309204 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49963
02:15:09 INFO - 1499073309206 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309206 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309210 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309213 Marionette TRACE 390 <- [1,1,null,{"sessionId":"46bc58a3-5ad9-4774-88dc-b79c4048f0a3","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309215 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:15:09 INFO - 1499073309215 Marionette TRACE 390 <- [1,2,null,{}]
02:15:09 INFO - 1499073309216 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:09 INFO - 1499073309217 Marionette TRACE 390 <- [1,3,null,{}]
02:15:09 INFO - 1499073309218 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:09 INFO - 1499073309218 Marionette TRACE 390 <- [1,4,null,{}]
02:15:09 INFO - 1499073309220 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}]
02:15:09 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499073309224 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:15:09 INFO - 1499073309225 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:15:09 INFO - 1499073309227 Marionette TRACE 390 <- [1,6,null,{}]
02:15:09 INFO - 1499073309229 Marionette DEBUG Closed connection 390
02:15:09 INFO - 1499073309230 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49964
02:15:09 INFO - 1499073309231 Marionette DEBUG Closed connection 391
02:15:09 INFO - 1499073309231 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49965
02:15:09 INFO - 1499073309233 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309233 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309282 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309286 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8ebac189-f79e-4a04-b040-6967ecfbab48","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309287 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:15:09 INFO - 1499073309289 Marionette TRACE 392 <- [1,2,null,{}]
02:15:09 INFO - 1499073309291 Marionette DEBUG Closed connection 392
02:15:09 INFO - 1499073309292 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49966
02:15:09 INFO - 1499073309293 Marionette DEBUG Closed connection 393
02:15:09 INFO - 1499073309294 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49967
02:15:09 INFO - 1499073309295 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:09 INFO - 1499073309296 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309302 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309306 Marionette TRACE 394 <- [1,1,null,{"sessionId":"de91a284-eb44-4eaf-85d1-a873bcd07281","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309308 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"}]
02:15:09 INFO - 1499073309318 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:15:09 INFO - 1499073309321 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:15:09 INFO - 1499073309321 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:15:09 INFO - 1499073309329 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"
02:15:09 INFO - 1499073309333 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"
02:15:09 INFO - 1499073309341 Marionette TRACE 394 <- [1,2,null,{}]
02:15:09 INFO - 1499073309347 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:15:09 INFO - 1499073309354 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d3013250-49c4-428a-8387-b1b56575a5ca","ELEMENT":"d3013250-49c4-428a-8387-b1b56575a5ca"}}]
02:15:09 INFO - 1499073309356 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:15:09 INFO - 1499073309359 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e315034-de62-4fea-90b3-98796d5bb2e5","ELEMENT":"5e315034-de62-4fea-90b3-98796d5bb2e5"}}]
02:15:09 INFO - 1499073309360 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"d3013250-49c4-428a-8387-b1b56575a5ca"}]
02:15:09 INFO - 1499073309602 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:15:09 INFO - 1499073309604 Marionette TRACE 394 <- [1,5,null,{}]
02:15:09 INFO - 1499073309606 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"5e315034-de62-4fea-90b3-98796d5bb2e5"}]
02:15:09 INFO - 1499073309627 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:1330:5\n"},null]
02:15:09 INFO - 1499073309630 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:15:09 INFO - 1499073309635 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:15:09 INFO - 1499073309638 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}]
02:15:09 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499073309643 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:15:09 INFO - 1499073309645 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:15:09 INFO - 1499073309648 Marionette TRACE 394 <- [1,9,null,{}]
02:15:09 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 448ms
02:15:09 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:15:09 INFO - 1499073309651 Marionette DEBUG Closed connection 394
02:15:09 INFO - 1499073309653 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49968
02:15:09 INFO - 1499073309655 Marionette DEBUG Closed connection 395
02:15:09 INFO - 1499073309656 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49969
02:15:09 INFO - 1499073309658 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309661 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309665 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309672 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6eb94b00-81e0-4095-b4f0-adf5c0bc68ef","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309674 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:15:09 INFO - 1499073309675 Marionette TRACE 396 <- [1,2,null,{}]
02:15:09 INFO - 1499073309677 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:15:09 INFO - 1499073309678 Marionette TRACE 396 <- [1,3,null,{}]
02:15:09 INFO - 1499073309680 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:15:09 INFO - 1499073309681 Marionette TRACE 396 <- [1,4,null,{}]
02:15:09 INFO - 1499073309684 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}]
02:15:09 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499073309689 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:15:09 INFO - 1499073309690 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:15:09 INFO - 1499073309693 Marionette TRACE 396 <- [1,6,null,{}]
02:15:09 INFO - 1499073309695 Marionette DEBUG Closed connection 396
02:15:09 INFO - 1499073309696 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49970
02:15:09 INFO - 1499073309697 Marionette DEBUG Closed connection 397
02:15:09 INFO - 1499073309698 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49971
02:15:09 INFO - 1499073309702 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:15:09 INFO - 1499073309703 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309707 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309713 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5d4a3a56-80fc-4fc3-ade1-55423c5a85fe","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309715 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:15:09 INFO - 1499073309717 Marionette TRACE 398 <- [1,2,null,{}]
02:15:09 INFO - 1499073309719 Marionette DEBUG Closed connection 398
02:15:09 INFO - 1499073309720 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49972
02:15:09 INFO - 1499073309722 Marionette DEBUG Closed connection 399
02:15:09 INFO - 1499073309723 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49973
02:15:09 INFO - 1499073309724 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:15:09 INFO - 1499073309725 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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}
02:15:09 INFO - 1499073309729 Marionette DEBUG Register listener.js for window 4294967297
02:15:09 INFO - 1499073309733 Marionette TRACE 400 <- [1,1,null,{"sessionId":"470e2ecf-1ab8-498f-9ffc-1713586b2c30","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":3388,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcdbcck.mozrunner","moz:accessibilityChecks":false}}]
02:15:09 INFO - 1499073309735 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"}]
02:15:09 INFO - 1499073309738 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"
02:15:09 INFO - 1499073309740 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"
02:15:09 INFO - 1499073309741 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"
02:15:09 INFO - 1499073309758 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"
02:15:09 INFO - 1499073309760 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"
02:15:09 INFO - 1499073309776 Marionette TRACE 400 <- [1,2,null,{}]
02:15:09 INFO - 1499073309780 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:15:09 INFO - 1499073309789 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81651e88-ca9a-4592-a7bc-7fa475aeab1a","ELEMENT":"81651e88-ca9a-4592-a7bc-7fa475aeab1a"}}]
02:15:09 INFO - 1499073309790 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"81651e88-ca9a-4592-a7bc-7fa475aeab1a"}]
02:15:09 INFO - 1499073309821 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:15:09 INFO - 1499073309822 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"81651e88-ca9a-4592-a7bc-7fa475aeab1a"}]
02:15:09 INFO - 1499073309843 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element