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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:42:32 INFO - 1500885752317 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}]
01:42:32 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500885752321 Marionette TRACE 352 <- [1,5,null,{"value":null}]
01:42:32 INFO - 1500885752323 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
01:42:32 INFO - 1500885752324 Marionette TRACE 352 <- [1,6,null,{}]
01:42:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 156ms
01:42:32 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:42:32 INFO - 1500885752326 Marionette DEBUG Closed connection 352
01:42:32 INFO - 1500885752327 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49764
01:42:32 INFO - 1500885752328 Marionette DEBUG Closed connection 353
01:42:32 INFO - 1500885752329 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49765
01:42:32 INFO - 1500885752330 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752331 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752334 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752337 Marionette TRACE 354 <- [1,1,null,{"sessionId":"76a20410-b677-45d6-b01d-1bb070b24ce8","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752340 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
01:42:32 INFO - 1500885752341 Marionette TRACE 354 <- [1,2,null,{}]
01:42:32 INFO - 1500885752342 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:32 INFO - 1500885752342 Marionette TRACE 354 <- [1,3,null,{}]
01:42:32 INFO - 1500885752343 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:32 INFO - 1500885752344 Marionette TRACE 354 <- [1,4,null,{}]
01:42:32 INFO - 1500885752345 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}]
01:42:32 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500885752348 Marionette TRACE 354 <- [1,5,null,{"value":null}]
01:42:32 INFO - 1500885752349 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
01:42:32 INFO - 1500885752350 Marionette TRACE 354 <- [1,6,null,{}]
01:42:32 INFO - 1500885752352 Marionette DEBUG Closed connection 354
01:42:32 INFO - 1500885752353 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49766
01:42:32 INFO - 1500885752354 Marionette DEBUG Closed connection 355
01:42:32 INFO - 1500885752354 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49767
01:42:32 INFO - 1500885752355 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752356 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752359 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752362 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d7ece6a3-aad1-429f-99e8-fb1fe0bb44f1","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752364 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
01:42:32 INFO - 1500885752365 Marionette TRACE 356 <- [1,2,null,{}]
01:42:32 INFO - 1500885752367 Marionette DEBUG Closed connection 356
01:42:32 INFO - 1500885752368 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49768
01:42:32 INFO - 1500885752369 Marionette DEBUG Closed connection 357
01:42:32 INFO - 1500885752370 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49769
01:42:32 INFO - 1500885752371 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:32 INFO - 1500885752371 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752374 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752377 Marionette TRACE 358 <- [1,1,null,{"sessionId":"169aa6e5-507d-44f1-8e27-c63173a40ea6","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752379 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
01:42:32 INFO - 1500885752381 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
01:42:32 INFO - 1500885752386 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
01:42:32 INFO - 1500885752386 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
01:42:32 INFO - 1500885752408 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
01:42:32 INFO - 1500885752410 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
01:42:32 INFO - 1500885752413 Marionette TRACE 358 <- [1,2,null,{}]
01:42:32 INFO - 1500885752420 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:42:32 INFO - 1500885752423 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"37bc3c03-0658-413c-83bd-9931e632cd11","ELEMENT":"37bc3c03-0658-413c-83bd-9931e632cd11"}}]
01:42:32 INFO - 1500885752424 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"37bc3c03-0658-413c-83bd-9931e632cd11"}]
01:42:32 INFO - 1500885752475 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
01:42:32 INFO - 1500885752485 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
01:42:32 INFO - 1500885752486 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
01:42:32 INFO - 1500885752507 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
01:42:32 INFO - 1500885752509 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
01:42:32 INFO - 1500885752512 Marionette TRACE 358 <- [1,4,null,{}]
01:42:32 INFO - 1500885752520 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:42:32 INFO - 1500885752522 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3163bcc-4b63-4d7c-b933-6c17a0bc4cd4","ELEMENT":"a3163bcc-4b63-4d7c-b933-6c17a0bc4cd4"}}]
01:42:32 INFO - 1500885752523 Marionette TRACE 358 -> [0,6,"getTitle",{}]
01:42:32 INFO - 1500885752524 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
01:42:32 INFO - 1500885752526 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}]
01:42:32 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500885752528 Marionette TRACE 358 <- [1,7,null,{"value":null}]
01:42:32 INFO - 1500885752530 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
01:42:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 207ms
01:42:32 INFO - TEST-START | test_click.py TestClick.test_container_element
01:42:32 INFO - 1500885752531 Marionette TRACE 358 <- [1,8,null,{}]
01:42:32 INFO - 1500885752533 Marionette DEBUG Closed connection 358
01:42:32 INFO - 1500885752534 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49772
01:42:32 INFO - 1500885752535 Marionette DEBUG Closed connection 359
01:42:32 INFO - 1500885752535 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49773
01:42:32 INFO - 1500885752537 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752537 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752540 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752543 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a0a7b57d-b8ca-4f28-b30c-ef43aae8d1d0","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752545 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
01:42:32 INFO - 1500885752546 Marionette TRACE 360 <- [1,2,null,{}]
01:42:32 INFO - 1500885752547 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:32 INFO - 1500885752547 Marionette TRACE 360 <- [1,3,null,{}]
01:42:32 INFO - 1500885752548 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:32 INFO - 1500885752548 Marionette TRACE 360 <- [1,4,null,{}]
01:42:32 INFO - 1500885752550 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}]
01:42:32 INFO - TEST-START: test_click.py TestClick.test_container_element1500885752552 Marionette TRACE 360 <- [1,5,null,{"value":null}]
01:42:32 INFO - 1500885752553 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
01:42:32 INFO - 1500885752555 Marionette TRACE 360 <- [1,6,null,{}]
01:42:32 INFO - 1500885752557 Marionette DEBUG Closed connection 360
01:42:32 INFO - 1500885752557 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49774
01:42:32 INFO - 1500885752558 Marionette DEBUG Closed connection 361
01:42:32 INFO - 1500885752559 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49775
01:42:32 INFO - 1500885752560 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752560 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752563 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752566 Marionette TRACE 362 <- [1,1,null,{"sessionId":"acfec269-58d9-4008-92be-ec3e36dfab06","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752568 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
01:42:32 INFO - 1500885752570 Marionette TRACE 362 <- [1,2,null,{}]
01:42:32 INFO - 1500885752572 Marionette DEBUG Closed connection 362
01:42:32 INFO - 1500885752572 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49776
01:42:32 INFO - 1500885752573 Marionette DEBUG Closed connection 363
01:42:32 INFO - 1500885752574 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49777
01:42:32 INFO - 1500885752575 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:32 INFO - 1500885752576 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752578 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752581 Marionette TRACE 364 <- [1,1,null,{"sessionId":"48e13d82-285f-4d35-81e4-8d4abb8476da","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752584 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"}]
01:42:32 INFO - 1500885752585 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
01:42:32 INFO - 1500885752587 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
01:42:32 INFO - 1500885752587 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
01:42:32 INFO - 1500885752611 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"
01:42:32 INFO - 1500885752616 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"
01:42:32 INFO - 1500885752620 Marionette TRACE 364 <- [1,2,null,{}]
01:42:32 INFO - 1500885752621 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:42:32 INFO - 1500885752623 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"debf4555-beee-466e-8802-971b15e74214","ELEMENT":"debf4555-beee-466e-8802-971b15e74214"}}]
01:42:32 INFO - 1500885752625 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"debf4555-beee-466e-8802-971b15e74214"}]
01:42:32 INFO - 1500885752879 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:42:32 INFO - 1500885752880 Marionette TRACE 364 <- [1,4,null,{}]
01:42:32 INFO - 1500885752881 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"debf4555-beee-466e-8802-971b15e74214","name":"selected"}]
01:42:32 INFO - 1500885752886 Marionette TRACE 364 <- [1,5,null,{"value":true}]
01:42:32 INFO - 1500885752888 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}]
01:42:32 INFO - TEST-END: test_click.py TestClick.test_container_element1500885752891 Marionette TRACE 364 <- [1,6,null,{"value":null}]
01:42:32 INFO - 1500885752892 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
01:42:32 INFO - 1500885752893 Marionette TRACE 364 <- [1,7,null,{}]
01:42:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 362ms
01:42:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:42:32 INFO - 1500885752895 Marionette DEBUG Closed connection 364
01:42:32 INFO - 1500885752896 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49778
01:42:32 INFO - 1500885752897 Marionette DEBUG Closed connection 365
01:42:32 INFO - 1500885752897 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49779
01:42:32 INFO - 1500885752898 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752899 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752902 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752905 Marionette TRACE 366 <- [1,1,null,{"sessionId":"eafc84b0-143f-4105-8a8f-0037929457fb","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752907 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
01:42:32 INFO - 1500885752907 Marionette TRACE 366 <- [1,2,null,{}]
01:42:32 INFO - 1500885752908 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:32 INFO - 1500885752908 Marionette TRACE 366 <- [1,3,null,{}]
01:42:32 INFO - 1500885752909 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:32 INFO - 1500885752911 Marionette TRACE 366 <- [1,4,null,{}]
01:42:32 INFO - 1500885752912 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}]
01:42:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500885752915 Marionette TRACE 366 <- [1,5,null,{"value":null}]
01:42:32 INFO - 1500885752916 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
01:42:32 INFO - 1500885752918 Marionette TRACE 366 <- [1,6,null,{}]
01:42:32 INFO - 1500885752920 Marionette DEBUG Closed connection 366
01:42:32 INFO - 1500885752921 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49780
01:42:32 INFO - 1500885752922 Marionette DEBUG Closed connection 367
01:42:32 INFO - 1500885752923 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49781
01:42:32 INFO - 1500885752924 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:32 INFO - 1500885752924 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752927 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752938 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c35bd3da-1874-4915-83fd-a5e33200f154","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752941 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
01:42:32 INFO - 1500885752942 Marionette TRACE 368 <- [1,2,null,{}]
01:42:32 INFO - 1500885752944 Marionette DEBUG Closed connection 368
01:42:32 INFO - 1500885752945 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49782
01:42:32 INFO - 1500885752946 Marionette DEBUG Closed connection 369
01:42:32 INFO - 1500885752946 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49783
01:42:32 INFO - 1500885752947 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:32 INFO - 1500885752948 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:32 INFO - 1500885752951 Marionette DEBUG Register listener.js for window 15
01:42:32 INFO - 1500885752955 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c7412d5a-8b75-4895-befd-e8433c2bbfb6","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:32 INFO - 1500885752957 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"}]
01:42:32 INFO - 1500885752958 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"
01:42:32 INFO - 1500885752960 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"
01:42:32 INFO - 1500885752960 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"
01:42:32 INFO - 1500885752982 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"
01:42:32 INFO - 1500885752984 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"
01:42:32 INFO - 1500885752987 Marionette TRACE 370 <- [1,2,null,{}]
01:42:32 INFO - 1500885752989 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:42:32 INFO - 1500885752991 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0be25713-96a1-4d10-8f78-aec77469bdc3","ELEMENT":"0be25713-96a1-4d10-8f78-aec77469bdc3"}}]
01:42:32 INFO - 1500885752992 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"0be25713-96a1-4d10-8f78-aec77469bdc3"}]
01:42:33 INFO - 1500885753247 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:42:33 INFO - 1500885753248 Marionette TRACE 370 <- [1,4,null,{}]
01:42:33 INFO - 1500885753249 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"0be25713-96a1-4d10-8f78-aec77469bdc3","name":"selected"}]
01:42:33 INFO - 1500885753254 Marionette TRACE 370 <- [1,5,null,{"value":true}]
01:42:33 INFO - 1500885753257 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}]
01:42:33 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500885753259 Marionette TRACE 370 <- [1,6,null,{"value":null}]
01:42:33 INFO - 1500885753260 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
01:42:33 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
01:42:33 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:42:33 INFO - 1500885753261 Marionette TRACE 370 <- [1,7,null,{}]
01:42:33 INFO - 1500885753264 Marionette DEBUG Closed connection 370
01:42:33 INFO - 1500885753264 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49784
01:42:33 INFO - 1500885753265 Marionette DEBUG Closed connection 371
01:42:33 INFO - 1500885753266 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49785
01:42:33 INFO - 1500885753266 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753267 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753270 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753273 Marionette TRACE 372 <- [1,1,null,{"sessionId":"97ab8aa8-1fa2-4636-ae1c-a17109b0d0ad","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753275 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
01:42:33 INFO - 1500885753275 Marionette TRACE 372 <- [1,2,null,{}]
01:42:33 INFO - 1500885753276 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:33 INFO - 1500885753277 Marionette TRACE 372 <- [1,3,null,{}]
01:42:33 INFO - 1500885753277 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:33 INFO - 1500885753278 Marionette TRACE 372 <- [1,4,null,{}]
01:42:33 INFO - 1500885753280 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}]
01:42:33 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500885753282 Marionette TRACE 372 <- [1,5,null,{"value":null}]
01:42:33 INFO - 1500885753283 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
01:42:33 INFO - 1500885753284 Marionette TRACE 372 <- [1,6,null,{}]
01:42:33 INFO - 1500885753286 Marionette DEBUG Closed connection 372
01:42:33 INFO - 1500885753287 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49786
01:42:33 INFO - 1500885753288 Marionette DEBUG Closed connection 373
01:42:33 INFO - 1500885753288 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49787
01:42:33 INFO - 1500885753289 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753290 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753293 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753296 Marionette TRACE 374 <- [1,1,null,{"sessionId":"70ddca59-4eb1-4b81-8e6c-660708dabd8b","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753298 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
01:42:33 INFO - 1500885753300 Marionette TRACE 374 <- [1,2,null,{}]
01:42:33 INFO - 1500885753302 Marionette DEBUG Closed connection 374
01:42:33 INFO - 1500885753302 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49788
01:42:33 INFO - 1500885753303 Marionette DEBUG Closed connection 375
01:42:33 INFO - 1500885753304 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49789
01:42:33 INFO - 1500885753305 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:33 INFO - 1500885753305 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753308 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753311 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f2f68b94-a31d-4c7d-b217-c2a52635c567","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753314 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"}]
01:42:33 INFO - 1500885753315 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"
01:42:33 INFO - 1500885753317 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"
01:42:33 INFO - 1500885753318 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"
01:42:33 INFO - 1500885753339 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"
01:42:33 INFO - 1500885753341 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"
01:42:33 INFO - 1500885753345 Marionette TRACE 376 <- [1,2,null,{}]
01:42:33 INFO - 1500885753351 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:42:33 INFO - 1500885753354 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6ca21d17-d99c-4f28-b272-b51e5b92635e","ELEMENT":"6ca21d17-d99c-4f28-b272-b51e5b92635e"}}]
01:42:33 INFO - 1500885753355 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6ca21d17-d99c-4f28-b272-b51e5b92635e"}]
01:42:33 INFO - 1500885753602 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:42:33 INFO - 1500885753603 Marionette TRACE 376 <- [1,4,null,{}]
01:42:33 INFO - 1500885753604 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}]
01:42:33 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500885753607 Marionette TRACE 376 <- [1,5,null,{"value":null}]
01:42:33 INFO - 1500885753608 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
01:42:33 INFO - 1500885753609 Marionette TRACE 376 <- [1,6,null,{}]
01:42:33 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 347ms
01:42:33 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:42:33 INFO - 1500885753611 Marionette DEBUG Closed connection 376
01:42:33 INFO - 1500885753612 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49790
01:42:33 INFO - 1500885753613 Marionette DEBUG Closed connection 377
01:42:33 INFO - 1500885753614 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49791
01:42:33 INFO - 1500885753615 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753615 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753618 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753621 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ada49e4c-4c68-4c4c-a5ba-ae8182b81f56","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753623 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
01:42:33 INFO - 1500885753623 Marionette TRACE 378 <- [1,2,null,{}]
01:42:33 INFO - 1500885753625 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:33 INFO - 1500885753625 Marionette TRACE 378 <- [1,3,null,{}]
01:42:33 INFO - 1500885753626 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:33 INFO - 1500885753627 Marionette TRACE 378 <- [1,4,null,{}]
01:42:33 INFO - 1500885753629 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}]
01:42:33 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500885753631 Marionette TRACE 378 <- [1,5,null,{"value":null}]
01:42:33 INFO - 1500885753632 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
01:42:33 INFO - 1500885753633 Marionette TRACE 378 <- [1,6,null,{}]
01:42:33 INFO - 1500885753635 Marionette DEBUG Closed connection 378
01:42:33 INFO - 1500885753636 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49792
01:42:33 INFO - 1500885753637 Marionette DEBUG Closed connection 379
01:42:33 INFO - 1500885753637 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49793
01:42:33 INFO - 1500885753638 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753639 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753642 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753645 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0b980978-e48c-47bb-aa35-f074f4910a75","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753647 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
01:42:33 INFO - 1500885753648 Marionette TRACE 380 <- [1,2,null,{}]
01:42:33 INFO - 1500885753651 Marionette DEBUG Closed connection 380
01:42:33 INFO - 1500885753651 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49794
01:42:33 INFO - 1500885753652 Marionette DEBUG Closed connection 381
01:42:33 INFO - 1500885753653 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49795
01:42:33 INFO - 1500885753654 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:33 INFO - 1500885753654 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753657 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753660 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d643e6eb-e706-44bf-9beb-c2a69b1297ac","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753662 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"}]
01:42:33 INFO - 1500885753664 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"
01:42:33 INFO - 1500885753666 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"
01:42:33 INFO - 1500885753666 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"
01:42:33 INFO - 1500885753677 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"
01:42:33 INFO - 1500885753687 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"
01:42:33 INFO - 1500885753691 Marionette TRACE 382 <- [1,2,null,{}]
01:42:33 INFO - 1500885753692 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:42:33 INFO - 1500885753694 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0bdb8b70-a55d-443c-a17a-276ad15f0f57","ELEMENT":"0bdb8b70-a55d-443c-a17a-276ad15f0f57"}}]
01:42:33 INFO - 1500885753702 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0bdb8b70-a55d-443c-a17a-276ad15f0f57"}]
01:42:33 INFO - 1500885753946 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:42:33 INFO - 1500885753946 Marionette TRACE 382 <- [1,4,null,{}]
01:42:33 INFO - 1500885753948 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}]
01:42:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500885753951 Marionette TRACE 382 <- [1,5,null,{"value":null}]
01:42:33 INFO - 1500885753952 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
01:42:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 344ms
01:42:33 INFO - TEST-START | test_click.py TestClick.test_input_file
01:42:33 INFO - 1500885753953 Marionette TRACE 382 <- [1,6,null,{}]
01:42:33 INFO - 1500885753956 Marionette DEBUG Closed connection 382
01:42:33 INFO - 1500885753956 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49796
01:42:33 INFO - 1500885753957 Marionette DEBUG Closed connection 383
01:42:33 INFO - 1500885753958 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49797
01:42:33 INFO - 1500885753959 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753960 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753962 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753965 Marionette TRACE 384 <- [1,1,null,{"sessionId":"505ad299-cbad-46c6-8a83-6cb577893c64","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753967 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
01:42:33 INFO - 1500885753968 Marionette TRACE 384 <- [1,2,null,{}]
01:42:33 INFO - 1500885753969 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:33 INFO - 1500885753969 Marionette TRACE 384 <- [1,3,null,{}]
01:42:33 INFO - 1500885753970 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:33 INFO - 1500885753970 Marionette TRACE 384 <- [1,4,null,{}]
01:42:33 INFO - 1500885753972 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}]
01:42:33 INFO - TEST-START: test_click.py TestClick.test_input_file1500885753974 Marionette TRACE 384 <- [1,5,null,{"value":null}]
01:42:33 INFO - 1500885753975 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
01:42:33 INFO - 1500885753977 Marionette TRACE 384 <- [1,6,null,{}]
01:42:33 INFO - 1500885753979 Marionette DEBUG Closed connection 384
01:42:33 INFO - 1500885753979 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49798
01:42:33 INFO - 1500885753980 Marionette DEBUG Closed connection 385
01:42:33 INFO - 1500885753981 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49799
01:42:33 INFO - 1500885753982 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:33 INFO - 1500885753982 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:33 INFO - 1500885753985 Marionette DEBUG Register listener.js for window 15
01:42:33 INFO - 1500885753988 Marionette TRACE 386 <- [1,1,null,{"sessionId":"8dd765d8-df91-4949-8405-522f23aa7e02","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:33 INFO - 1500885753991 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
01:42:33 INFO - 1500885753992 Marionette TRACE 386 <- [1,2,null,{}]
01:42:33 INFO - 1500885753994 Marionette DEBUG Closed connection 386
01:42:33 INFO - 1500885753995 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49800
01:42:33 INFO - 1500885753996 Marionette DEBUG Closed connection 387
01:42:33 INFO - 1500885753996 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49801
01:42:33 INFO - 1500885753997 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:33 INFO - 1500885753998 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754001 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754011 Marionette TRACE 388 <- [1,1,null,{"sessionId":"8905423c-5f3c-4aa2-84e8-3ee3041fe2ef","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754013 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:42:34 INFO - 1500885754015 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"
01:42:34 INFO - 1500885754017 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"
01:42:34 INFO - 1500885754018 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"
01:42:34 INFO - 1500885754037 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:42:34 INFO - 1500885754039 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:42:34 INFO - 1500885754043 Marionette TRACE 388 <- [1,2,null,{}]
01:42:34 INFO - 1500885754044 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:42:34 INFO - 1500885754052 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"552210dc-8a5c-4755-84bd-8c76fb23614a","ELEMENT":"552210dc-8a5c-4755-84bd-8c76fb23614a"}}]
01:42:34 INFO - 1500885754053 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"552210dc-8a5c-4755-84bd-8c76fb23614a"}]
01:42:34 INFO - 1500885754081 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:42:34 INFO - 1500885754093 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}]
01:42:34 INFO - TEST-END: test_click.py TestClick.test_input_file1500885754097 Marionette TRACE 388 <- [1,5,null,{"value":null}]
01:42:34 INFO - 1500885754099 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
01:42:34 INFO - 1500885754101 Marionette TRACE 388 <- [1,6,null,{}]
01:42:34 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 148ms
01:42:34 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:42:34 INFO - 1500885754105 Marionette DEBUG Closed connection 388
01:42:34 INFO - 1500885754106 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49802
01:42:34 INFO - 1500885754107 Marionette DEBUG Closed connection 389
01:42:34 INFO - 1500885754108 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49803
01:42:34 INFO - 1500885754110 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:34 INFO - 1500885754112 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754116 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754122 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9668decc-6392-4eba-a6cd-250bde897793","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754127 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
01:42:34 INFO - 1500885754128 Marionette TRACE 390 <- [1,2,null,{}]
01:42:34 INFO - 1500885754134 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:34 INFO - 1500885754135 Marionette TRACE 390 <- [1,3,null,{}]
01:42:34 INFO - 1500885754137 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:34 INFO - 1500885754138 Marionette TRACE 390 <- [1,4,null,{}]
01:42:34 INFO - 1500885754140 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}]
01:42:34 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500885754145 Marionette TRACE 390 <- [1,5,null,{"value":null}]
01:42:34 INFO - 1500885754146 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
01:42:34 INFO - 1500885754148 Marionette TRACE 390 <- [1,6,null,{}]
01:42:34 INFO - 1500885754151 Marionette DEBUG Closed connection 390
01:42:34 INFO - 1500885754152 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49804
01:42:34 INFO - 1500885754154 Marionette DEBUG Closed connection 391
01:42:34 INFO - 1500885754154 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49805
01:42:34 INFO - 1500885754156 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:34 INFO - 1500885754157 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754160 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754164 Marionette TRACE 392 <- [1,1,null,{"sessionId":"054275b5-b8eb-4825-b6ea-d8bb61df6fc0","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754167 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
01:42:34 INFO - 1500885754169 Marionette TRACE 392 <- [1,2,null,{}]
01:42:34 INFO - 1500885754172 Marionette DEBUG Closed connection 392
01:42:34 INFO - 1500885754172 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49806
01:42:34 INFO - 1500885754173 Marionette DEBUG Closed connection 393
01:42:34 INFO - 1500885754174 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49807
01:42:34 INFO - 1500885754177 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:34 INFO - 1500885754179 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754181 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754186 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7eb1c26a-c6f7-4b37-bfae-3da397f458c7","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754189 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"}]
01:42:34 INFO - 1500885754191 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:42:34 INFO - 1500885754194 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:42:34 INFO - 1500885754195 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:42:34 INFO - 1500885754220 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"
01:42:34 INFO - 1500885754222 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"
01:42:34 INFO - 1500885754238 Marionette TRACE 394 <- [1,2,null,{}]
01:42:34 INFO - 1500885754245 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:42:34 INFO - 1500885754249 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7d775725-73a7-4f5f-8866-6da273719148","ELEMENT":"7d775725-73a7-4f5f-8866-6da273719148"}}]
01:42:34 INFO - 1500885754251 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:42:34 INFO - 1500885754254 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"050307b4-74eb-42ac-ad2a-73c151f7d7a2","ELEMENT":"050307b4-74eb-42ac-ad2a-73c151f7d7a2"}}]
01:42:34 INFO - 1500885754255 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7d775725-73a7-4f5f-8866-6da273719148"}]
01:42:34 INFO - 1500885754503 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:42:34 INFO - 1500885754504 Marionette TRACE 394 <- [1,5,null,{}]
01:42:34 INFO - 1500885754505 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"050307b4-74eb-42ac-ad2a-73c151f7d7a2"}]
01:42:34 INFO - 1500885754525 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:42:34 INFO - 1500885754527 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:42:34 INFO - 1500885754531 Marionette TRACE 394 <- [1,7,null,{"value":false}]
01:42:34 INFO - 1500885754533 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}]
01:42:34 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500885754537 Marionette TRACE 394 <- [1,8,null,{"value":null}]
01:42:34 INFO - 1500885754538 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
01:42:34 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 438ms
01:42:34 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:42:34 INFO - 1500885754541 Marionette TRACE 394 <- [1,9,null,{}]
01:42:34 INFO - 1500885754544 Marionette DEBUG Closed connection 394
01:42:34 INFO - 1500885754545 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49808
01:42:34 INFO - 1500885754560 Marionette DEBUG Closed connection 395
01:42:34 INFO - 1500885754561 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49809
01:42:34 INFO - 1500885754562 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:34 INFO - 1500885754565 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754568 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754574 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8188cd0a-ad75-4ebd-9ca2-87beec14158c","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754578 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
01:42:34 INFO - 1500885754579 Marionette TRACE 396 <- [1,2,null,{}]
01:42:34 INFO - 1500885754580 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:42:34 INFO - 1500885754580 Marionette TRACE 396 <- [1,3,null,{}]
01:42:34 INFO - 1500885754582 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
01:42:34 INFO - 1500885754582 Marionette TRACE 396 <- [1,4,null,{}]
01:42:34 INFO - 1500885754585 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}]
01:42:34 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500885754588 Marionette TRACE 396 <- [1,5,null,{"value":null}]
01:42:34 INFO - 1500885754589 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
01:42:34 INFO - 1500885754591 Marionette TRACE 396 <- [1,6,null,{}]
01:42:34 INFO - 1500885754594 Marionette DEBUG Closed connection 396
01:42:34 INFO - 1500885754595 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49810
01:42:34 INFO - 1500885754601 Marionette DEBUG Closed connection 397
01:42:34 INFO - 1500885754602 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49811
01:42:34 INFO - 1500885754605 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:42:34 INFO - 1500885754607 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754610 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754616 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cfa3700c-366f-40c6-b613-5263ddda4fb2","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754626 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
01:42:34 INFO - 1500885754628 Marionette TRACE 398 <- [1,2,null,{}]
01:42:34 INFO - 1500885754632 Marionette DEBUG Closed connection 398
01:42:34 INFO - 1500885754632 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49812
01:42:34 INFO - 1500885754634 Marionette DEBUG Closed connection 399
01:42:34 INFO - 1500885754634 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49813
01:42:34 INFO - 1500885754636 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:42:34 INFO - 1500885754637 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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}
01:42:34 INFO - 1500885754639 Marionette DEBUG Register listener.js for window 15
01:42:34 INFO - 1500885754644 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b1592267-a205-4383-b4c1-940f068e73c2","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":2372,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpzarsio.mozrunner","moz:accessibilityChecks":false}}]
01:42:34 INFO - 1500885754647 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"}]
01:42:34 INFO - 1500885754649 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"
01:42:34 INFO - 1500885754653 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"
01:42:34 INFO - 1500885754653 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"
01:42:34 INFO - 1500885754676 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"
01:42:34 INFO - 1500885754679 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"
01:42:34 INFO - 1500885754691 Marionette TRACE 400 <- [1,2,null,{}]
01:42:34 INFO - 1500885754692 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:42:34 INFO - 1500885754696 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe5cdc18-14ce-4296-acc2-7d609e6d64f5","ELEMENT":"fe5cdc18-14ce-4296-acc2-7d609e6d64f5"}}]
01:42:34 INFO - 1500885754703 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fe5cdc18-14ce-4296-acc2-7d609e6d64f5"}]
01:42:34 INFO - 1500885754729 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
01:42:34 INFO - 1500885754731 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"fe5cdc18-14ce-4296-acc2-7d609e6d64f5"}]
01:42:34 INFO - 1500885754753 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element