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]
04:46:11 INFO - 1499427971471 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}]
04:46:11 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499427971473 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:46:11 INFO - 1499427971474 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:46:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
04:46:11 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:46:11 INFO - 1499427971475 Marionette TRACE 352 <- [1,6,null,{}]
04:46:11 INFO - 1499427971476 Marionette DEBUG Closed connection 352
04:46:11 INFO - 1499427971477 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49749
04:46:11 INFO - 1499427971478 Marionette DEBUG Closed connection 353
04:46:11 INFO - 1499427971478 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49750
04:46:11 INFO - 1499427971479 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:11 INFO - 1499427971480 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971482 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971484 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c9cba10a-a99d-45db-acbe-7e17a7bf854b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971485 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:46:11 INFO - 1499427971485 Marionette TRACE 354 <- [1,2,null,{}]
04:46:11 INFO - 1499427971486 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:11 INFO - 1499427971486 Marionette TRACE 354 <- [1,3,null,{}]
04:46:11 INFO - 1499427971487 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:11 INFO - 1499427971488 Marionette TRACE 354 <- [1,4,null,{}]
04:46:11 INFO - 1499427971489 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}]
04:46:11 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499427971491 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:46:11 INFO - 1499427971492 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:46:11 INFO - 1499427971493 Marionette TRACE 354 <- [1,6,null,{}]
04:46:11 INFO - 1499427971495 Marionette DEBUG Closed connection 354
04:46:11 INFO - 1499427971495 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49751
04:46:11 INFO - 1499427971496 Marionette DEBUG Closed connection 355
04:46:11 INFO - 1499427971496 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49752
04:46:11 INFO - 1499427971497 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:11 INFO - 1499427971498 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971500 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971502 Marionette TRACE 356 <- [1,1,null,{"sessionId":"96c10033-932c-4cda-b950-cf36e846d5da","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971503 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:46:11 INFO - 1499427971504 Marionette TRACE 356 <- [1,2,null,{}]
04:46:11 INFO - 1499427971506 Marionette DEBUG Closed connection 356
04:46:11 INFO - 1499427971506 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49753
04:46:11 INFO - 1499427971507 Marionette DEBUG Closed connection 357
04:46:11 INFO - 1499427971507 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49754
04:46:11 INFO - 1499427971508 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:11 INFO - 1499427971509 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971511 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971514 Marionette TRACE 358 <- [1,1,null,{"sessionId":"937c4e5e-85ad-4427-8cc5-c44212c7a7bf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971514 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49324/clicks.html"}]
04:46:11 INFO - 1499427971515 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/hidden.html"
04:46:11 INFO - 1499427971520 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/hidden.html"
04:46:11 INFO - 1499427971520 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/hidden.html"
04:46:11 INFO - 1499427971536 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/clicks.html"
04:46:11 INFO - 1499427971541 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/clicks.html"
04:46:11 INFO - 1499427971549 Marionette TRACE 358 <- [1,2,null,{}]
04:46:11 INFO - 1499427971550 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:46:11 INFO - 1499427971552 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84c8067c-2809-4628-817a-bb98c57b2d07","ELEMENT":"84c8067c-2809-4628-817a-bb98c57b2d07"}}]
04:46:11 INFO - 1499427971553 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"84c8067c-2809-4628-817a-bb98c57b2d07"}]
04:46:11 INFO - 1499427971590 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/clicks.html"
04:46:11 INFO - 1499427971597 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/clicks.html"
04:46:11 INFO - 1499427971597 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/clicks.html"
04:46:11 INFO - 1499427971615 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/test.html"
04:46:11 INFO - 1499427971616 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/test.html"
04:46:11 INFO - 1499427971618 Marionette TRACE 358 <- [1,4,null,{}]
04:46:11 INFO - 1499427971619 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:46:11 INFO - 1499427971621 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6341fa5-15d5-4ca1-a633-49b847b59546","ELEMENT":"a6341fa5-15d5-4ca1-a633-49b847b59546"}}]
04:46:11 INFO - 1499427971622 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:46:11 INFO - 1499427971622 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:46:11 INFO - 1499427971629 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}]
04:46:11 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499427971631 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:46:11 INFO - 1499427971633 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:46:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 159ms
04:46:11 INFO - TEST-START | test_click.py TestClick.test_container_element
04:46:11 INFO - 1499427971634 Marionette TRACE 358 <- [1,8,null,{}]
04:46:11 INFO - 1499427971636 Marionette DEBUG Closed connection 358
04:46:11 INFO - 1499427971637 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49757
04:46:11 INFO - 1499427971637 Marionette DEBUG Closed connection 359
04:46:11 INFO - 1499427971638 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49758
04:46:11 INFO - 1499427971639 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:11 INFO - 1499427971639 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971642 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971649 Marionette TRACE 360 <- [1,1,null,{"sessionId":"87b516bb-fc14-40b9-808c-542b297a5e5b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971650 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:46:11 INFO - 1499427971651 Marionette TRACE 360 <- [1,2,null,{}]
04:46:11 INFO - 1499427971652 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:11 INFO - 1499427971652 Marionette TRACE 360 <- [1,3,null,{}]
04:46:11 INFO - 1499427971653 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:11 INFO - 1499427971653 Marionette TRACE 360 <- [1,4,null,{}]
04:46:11 INFO - 1499427971655 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}]
04:46:11 INFO - TEST-START: test_click.py TestClick.test_container_element1499427971657 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:46:11 INFO - 1499427971658 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:46:11 INFO - 1499427971659 Marionette TRACE 360 <- [1,6,null,{}]
04:46:11 INFO - 1499427971661 Marionette DEBUG Closed connection 360
04:46:11 INFO - 1499427971662 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49759
04:46:11 INFO - 1499427971662 Marionette DEBUG Closed connection 361
04:46:11 INFO - 1499427971663 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49760
04:46:11 INFO - 1499427971664 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:11 INFO - 1499427971664 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971666 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971669 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cebb6c80-f88c-494f-8642-932b49a49dfb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971670 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:46:11 INFO - 1499427971671 Marionette TRACE 362 <- [1,2,null,{}]
04:46:11 INFO - 1499427971672 Marionette DEBUG Closed connection 362
04:46:11 INFO - 1499427971673 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49761
04:46:11 INFO - 1499427971673 Marionette DEBUG Closed connection 363
04:46:11 INFO - 1499427971674 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49762
04:46:11 INFO - 1499427971675 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:11 INFO - 1499427971675 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:11 INFO - 1499427971678 Marionette DEBUG Register listener.js for window 12
04:46:11 INFO - 1499427971681 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a1d349ed-9382-46a2-9a7c-68971ad9b404","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:11 INFO - 1499427971682 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"}]
04:46:11 INFO - 1499427971683 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/test.html"
04:46:11 INFO - 1499427971685 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/test.html"
04:46:11 INFO - 1499427971685 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/test.html"
04:46:11 INFO - 1499427971703 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"
04:46:11 INFO - 1499427971707 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"
04:46:11 INFO - 1499427971714 Marionette TRACE 364 <- [1,2,null,{}]
04:46:11 INFO - 1499427971716 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:46:11 INFO - 1499427971717 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5874b652-9fd0-4969-a3a8-eda2075702bc","ELEMENT":"5874b652-9fd0-4969-a3a8-eda2075702bc"}}]
04:46:11 INFO - 1499427971719 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5874b652-9fd0-4969-a3a8-eda2075702bc"}]
04:46:11 INFO - 1499427971961 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:46:11 INFO - 1499427971962 Marionette TRACE 364 <- [1,4,null,{}]
04:46:11 INFO - 1499427971963 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5874b652-9fd0-4969-a3a8-eda2075702bc","name":"selected"}]
04:46:11 INFO - 1499427971968 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:46:11 INFO - 1499427971970 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}]
04:46:11 INFO - TEST-END: test_click.py TestClick.test_container_element1499427971974 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:46:11 INFO - 1499427971975 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:46:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 343ms
04:46:11 INFO - 1499427971977 Marionette TRACE 364 <- [1,7,null,{}]
04:46:11 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:46:11 INFO - 1499427971995 Marionette DEBUG Closed connection 364
04:46:11 INFO - 1499427971995 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49763
04:46:11 INFO - 1499427971997 Marionette DEBUG Closed connection 365
04:46:12 INFO - 1499427971998 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49764
04:46:12 INFO - 1499427971999 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972001 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972004 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972009 Marionette TRACE 366 <- [1,1,null,{"sessionId":"baa309cb-d7a7-488d-be27-8f1a3dc4e65a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972070 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:46:12 INFO - 1499427972071 Marionette TRACE 366 <- [1,2,null,{}]
04:46:12 INFO - 1499427972073 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:12 INFO - 1499427972074 Marionette TRACE 366 <- [1,3,null,{}]
04:46:12 INFO - 1499427972076 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:12 INFO - 1499427972077 Marionette TRACE 366 <- [1,4,null,{}]
04:46:12 INFO - 1499427972079 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}]
04:46:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499427972082 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:46:12 INFO - 1499427972083 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:46:12 INFO - 1499427972085 Marionette TRACE 366 <- [1,6,null,{}]
04:46:12 INFO - 1499427972088 Marionette DEBUG Closed connection 366
04:46:12 INFO - 1499427972089 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49765
04:46:12 INFO - 1499427972105 Marionette DEBUG Closed connection 367
04:46:12 INFO - 1499427972105 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49766
04:46:12 INFO - 1499427972107 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972109 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972111 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972116 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c09ddaf0-160d-4f39-842d-a5c05353f89b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972118 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:46:12 INFO - 1499427972119 Marionette TRACE 368 <- [1,2,null,{}]
04:46:12 INFO - 1499427972121 Marionette DEBUG Closed connection 368
04:46:12 INFO - 1499427972123 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49767
04:46:12 INFO - 1499427972123 Marionette DEBUG Closed connection 369
04:46:12 INFO - 1499427972124 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49768
04:46:12 INFO - 1499427972126 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:12 INFO - 1499427972127 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972129 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972133 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b785b834-5915-420a-9b03-fcc2985c9175","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972135 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"}]
04:46:12 INFO - 1499427972136 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"
04:46:12 INFO - 1499427972140 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"
04:46:12 INFO - 1499427972140 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"
04:46:12 INFO - 1499427972162 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"
04:46:12 INFO - 1499427972164 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"
04:46:12 INFO - 1499427972169 Marionette TRACE 370 <- [1,2,null,{}]
04:46:12 INFO - 1499427972170 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:46:12 INFO - 1499427972172 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec28dcc5-9f4c-4a6f-8bb6-acf764f481cf","ELEMENT":"ec28dcc5-9f4c-4a6f-8bb6-acf764f481cf"}}]
04:46:12 INFO - 1499427972181 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ec28dcc5-9f4c-4a6f-8bb6-acf764f481cf"}]
04:46:12 INFO - 1499427972423 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:46:12 INFO - 1499427972424 Marionette TRACE 370 <- [1,4,null,{}]
04:46:12 INFO - 1499427972425 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ec28dcc5-9f4c-4a6f-8bb6-acf764f481cf","name":"selected"}]
04:46:12 INFO - 1499427972430 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:46:12 INFO - 1499427972432 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}]
04:46:12 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499427972434 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:46:12 INFO - 1499427972435 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:46:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 459ms
04:46:12 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:46:12 INFO - 1499427972437 Marionette TRACE 370 <- [1,7,null,{}]
04:46:12 INFO - 1499427972439 Marionette DEBUG Closed connection 370
04:46:12 INFO - 1499427972440 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49769
04:46:12 INFO - 1499427972441 Marionette DEBUG Closed connection 371
04:46:12 INFO - 1499427972441 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49770
04:46:12 INFO - 1499427972442 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972443 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972445 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972449 Marionette TRACE 372 <- [1,1,null,{"sessionId":"20eaf6c3-7229-40d1-8ad7-773af78d1452","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972450 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:46:12 INFO - 1499427972450 Marionette TRACE 372 <- [1,2,null,{}]
04:46:12 INFO - 1499427972451 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:12 INFO - 1499427972452 Marionette TRACE 372 <- [1,3,null,{}]
04:46:12 INFO - 1499427972453 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:12 INFO - 1499427972453 Marionette TRACE 372 <- [1,4,null,{}]
04:46:12 INFO - 1499427972455 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}]
04:46:12 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499427972457 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:46:12 INFO - 1499427972458 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:46:12 INFO - 1499427972459 Marionette TRACE 372 <- [1,6,null,{}]
04:46:12 INFO - 1499427972461 Marionette DEBUG Closed connection 372
04:46:12 INFO - 1499427972462 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49771
04:46:12 INFO - 1499427972462 Marionette DEBUG Closed connection 373
04:46:12 INFO - 1499427972463 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49772
04:46:12 INFO - 1499427972467 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972469 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972472 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972476 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5a53dafe-6874-4f99-91cb-ffd1bdc3ebd3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972477 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:46:12 INFO - 1499427972479 Marionette TRACE 374 <- [1,2,null,{}]
04:46:12 INFO - 1499427972481 Marionette DEBUG Closed connection 374
04:46:12 INFO - 1499427972481 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49773
04:46:12 INFO - 1499427972482 Marionette DEBUG Closed connection 375
04:46:12 INFO - 1499427972483 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49774
04:46:12 INFO - 1499427972491 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:12 INFO - 1499427972492 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972494 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972499 Marionette TRACE 376 <- [1,1,null,{"sessionId":"93a39499-d83a-42a1-9ded-736dbfc757e2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972501 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"}]
04:46:12 INFO - 1499427972503 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"
04:46:12 INFO - 1499427972505 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"
04:46:12 INFO - 1499427972506 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"
04:46:12 INFO - 1499427972527 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"
04:46:12 INFO - 1499427972529 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"
04:46:12 INFO - 1499427972533 Marionette TRACE 376 <- [1,2,null,{}]
04:46:12 INFO - 1499427972534 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:46:12 INFO - 1499427972536 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4181e0df-c280-490f-9299-54f6026beda9","ELEMENT":"4181e0df-c280-490f-9299-54f6026beda9"}}]
04:46:12 INFO - 1499427972537 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4181e0df-c280-490f-9299-54f6026beda9"}]
04:46:12 INFO - 1499427972775 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:46:12 INFO - 1499427972775 Marionette TRACE 376 <- [1,4,null,{}]
04:46:12 INFO - 1499427972777 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}]
04:46:12 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499427972780 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:46:12 INFO - 1499427972781 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:46:12 INFO - 1499427972783 Marionette TRACE 376 <- [1,6,null,{}]
04:46:12 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
04:46:12 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:46:12 INFO - 1499427972785 Marionette DEBUG Closed connection 376
04:46:12 INFO - 1499427972787 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49775
04:46:12 INFO - 1499427972788 Marionette DEBUG Closed connection 377
04:46:12 INFO - 1499427972788 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49776
04:46:12 INFO - 1499427972789 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972791 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972793 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972796 Marionette TRACE 378 <- [1,1,null,{"sessionId":"12092543-a1cb-47ae-b178-e4f460794efc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972798 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:46:12 INFO - 1499427972798 Marionette TRACE 378 <- [1,2,null,{}]
04:46:12 INFO - 1499427972799 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:12 INFO - 1499427972799 Marionette TRACE 378 <- [1,3,null,{}]
04:46:12 INFO - 1499427972800 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:12 INFO - 1499427972801 Marionette TRACE 378 <- [1,4,null,{}]
04:46:12 INFO - 1499427972802 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}]
04:46:12 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499427972805 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:46:12 INFO - 1499427972806 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:46:12 INFO - 1499427972807 Marionette TRACE 378 <- [1,6,null,{}]
04:46:12 INFO - 1499427972809 Marionette DEBUG Closed connection 378
04:46:12 INFO - 1499427972809 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49777
04:46:12 INFO - 1499427972810 Marionette DEBUG Closed connection 379
04:46:12 INFO - 1499427972811 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49778
04:46:12 INFO - 1499427972812 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:12 INFO - 1499427972812 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972814 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972817 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5406e6c8-5b51-49aa-818b-af13b4708287","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972818 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:46:12 INFO - 1499427972819 Marionette TRACE 380 <- [1,2,null,{}]
04:46:12 INFO - 1499427972821 Marionette DEBUG Closed connection 380
04:46:12 INFO - 1499427972821 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49779
04:46:12 INFO - 1499427972822 Marionette DEBUG Closed connection 381
04:46:12 INFO - 1499427972823 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49780
04:46:12 INFO - 1499427972823 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:12 INFO - 1499427972824 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:12 INFO - 1499427972826 Marionette DEBUG Register listener.js for window 12
04:46:12 INFO - 1499427972829 Marionette TRACE 382 <- [1,1,null,{"sessionId":"85ebdc24-c39e-454b-a56f-046c6ec56d46","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:12 INFO - 1499427972830 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"}]
04:46:12 INFO - 1499427972831 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"
04:46:12 INFO - 1499427972833 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"
04:46:12 INFO - 1499427972834 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"
04:46:12 INFO - 1499427972853 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"
04:46:12 INFO - 1499427972856 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"
04:46:12 INFO - 1499427972865 Marionette TRACE 382 <- [1,2,null,{}]
04:46:12 INFO - 1499427972866 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:46:12 INFO - 1499427972868 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"03163fd3-83ea-48c0-942e-117d4e8ea8ed","ELEMENT":"03163fd3-83ea-48c0-942e-117d4e8ea8ed"}}]
04:46:12 INFO - 1499427972869 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"03163fd3-83ea-48c0-942e-117d4e8ea8ed"}]
04:46:13 INFO - 1499427973113 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:46:13 INFO - 1499427973113 Marionette TRACE 382 <- [1,4,null,{}]
04:46:13 INFO - 1499427973115 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}]
04:46:13 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499427973118 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:46:13 INFO - 1499427973119 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:46:13 INFO - 1499427973120 Marionette TRACE 382 <- [1,6,null,{}]
04:46:13 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 337ms
04:46:13 INFO - TEST-START | test_click.py TestClick.test_input_file
04:46:13 INFO - 1499427973122 Marionette DEBUG Closed connection 382
04:46:13 INFO - 1499427973122 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49781
04:46:13 INFO - 1499427973124 Marionette DEBUG Closed connection 383
04:46:13 INFO - 1499427973124 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49782
04:46:13 INFO - 1499427973125 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973126 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973128 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973131 Marionette TRACE 384 <- [1,1,null,{"sessionId":"898e27a9-9131-4a8e-9ff5-998fbf6a1787","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973132 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:46:13 INFO - 1499427973132 Marionette TRACE 384 <- [1,2,null,{}]
04:46:13 INFO - 1499427973133 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:13 INFO - 1499427973134 Marionette TRACE 384 <- [1,3,null,{}]
04:46:13 INFO - 1499427973135 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:13 INFO - 1499427973135 Marionette TRACE 384 <- [1,4,null,{}]
04:46:13 INFO - 1499427973137 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}]
04:46:13 INFO - TEST-START: test_click.py TestClick.test_input_file1499427973139 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:46:13 INFO - 1499427973140 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:46:13 INFO - 1499427973141 Marionette TRACE 384 <- [1,6,null,{}]
04:46:13 INFO - 1499427973143 Marionette DEBUG Closed connection 384
04:46:13 INFO - 1499427973143 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49783
04:46:13 INFO - 1499427973144 Marionette DEBUG Closed connection 385
04:46:13 INFO - 1499427973145 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49784
04:46:13 INFO - 1499427973146 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973146 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973148 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973151 Marionette TRACE 386 <- [1,1,null,{"sessionId":"85eaba47-b936-4740-bb1f-2a813a767cbc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973152 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:46:13 INFO - 1499427973153 Marionette TRACE 386 <- [1,2,null,{}]
04:46:13 INFO - 1499427973154 Marionette DEBUG Closed connection 386
04:46:13 INFO - 1499427973155 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49785
04:46:13 INFO - 1499427973156 Marionette DEBUG Closed connection 387
04:46:13 INFO - 1499427973157 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49786
04:46:13 INFO - 1499427973158 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:13 INFO - 1499427973158 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973162 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973165 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7739e5ef-1a80-4475-85ff-00c6417d292d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973166 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:46:13 INFO - 1499427973167 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"
04:46:13 INFO - 1499427973170 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"
04:46:13 INFO - 1499427973171 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"
04:46:13 INFO - 1499427973188 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:46:13 INFO - 1499427973195 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:46:13 INFO - 1499427973199 Marionette TRACE 388 <- [1,2,null,{}]
04:46:13 INFO - 1499427973200 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:46:13 INFO - 1499427973202 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"de26d217-752c-4729-b3ef-5f0afc4f3a5c","ELEMENT":"de26d217-752c-4729-b3ef-5f0afc4f3a5c"}}]
04:46:13 INFO - 1499427973203 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"de26d217-752c-4729-b3ef-5f0afc4f3a5c"}]
04:46:13 INFO - 1499427973232 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]
04:46:13 INFO - 1499427973234 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}]
04:46:13 INFO - TEST-END: test_click.py TestClick.test_input_file1499427973236 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:46:13 INFO - 1499427973237 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:46:13 INFO - 1499427973238 Marionette TRACE 388 <- [1,6,null,{}]
04:46:13 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 118ms
04:46:13 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:46:13 INFO - 1499427973240 Marionette DEBUG Closed connection 388
04:46:13 INFO - 1499427973241 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49787
04:46:13 INFO - 1499427973241 Marionette DEBUG Closed connection 389
04:46:13 INFO - 1499427973242 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49788
04:46:13 INFO - 1499427973243 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973244 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973246 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973249 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b6b12167-bab0-41eb-8965-5c0327c9665a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973250 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:46:13 INFO - 1499427973250 Marionette TRACE 390 <- [1,2,null,{}]
04:46:13 INFO - 1499427973251 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:13 INFO - 1499427973252 Marionette TRACE 390 <- [1,3,null,{}]
04:46:13 INFO - 1499427973252 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:13 INFO - 1499427973253 Marionette TRACE 390 <- [1,4,null,{}]
04:46:13 INFO - 1499427973254 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}]
04:46:13 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499427973257 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:46:13 INFO - 1499427973258 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:46:13 INFO - 1499427973259 Marionette TRACE 390 <- [1,6,null,{}]
04:46:13 INFO - 1499427973261 Marionette DEBUG Closed connection 390
04:46:13 INFO - 1499427973261 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49789
04:46:13 INFO - 1499427973262 Marionette DEBUG Closed connection 391
04:46:13 INFO - 1499427973262 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49790
04:46:13 INFO - 1499427973263 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973264 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973266 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973269 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6187b587-3049-4580-a88c-9ba4304b7172","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973270 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:46:13 INFO - 1499427973271 Marionette TRACE 392 <- [1,2,null,{}]
04:46:13 INFO - 1499427973273 Marionette DEBUG Closed connection 392
04:46:13 INFO - 1499427973273 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49791
04:46:13 INFO - 1499427973274 Marionette DEBUG Closed connection 393
04:46:13 INFO - 1499427973275 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49792
04:46:13 INFO - 1499427973276 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:13 INFO - 1499427973276 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973278 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973281 Marionette TRACE 394 <- [1,1,null,{"sessionId":"94e7c81e-5f16-4f05-ac86-4c2fdda85833","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973282 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"}]
04:46:13 INFO - 1499427973283 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:46:13 INFO - 1499427973285 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:46:13 INFO - 1499427973286 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:46:13 INFO - 1499427973303 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"
04:46:13 INFO - 1499427973306 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"
04:46:13 INFO - 1499427973314 Marionette TRACE 394 <- [1,2,null,{}]
04:46:13 INFO - 1499427973315 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:46:13 INFO - 1499427973317 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b178b3e2-677e-414a-8cf1-bc4927ab96cd","ELEMENT":"b178b3e2-677e-414a-8cf1-bc4927ab96cd"}}]
04:46:13 INFO - 1499427973318 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:46:13 INFO - 1499427973320 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5c577147-e219-4c06-96bb-a5470a35ecfd","ELEMENT":"5c577147-e219-4c06-96bb-a5470a35ecfd"}}]
04:46:13 INFO - 1499427973321 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b178b3e2-677e-414a-8cf1-bc4927ab96cd"}]
04:46:13 INFO - 1499427973563 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:46:13 INFO - 1499427973564 Marionette TRACE 394 <- [1,5,null,{}]
04:46:13 INFO - 1499427973565 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"5c577147-e219-4c06-96bb-a5470a35ecfd"}]
04:46:13 INFO - 1499427973578 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:46:13 INFO - 1499427973580 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:46:13 INFO - 1499427973582 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:46:13 INFO - 1499427973584 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}]
04:46:13 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499427973586 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:46:13 INFO - 1499427973587 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:46:13 INFO - 1499427973588 Marionette TRACE 394 <- [1,9,null,{}]
04:46:13 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 350ms
04:46:13 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:46:13 INFO - 1499427973590 Marionette DEBUG Closed connection 394
04:46:13 INFO - 1499427973592 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49793
04:46:13 INFO - 1499427973593 Marionette DEBUG Closed connection 395
04:46:13 INFO - 1499427973593 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49794
04:46:13 INFO - 1499427973594 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973594 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973596 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973599 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e6341e07-dd58-49b2-b62f-f69c699657f7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973600 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:46:13 INFO - 1499427973600 Marionette TRACE 396 <- [1,2,null,{}]
04:46:13 INFO - 1499427973601 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:46:13 INFO - 1499427973601 Marionette TRACE 396 <- [1,3,null,{}]
04:46:13 INFO - 1499427973602 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:46:13 INFO - 1499427973603 Marionette TRACE 396 <- [1,4,null,{}]
04:46:13 INFO - 1499427973604 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}]
04:46:13 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499427973606 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:46:13 INFO - 1499427973607 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:46:13 INFO - 1499427973608 Marionette TRACE 396 <- [1,6,null,{}]
04:46:13 INFO - 1499427973610 Marionette DEBUG Closed connection 396
04:46:13 INFO - 1499427973610 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49795
04:46:13 INFO - 1499427973611 Marionette DEBUG Closed connection 397
04:46:13 INFO - 1499427973611 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49796
04:46:13 INFO - 1499427973612 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:46:13 INFO - 1499427973613 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973615 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973617 Marionette TRACE 398 <- [1,1,null,{"sessionId":"91539f8b-4a0a-43de-b599-6ba388171d97","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973618 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:46:13 INFO - 1499427973619 Marionette TRACE 398 <- [1,2,null,{}]
04:46:13 INFO - 1499427973622 Marionette DEBUG Closed connection 398
04:46:13 INFO - 1499427973622 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49797
04:46:13 INFO - 1499427973623 Marionette DEBUG Closed connection 399
04:46:13 INFO - 1499427973624 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49798
04:46:13 INFO - 1499427973624 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:46:13 INFO - 1499427973625 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}
04:46:13 INFO - 1499427973627 Marionette DEBUG Register listener.js for window 12
04:46:13 INFO - 1499427973630 Marionette TRACE 400 <- [1,1,null,{"sessionId":"bd3505cb-0917-487f-832c-853bb4f96f59","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-349.000\\AppData\\Local\\Temp\\tmpkystbb.mozrunner","moz:accessibilityChecks":false}}]
04:46:13 INFO - 1499427973631 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"}]
04:46:13 INFO - 1499427973632 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"
04:46:13 INFO - 1499427973636 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"
04:46:13 INFO - 1499427973636 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"
04:46:13 INFO - 1499427973659 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"
04:46:13 INFO - 1499427973661 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"
04:46:13 INFO - 1499427973664 Marionette TRACE 400 <- [1,2,null,{}]
04:46:13 INFO - 1499427973668 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:46:13 INFO - 1499427973671 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6fc99f07-560f-4e3a-95d7-8590f24aa664","ELEMENT":"6fc99f07-560f-4e3a-95d7-8590f24aa664"}}]
04:46:13 INFO - 1499427973672 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6fc99f07-560f-4e3a-95d7-8590f24aa664"}]
04:46:13 INFO - 1499427973699 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:46:13 INFO - 1499427973700 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6fc99f07-560f-4e3a-95d7-8590f24aa664"}]
04:46:13 INFO - 1499427973718 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element