could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
22:01:39 INFO - 1499749299380 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}]
22:01:39 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499749299383 Marionette TRACE 352 <- [1,5,null,{"value":null}]
22:01:39 INFO - 1499749299383 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
22:01:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 121ms
22:01:39 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:01:39 INFO - 1499749299385 Marionette TRACE 352 <- [1,6,null,{}]
22:01:39 INFO - 1499749299386 Marionette DEBUG Closed connection 352
22:01:39 INFO - 1499749299387 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49763
22:01:39 INFO - 1499749299388 Marionette DEBUG Closed connection 353
22:01:39 INFO - 1499749299389 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49764
22:01:39 INFO - 1499749299390 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299390 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299392 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299395 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8f64f0f4-51e5-4be0-825c-f7c6112cfa6d","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299397 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
22:01:39 INFO - 1499749299398 Marionette TRACE 354 <- [1,2,null,{}]
22:01:39 INFO - 1499749299398 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:39 INFO - 1499749299399 Marionette TRACE 354 <- [1,3,null,{}]
22:01:39 INFO - 1499749299400 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:39 INFO - 1499749299400 Marionette TRACE 354 <- [1,4,null,{}]
22:01:39 INFO - 1499749299401 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}]
22:01:39 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499749299404 Marionette TRACE 354 <- [1,5,null,{"value":null}]
22:01:39 INFO - 1499749299404 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
22:01:39 INFO - 1499749299406 Marionette TRACE 354 <- [1,6,null,{}]
22:01:39 INFO - 1499749299407 Marionette DEBUG Closed connection 354
22:01:39 INFO - 1499749299408 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49765
22:01:39 INFO - 1499749299409 Marionette DEBUG Closed connection 355
22:01:39 INFO - 1499749299409 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49766
22:01:39 INFO - 1499749299410 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299411 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299413 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299415 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cb8866ce-0b00-49a9-b08a-d272bc52a314","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299417 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
22:01:39 INFO - 1499749299418 Marionette TRACE 356 <- [1,2,null,{}]
22:01:39 INFO - 1499749299420 Marionette DEBUG Closed connection 356
22:01:39 INFO - 1499749299420 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49767
22:01:39 INFO - 1499749299421 Marionette DEBUG Closed connection 357
22:01:39 INFO - 1499749299421 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49768
22:01:39 INFO - 1499749299422 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:39 INFO - 1499749299423 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299425 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299427 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d5d908fb-1312-4d7c-870d-77c37d3f8935","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299429 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49328/clicks.html"}]
22:01:39 INFO - 1499749299430 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/hidden.html"
22:01:39 INFO - 1499749299435 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/hidden.html"
22:01:39 INFO - 1499749299436 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/hidden.html"
22:01:39 INFO - 1499749299454 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/clicks.html"
22:01:39 INFO - 1499749299455 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/clicks.html"
22:01:39 INFO - 1499749299458 Marionette TRACE 358 <- [1,2,null,{}]
22:01:39 INFO - 1499749299465 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:01:39 INFO - 1499749299467 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59acfa18-6ae6-4eb0-9acf-b061bc0326fa","ELEMENT":"59acfa18-6ae6-4eb0-9acf-b061bc0326fa"}}]
22:01:39 INFO - 1499749299468 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"59acfa18-6ae6-4eb0-9acf-b061bc0326fa"}]
22:01:39 INFO - 1499749299514 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/clicks.html"
22:01:39 INFO - 1499749299519 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/clicks.html"
22:01:39 INFO - 1499749299519 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/clicks.html"
22:01:39 INFO - 1499749299537 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/test.html"
22:01:39 INFO - 1499749299538 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/test.html"
22:01:39 INFO - 1499749299540 Marionette TRACE 358 <- [1,4,null,{}]
22:01:39 INFO - 1499749299541 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:01:39 INFO - 1499749299548 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fbe21594-eb3b-41d6-ae36-c7aa15b9e733","ELEMENT":"fbe21594-eb3b-41d6-ae36-c7aa15b9e733"}}]
22:01:39 INFO - 1499749299551 Marionette TRACE 358 -> [0,6,"getTitle",{}]
22:01:39 INFO - 1499749299551 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
22:01:39 INFO - 1499749299553 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}]
22:01:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499749299555 Marionette TRACE 358 <- [1,7,null,{"value":null}]
22:01:39 INFO - 1499749299556 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
22:01:39 INFO - 1499749299557 Marionette TRACE 358 <- [1,8,null,{}]
22:01:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
22:01:39 INFO - TEST-START | test_click.py TestClick.test_container_element
22:01:39 INFO - 1499749299559 Marionette DEBUG Closed connection 358
22:01:39 INFO - 1499749299560 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49771
22:01:39 INFO - 1499749299562 Marionette DEBUG Closed connection 359
22:01:39 INFO - 1499749299562 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49772
22:01:39 INFO - 1499749299563 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299564 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299566 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299569 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5132fe9e-d286-4b60-aee3-5afb52cb7294","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299571 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
22:01:39 INFO - 1499749299571 Marionette TRACE 360 <- [1,2,null,{}]
22:01:39 INFO - 1499749299572 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:39 INFO - 1499749299572 Marionette TRACE 360 <- [1,3,null,{}]
22:01:39 INFO - 1499749299573 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:39 INFO - 1499749299573 Marionette TRACE 360 <- [1,4,null,{}]
22:01:39 INFO - 1499749299575 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}]
22:01:39 INFO - TEST-START: test_click.py TestClick.test_container_element1499749299578 Marionette TRACE 360 <- [1,5,null,{"value":null}]
22:01:39 INFO - 1499749299579 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
22:01:39 INFO - 1499749299580 Marionette TRACE 360 <- [1,6,null,{}]
22:01:39 INFO - 1499749299582 Marionette DEBUG Closed connection 360
22:01:39 INFO - 1499749299582 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49773
22:01:39 INFO - 1499749299583 Marionette DEBUG Closed connection 361
22:01:39 INFO - 1499749299583 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49774
22:01:39 INFO - 1499749299584 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299585 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299587 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299589 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1d492f06-5ddb-42b2-b631-55987f8aee4e","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299591 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
22:01:39 INFO - 1499749299592 Marionette TRACE 362 <- [1,2,null,{}]
22:01:39 INFO - 1499749299594 Marionette DEBUG Closed connection 362
22:01:39 INFO - 1499749299595 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49775
22:01:39 INFO - 1499749299595 Marionette DEBUG Closed connection 363
22:01:39 INFO - 1499749299596 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49776
22:01:39 INFO - 1499749299597 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:39 INFO - 1499749299597 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299599 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299602 Marionette TRACE 364 <- [1,1,null,{"sessionId":"42b3eb7d-65da-4ca4-bac0-acda7d4e7907","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299604 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"}]
22:01:39 INFO - 1499749299605 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/test.html"
22:01:39 INFO - 1499749299606 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/test.html"
22:01:39 INFO - 1499749299607 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/test.html"
22:01:39 INFO - 1499749299626 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"
22:01:39 INFO - 1499749299632 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"
22:01:39 INFO - 1499749299635 Marionette TRACE 364 <- [1,2,null,{}]
22:01:39 INFO - 1499749299636 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:01:39 INFO - 1499749299639 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c0dd92ec-5190-49be-9799-c917c5209240","ELEMENT":"c0dd92ec-5190-49be-9799-c917c5209240"}}]
22:01:39 INFO - 1499749299640 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"c0dd92ec-5190-49be-9799-c917c5209240"}]
22:01:39 INFO - 1499749299878 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:01:39 INFO - 1499749299878 Marionette TRACE 364 <- [1,4,null,{}]
22:01:39 INFO - 1499749299880 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"c0dd92ec-5190-49be-9799-c917c5209240","name":"selected"}]
22:01:39 INFO - 1499749299883 Marionette TRACE 364 <- [1,5,null,{"value":true}]
22:01:39 INFO - 1499749299885 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}]
22:01:39 INFO - TEST-END: test_click.py TestClick.test_container_element1499749299887 Marionette TRACE 364 <- [1,6,null,{"value":null}]
22:01:39 INFO - 1499749299887 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
22:01:39 INFO - 1499749299888 Marionette TRACE 364 <- [1,7,null,{}]
22:01:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
22:01:39 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:01:39 INFO - 1499749299890 Marionette DEBUG Closed connection 364
22:01:39 INFO - 1499749299891 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49777
22:01:39 INFO - 1499749299892 Marionette DEBUG Closed connection 365
22:01:39 INFO - 1499749299893 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49778
22:01:39 INFO - 1499749299893 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299894 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299896 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299905 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e3c53dc1-2306-4e68-a4c7-25fe629b44a1","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299907 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
22:01:39 INFO - 1499749299908 Marionette TRACE 366 <- [1,2,null,{}]
22:01:39 INFO - 1499749299908 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:39 INFO - 1499749299909 Marionette TRACE 366 <- [1,3,null,{}]
22:01:39 INFO - 1499749299909 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:39 INFO - 1499749299910 Marionette TRACE 366 <- [1,4,null,{}]
22:01:39 INFO - 1499749299911 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}]
22:01:39 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499749299913 Marionette TRACE 366 <- [1,5,null,{"value":null}]
22:01:39 INFO - 1499749299914 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
22:01:39 INFO - 1499749299915 Marionette TRACE 366 <- [1,6,null,{}]
22:01:39 INFO - 1499749299917 Marionette DEBUG Closed connection 366
22:01:39 INFO - 1499749299917 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49779
22:01:39 INFO - 1499749299918 Marionette DEBUG Closed connection 367
22:01:39 INFO - 1499749299919 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49780
22:01:39 INFO - 1499749299920 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:39 INFO - 1499749299920 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299922 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299924 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3ab4cbe7-92f5-45ae-bbe5-14b501074fa7","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299926 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
22:01:39 INFO - 1499749299927 Marionette TRACE 368 <- [1,2,null,{}]
22:01:39 INFO - 1499749299929 Marionette DEBUG Closed connection 368
22:01:39 INFO - 1499749299929 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49781
22:01:39 INFO - 1499749299930 Marionette DEBUG Closed connection 369
22:01:39 INFO - 1499749299931 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49782
22:01:39 INFO - 1499749299932 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:39 INFO - 1499749299932 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:39 INFO - 1499749299934 Marionette DEBUG Register listener.js for window 12
22:01:39 INFO - 1499749299936 Marionette TRACE 370 <- [1,1,null,{"sessionId":"11414a51-ea56-41d3-a198-9d8ef54b4220","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:39 INFO - 1499749299938 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"}]
22:01:39 INFO - 1499749299939 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"
22:01:39 INFO - 1499749299940 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"
22:01:39 INFO - 1499749299941 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"
22:01:39 INFO - 1499749299955 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"
22:01:39 INFO - 1499749299958 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"
22:01:39 INFO - 1499749299966 Marionette TRACE 370 <- [1,2,null,{}]
22:01:39 INFO - 1499749299968 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:01:39 INFO - 1499749299970 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a034c30f-d063-426c-ac21-2e40554a4551","ELEMENT":"a034c30f-d063-426c-ac21-2e40554a4551"}}]
22:01:39 INFO - 1499749299970 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a034c30f-d063-426c-ac21-2e40554a4551"}]
22:01:40 INFO - 1499749300208 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:01:40 INFO - 1499749300208 Marionette TRACE 370 <- [1,4,null,{}]
22:01:40 INFO - 1499749300209 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a034c30f-d063-426c-ac21-2e40554a4551","name":"selected"}]
22:01:40 INFO - 1499749300213 Marionette TRACE 370 <- [1,5,null,{"value":true}]
22:01:40 INFO - 1499749300214 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}]
22:01:40 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499749300216 Marionette TRACE 370 <- [1,6,null,{"value":null}]
22:01:40 INFO - 1499749300218 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
22:01:40 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 331ms
22:01:40 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:01:40 INFO - 1499749300219 Marionette TRACE 370 <- [1,7,null,{}]
22:01:40 INFO - 1499749300221 Marionette DEBUG Closed connection 370
22:01:40 INFO - 1499749300222 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49783
22:01:40 INFO - 1499749300222 Marionette DEBUG Closed connection 371
22:01:40 INFO - 1499749300223 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49784
22:01:40 INFO - 1499749300224 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300224 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300226 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300230 Marionette TRACE 372 <- [1,1,null,{"sessionId":"45c5d888-d221-4b33-b5a5-ca61fce2f657","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300232 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
22:01:40 INFO - 1499749300232 Marionette TRACE 372 <- [1,2,null,{}]
22:01:40 INFO - 1499749300233 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:40 INFO - 1499749300234 Marionette TRACE 372 <- [1,3,null,{}]
22:01:40 INFO - 1499749300234 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:40 INFO - 1499749300235 Marionette TRACE 372 <- [1,4,null,{}]
22:01:40 INFO - 1499749300236 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}]
22:01:40 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499749300238 Marionette TRACE 372 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300239 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300240 Marionette TRACE 372 <- [1,6,null,{}]
22:01:40 INFO - 1499749300242 Marionette DEBUG Closed connection 372
22:01:40 INFO - 1499749300242 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49785
22:01:40 INFO - 1499749300243 Marionette DEBUG Closed connection 373
22:01:40 INFO - 1499749300243 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49786
22:01:40 INFO - 1499749300244 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300245 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300247 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300249 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4bd63e7c-7119-4017-84e5-e58f294502df","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300251 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
22:01:40 INFO - 1499749300252 Marionette TRACE 374 <- [1,2,null,{}]
22:01:40 INFO - 1499749300253 Marionette DEBUG Closed connection 374
22:01:40 INFO - 1499749300254 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49787
22:01:40 INFO - 1499749300254 Marionette DEBUG Closed connection 375
22:01:40 INFO - 1499749300255 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49788
22:01:40 INFO - 1499749300256 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:40 INFO - 1499749300257 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300259 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300261 Marionette TRACE 376 <- [1,1,null,{"sessionId":"24f83ff4-4c72-4358-9834-48fc1d716557","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300263 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"}]
22:01:40 INFO - 1499749300264 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"
22:01:40 INFO - 1499749300266 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"
22:01:40 INFO - 1499749300266 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"
22:01:40 INFO - 1499749300275 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"
22:01:40 INFO - 1499749300279 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"
22:01:40 INFO - 1499749300287 Marionette TRACE 376 <- [1,2,null,{}]
22:01:40 INFO - 1499749300289 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:01:40 INFO - 1499749300291 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0c01f7b9-c77a-4725-bda4-3852b60866e2","ELEMENT":"0c01f7b9-c77a-4725-bda4-3852b60866e2"}}]
22:01:40 INFO - 1499749300292 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"0c01f7b9-c77a-4725-bda4-3852b60866e2"}]
22:01:40 INFO - 1499749300533 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:01:40 INFO - 1499749300533 Marionette TRACE 376 <- [1,4,null,{}]
22:01:40 INFO - 1499749300535 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}]
22:01:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499749300537 Marionette TRACE 376 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300538 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300539 Marionette TRACE 376 <- [1,6,null,{}]
22:01:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
22:01:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:01:40 INFO - 1499749300541 Marionette DEBUG Closed connection 376
22:01:40 INFO - 1499749300542 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49789
22:01:40 INFO - 1499749300542 Marionette DEBUG Closed connection 377
22:01:40 INFO - 1499749300543 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49790
22:01:40 INFO - 1499749300544 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300545 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300547 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300549 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d6ebaa95-c5a3-4b5b-b1ca-5961b3907fbd","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300552 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
22:01:40 INFO - 1499749300552 Marionette TRACE 378 <- [1,2,null,{}]
22:01:40 INFO - 1499749300553 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:40 INFO - 1499749300554 Marionette TRACE 378 <- [1,3,null,{}]
22:01:40 INFO - 1499749300555 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:40 INFO - 1499749300555 Marionette TRACE 378 <- [1,4,null,{}]
22:01:40 INFO - 1499749300557 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}]
22:01:40 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499749300559 Marionette TRACE 378 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300560 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300561 Marionette TRACE 378 <- [1,6,null,{}]
22:01:40 INFO - 1499749300562 Marionette DEBUG Closed connection 378
22:01:40 INFO - 1499749300563 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49791
22:01:40 INFO - 1499749300564 Marionette DEBUG Closed connection 379
22:01:40 INFO - 1499749300564 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49792
22:01:40 INFO - 1499749300566 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300566 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300568 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300570 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b44556b3-d7a5-4374-9500-2a8b0f6a99ea","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300572 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
22:01:40 INFO - 1499749300573 Marionette TRACE 380 <- [1,2,null,{}]
22:01:40 INFO - 1499749300574 Marionette DEBUG Closed connection 380
22:01:40 INFO - 1499749300575 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49793
22:01:40 INFO - 1499749300576 Marionette DEBUG Closed connection 381
22:01:40 INFO - 1499749300576 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49794
22:01:40 INFO - 1499749300577 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:40 INFO - 1499749300578 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300579 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300582 Marionette TRACE 382 <- [1,1,null,{"sessionId":"655259fd-18bc-4623-a06a-e8ff670711cc","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300584 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"}]
22:01:40 INFO - 1499749300585 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"
22:01:40 INFO - 1499749300586 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"
22:01:40 INFO - 1499749300587 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"
22:01:40 INFO - 1499749300603 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"
22:01:40 INFO - 1499749300606 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"
22:01:40 INFO - 1499749300609 Marionette TRACE 382 <- [1,2,null,{}]
22:01:40 INFO - 1499749300616 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:01:40 INFO - 1499749300619 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a15bd73f-45a9-49a7-bbf5-fbb7dadc07a9","ELEMENT":"a15bd73f-45a9-49a7-bbf5-fbb7dadc07a9"}}]
22:01:40 INFO - 1499749300620 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a15bd73f-45a9-49a7-bbf5-fbb7dadc07a9"}]
22:01:40 INFO - 1499749300855 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:01:40 INFO - 1499749300856 Marionette TRACE 382 <- [1,4,null,{}]
22:01:40 INFO - 1499749300857 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}]
22:01:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499749300859 Marionette TRACE 382 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300860 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
22:01:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 322ms
22:01:40 INFO - TEST-START | test_click.py TestClick.test_input_file
22:01:40 INFO - 1499749300861 Marionette TRACE 382 <- [1,6,null,{}]
22:01:40 INFO - 1499749300863 Marionette DEBUG Closed connection 382
22:01:40 INFO - 1499749300864 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49795
22:01:40 INFO - 1499749300865 Marionette DEBUG Closed connection 383
22:01:40 INFO - 1499749300865 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49796
22:01:40 INFO - 1499749300866 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300867 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300869 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300871 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4dc9def5-1069-4eb4-a088-db933cbd74c1","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300873 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
22:01:40 INFO - 1499749300873 Marionette TRACE 384 <- [1,2,null,{}]
22:01:40 INFO - 1499749300874 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:40 INFO - 1499749300874 Marionette TRACE 384 <- [1,3,null,{}]
22:01:40 INFO - 1499749300875 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:40 INFO - 1499749300876 Marionette TRACE 384 <- [1,4,null,{}]
22:01:40 INFO - 1499749300877 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}]
22:01:40 INFO - TEST-START: test_click.py TestClick.test_input_file1499749300879 Marionette TRACE 384 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300880 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300881 Marionette TRACE 384 <- [1,6,null,{}]
22:01:40 INFO - 1499749300883 Marionette DEBUG Closed connection 384
22:01:40 INFO - 1499749300884 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49797
22:01:40 INFO - 1499749300885 Marionette DEBUG Closed connection 385
22:01:40 INFO - 1499749300885 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49798
22:01:40 INFO - 1499749300886 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300887 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300888 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300897 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5cd116d1-342e-4ab5-8056-35c1640923ad","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300899 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
22:01:40 INFO - 1499749300900 Marionette TRACE 386 <- [1,2,null,{}]
22:01:40 INFO - 1499749300902 Marionette DEBUG Closed connection 386
22:01:40 INFO - 1499749300902 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49799
22:01:40 INFO - 1499749300903 Marionette DEBUG Closed connection 387
22:01:40 INFO - 1499749300904 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49800
22:01:40 INFO - 1499749300905 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:40 INFO - 1499749300905 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300907 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300909 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7e79cdc9-2cf8-4050-bb84-83e87b8267fe","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300911 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:01:40 INFO - 1499749300912 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"
22:01:40 INFO - 1499749300914 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"
22:01:40 INFO - 1499749300914 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"
22:01:40 INFO - 1499749300923 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:01:40 INFO - 1499749300926 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:01:40 INFO - 1499749300934 Marionette TRACE 388 <- [1,2,null,{}]
22:01:40 INFO - 1499749300936 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:01:40 INFO - 1499749300938 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"502d1c06-236b-4057-9ddd-b66fc1d7bc11","ELEMENT":"502d1c06-236b-4057-9ddd-b66fc1d7bc11"}}]
22:01:40 INFO - 1499749300939 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"502d1c06-236b-4057-9ddd-b66fc1d7bc11"}]
22:01:40 INFO - 1499749300962 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
22:01:40 INFO - 1499749300964 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}]
22:01:40 INFO - TEST-END: test_click.py TestClick.test_input_file1499749300966 Marionette TRACE 388 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300967 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300968 Marionette TRACE 388 <- [1,6,null,{}]
22:01:40 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
22:01:40 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:01:40 INFO - 1499749300970 Marionette DEBUG Closed connection 388
22:01:40 INFO - 1499749300971 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49801
22:01:40 INFO - 1499749300972 Marionette DEBUG Closed connection 389
22:01:40 INFO - 1499749300972 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49802
22:01:40 INFO - 1499749300973 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300973 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300975 Marionette DEBUG Register listener.js for window 12
22:01:40 INFO - 1499749300978 Marionette TRACE 390 <- [1,1,null,{"sessionId":"13b5c186-0287-48c6-8e3e-9751e87eeb4b","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:40 INFO - 1499749300980 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
22:01:40 INFO - 1499749300980 Marionette TRACE 390 <- [1,2,null,{}]
22:01:40 INFO - 1499749300982 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:40 INFO - 1499749300982 Marionette TRACE 390 <- [1,3,null,{}]
22:01:40 INFO - 1499749300983 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:40 INFO - 1499749300983 Marionette TRACE 390 <- [1,4,null,{}]
22:01:40 INFO - 1499749300985 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}]
22:01:40 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499749300987 Marionette TRACE 390 <- [1,5,null,{"value":null}]
22:01:40 INFO - 1499749300988 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
22:01:40 INFO - 1499749300989 Marionette TRACE 390 <- [1,6,null,{}]
22:01:40 INFO - 1499749300990 Marionette DEBUG Closed connection 390
22:01:40 INFO - 1499749300991 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49803
22:01:40 INFO - 1499749300991 Marionette DEBUG Closed connection 391
22:01:40 INFO - 1499749300992 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49804
22:01:40 INFO - 1499749300993 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:40 INFO - 1499749300993 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:40 INFO - 1499749300995 Marionette DEBUG Register listener.js for window 12
22:01:41 INFO - 1499749300998 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6779ed0f-b5c8-4586-ab11-d6c04be293ff","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:41 INFO - 1499749301000 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
22:01:41 INFO - 1499749301001 Marionette TRACE 392 <- [1,2,null,{}]
22:01:41 INFO - 1499749301002 Marionette DEBUG Closed connection 392
22:01:41 INFO - 1499749301003 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49805
22:01:41 INFO - 1499749301003 Marionette DEBUG Closed connection 393
22:01:41 INFO - 1499749301004 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49806
22:01:41 INFO - 1499749301005 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:41 INFO - 1499749301005 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:41 INFO - 1499749301007 Marionette DEBUG Register listener.js for window 12
22:01:41 INFO - 1499749301010 Marionette TRACE 394 <- [1,1,null,{"sessionId":"01cad2fc-c9cb-4b6e-aa3c-03efbead7415","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:41 INFO - 1499749301012 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"}]
22:01:41 INFO - 1499749301013 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:01:41 INFO - 1499749301015 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:01:41 INFO - 1499749301015 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:01:41 INFO - 1499749301024 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"
22:01:41 INFO - 1499749301027 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"
22:01:41 INFO - 1499749301036 Marionette TRACE 394 <- [1,2,null,{}]
22:01:41 INFO - 1499749301037 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:01:41 INFO - 1499749301039 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b564d647-9624-440c-bfb2-26e7234732c3","ELEMENT":"b564d647-9624-440c-bfb2-26e7234732c3"}}]
22:01:41 INFO - 1499749301040 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:01:41 INFO - 1499749301041 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"073bccd6-c0c2-4295-8fe3-cd053d769b67","ELEMENT":"073bccd6-c0c2-4295-8fe3-cd053d769b67"}}]
22:01:41 INFO - 1499749301042 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b564d647-9624-440c-bfb2-26e7234732c3"}]
22:01:41 INFO - 1499749301282 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:01:41 INFO - 1499749301283 Marionette TRACE 394 <- [1,5,null,{}]
22:01:41 INFO - 1499749301284 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"073bccd6-c0c2-4295-8fe3-cd053d769b67"}]
22:01:41 INFO - 1499749301299 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
22:01:41 INFO - 1499749301301 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:01:41 INFO - 1499749301303 Marionette TRACE 394 <- [1,7,null,{"value":false}]
22:01:41 INFO - 1499749301304 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}]
22:01:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499749301306 Marionette TRACE 394 <- [1,8,null,{"value":null}]
22:01:41 INFO - 1499749301307 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
22:01:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
22:01:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:01:41 INFO - 1499749301308 Marionette TRACE 394 <- [1,9,null,{}]
22:01:41 INFO - 1499749301310 Marionette DEBUG Closed connection 394
22:01:41 INFO - 1499749301311 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49807
22:01:41 INFO - 1499749301311 Marionette DEBUG Closed connection 395
22:01:41 INFO - 1499749301312 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49808
22:01:41 INFO - 1499749301313 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:41 INFO - 1499749301313 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:41 INFO - 1499749301315 Marionette DEBUG Register listener.js for window 12
22:01:41 INFO - 1499749301318 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6e4ad104-85c1-4374-9906-9f716b273b50","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:41 INFO - 1499749301319 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
22:01:41 INFO - 1499749301320 Marionette TRACE 396 <- [1,2,null,{}]
22:01:41 INFO - 1499749301321 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:01:41 INFO - 1499749301321 Marionette TRACE 396 <- [1,3,null,{}]
22:01:41 INFO - 1499749301322 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
22:01:41 INFO - 1499749301322 Marionette TRACE 396 <- [1,4,null,{}]
22:01:41 INFO - 1499749301324 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}]
22:01:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499749301326 Marionette TRACE 396 <- [1,5,null,{"value":null}]
22:01:41 INFO - 1499749301327 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
22:01:41 INFO - 1499749301328 Marionette TRACE 396 <- [1,6,null,{}]
22:01:41 INFO - 1499749301329 Marionette DEBUG Closed connection 396
22:01:41 INFO - 1499749301330 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49809
22:01:41 INFO - 1499749301331 Marionette DEBUG Closed connection 397
22:01:41 INFO - 1499749301331 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49810
22:01:41 INFO - 1499749301332 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:01:41 INFO - 1499749301333 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:41 INFO - 1499749301335 Marionette DEBUG Register listener.js for window 12
22:01:41 INFO - 1499749301337 Marionette TRACE 398 <- [1,1,null,{"sessionId":"bd4137f4-34aa-48ea-8486-de4eef272e37","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:41 INFO - 1499749301339 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
22:01:41 INFO - 1499749301340 Marionette TRACE 398 <- [1,2,null,{}]
22:01:41 INFO - 1499749301342 Marionette DEBUG Closed connection 398
22:01:41 INFO - 1499749301342 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49811
22:01:41 INFO - 1499749301343 Marionette DEBUG Closed connection 399
22:01:41 INFO - 1499749301343 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49812
22:01:41 INFO - 1499749301344 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:01:41 INFO - 1499749301345 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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}
22:01:41 INFO - 1499749301347 Marionette DEBUG Register listener.js for window 12
22:01:41 INFO - 1499749301349 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1da0c754-ec2c-430a-8a99-f419f4fb672c","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":1360,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-335.001\\AppData\\Local\\Temp\\tmpb_vtud.mozrunner","moz:accessibilityChecks":false}}]
22:01:41 INFO - 1499749301351 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"}]
22:01:41 INFO - 1499749301352 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"
22:01:41 INFO - 1499749301354 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"
22:01:41 INFO - 1499749301354 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"
22:01:41 INFO - 1499749301371 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"
22:01:41 INFO - 1499749301373 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"
22:01:41 INFO - 1499749301375 Marionette TRACE 400 <- [1,2,null,{}]
22:01:41 INFO - 1499749301382 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:01:41 INFO - 1499749301384 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38d7d74c-aea4-4a64-a1e2-0584bfb9aeb3","ELEMENT":"38d7d74c-aea4-4a64-a1e2-0584bfb9aeb3"}}]
22:01:41 INFO - 1499749301385 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"38d7d74c-aea4-4a64-a1e2-0584bfb9aeb3"}]
22:01:41 INFO - 1499749301408 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
22:01:41 INFO - 1499749301409 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"38d7d74c-aea4-4a64-a1e2-0584bfb9aeb3"}]
22:01:41 INFO - 1499749301423 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element