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]
19:45:00 INFO - 1500691500320 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}]
19:45:00 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500691500323 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:45:00 INFO - 1500691500325 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:45:00 INFO - 1500691500326 Marionette TRACE 352 <- [1,6,null,{}]
19:45:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 153ms
19:45:00 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:45:00 INFO - 1500691500328 Marionette DEBUG Closed connection 352
19:45:00 INFO - 1500691500328 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49773
19:45:00 INFO - 1500691500329 Marionette DEBUG Closed connection 353
19:45:00 INFO - 1500691500330 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49774
19:45:00 INFO - 1500691500331 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500331 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500334 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500339 Marionette TRACE 354 <- [1,1,null,{"sessionId":"788dae1e-3a5b-4b11-b0d1-6c9936b20ba1","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500340 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:45:00 INFO - 1500691500341 Marionette TRACE 354 <- [1,2,null,{}]
19:45:00 INFO - 1500691500342 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:00 INFO - 1500691500342 Marionette TRACE 354 <- [1,3,null,{}]
19:45:00 INFO - 1500691500343 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:00 INFO - 1500691500344 Marionette TRACE 354 <- [1,4,null,{}]
19:45:00 INFO - 1500691500345 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}]
19:45:00 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500691500348 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:45:00 INFO - 1500691500349 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:45:00 INFO - 1500691500351 Marionette TRACE 354 <- [1,6,null,{}]
19:45:00 INFO - 1500691500352 Marionette DEBUG Closed connection 354
19:45:00 INFO - 1500691500353 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49775
19:45:00 INFO - 1500691500354 Marionette DEBUG Closed connection 355
19:45:00 INFO - 1500691500354 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49776
19:45:00 INFO - 1500691500355 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500356 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500359 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500362 Marionette TRACE 356 <- [1,1,null,{"sessionId":"af911820-8d35-4d44-8a04-4a5ff5bd43c1","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500363 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:45:00 INFO - 1500691500365 Marionette TRACE 356 <- [1,2,null,{}]
19:45:00 INFO - 1500691500366 Marionette DEBUG Closed connection 356
19:45:00 INFO - 1500691500367 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49777
19:45:00 INFO - 1500691500368 Marionette DEBUG Closed connection 357
19:45:00 INFO - 1500691500368 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49778
19:45:00 INFO - 1500691500369 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:00 INFO - 1500691500370 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500373 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500376 Marionette TRACE 358 <- [1,1,null,{"sessionId":"675007ef-2bef-4f99-8b01-ea95b986f316","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500378 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
19:45:00 INFO - 1500691500380 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
19:45:00 INFO - 1500691500387 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
19:45:00 INFO - 1500691500387 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
19:45:00 INFO - 1500691500399 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
19:45:00 INFO - 1500691500401 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
19:45:00 INFO - 1500691500403 Marionette TRACE 358 <- [1,2,null,{}]
19:45:00 INFO - 1500691500408 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:45:00 INFO - 1500691500413 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"30b8ee5c-22d4-4625-9c04-d7151890440e","ELEMENT":"30b8ee5c-22d4-4625-9c04-d7151890440e"}}]
19:45:00 INFO - 1500691500415 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"30b8ee5c-22d4-4625-9c04-d7151890440e"}]
19:45:00 INFO - 1500691500458 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
19:45:00 INFO - 1500691500467 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
19:45:00 INFO - 1500691500467 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
19:45:00 INFO - 1500691500479 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
19:45:00 INFO - 1500691500483 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
19:45:00 INFO - 1500691500485 Marionette TRACE 358 <- [1,4,null,{}]
19:45:00 INFO - 1500691500490 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:45:00 INFO - 1500691500496 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4e64371-2327-426a-aa50-21aca6bc1f90","ELEMENT":"f4e64371-2327-426a-aa50-21aca6bc1f90"}}]
19:45:00 INFO - 1500691500497 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:45:00 INFO - 1500691500498 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:45:00 INFO - 1500691500501 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}]
19:45:00 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500691500504 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:45:00 INFO - 1500691500506 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:45:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
19:45:00 INFO - TEST-START | test_click.py TestClick.test_container_element
19:45:00 INFO - 1500691500507 Marionette TRACE 358 <- [1,8,null,{}]
19:45:00 INFO - 1500691500509 Marionette DEBUG Closed connection 358
19:45:00 INFO - 1500691500510 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49781
19:45:00 INFO - 1500691500511 Marionette DEBUG Closed connection 359
19:45:00 INFO - 1500691500512 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49782
19:45:00 INFO - 1500691500513 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500513 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500517 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500520 Marionette TRACE 360 <- [1,1,null,{"sessionId":"35b0c167-6fc1-48ae-b10b-21dcb2f446e2","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500521 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:45:00 INFO - 1500691500522 Marionette TRACE 360 <- [1,2,null,{}]
19:45:00 INFO - 1500691500523 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:00 INFO - 1500691500523 Marionette TRACE 360 <- [1,3,null,{}]
19:45:00 INFO - 1500691500524 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:00 INFO - 1500691500524 Marionette TRACE 360 <- [1,4,null,{}]
19:45:00 INFO - 1500691500526 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}]
19:45:00 INFO - TEST-START: test_click.py TestClick.test_container_element1500691500529 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:45:00 INFO - 1500691500530 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:45:00 INFO - 1500691500531 Marionette TRACE 360 <- [1,6,null,{}]
19:45:00 INFO - 1500691500533 Marionette DEBUG Closed connection 360
19:45:00 INFO - 1500691500534 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49783
19:45:00 INFO - 1500691500534 Marionette DEBUG Closed connection 361
19:45:00 INFO - 1500691500535 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49784
19:45:00 INFO - 1500691500536 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500537 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500539 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500543 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bdc2ee47-b0a0-4127-968c-95acf99ac574","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500545 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:45:00 INFO - 1500691500546 Marionette TRACE 362 <- [1,2,null,{}]
19:45:00 INFO - 1500691500548 Marionette DEBUG Closed connection 362
19:45:00 INFO - 1500691500548 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49785
19:45:00 INFO - 1500691500549 Marionette DEBUG Closed connection 363
19:45:00 INFO - 1500691500550 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49786
19:45:00 INFO - 1500691500551 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:00 INFO - 1500691500552 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500554 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500558 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5a0294b3-a297-4b74-a1a3-161953afce90","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500559 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"}]
19:45:00 INFO - 1500691500561 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
19:45:00 INFO - 1500691500563 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
19:45:00 INFO - 1500691500563 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
19:45:00 INFO - 1500691500570 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"
19:45:00 INFO - 1500691500573 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"
19:45:00 INFO - 1500691500580 Marionette TRACE 364 <- [1,2,null,{}]
19:45:00 INFO - 1500691500589 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:45:00 INFO - 1500691500593 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ddc010f-9be5-43d1-b652-b515c6fd5b94","ELEMENT":"5ddc010f-9be5-43d1-b652-b515c6fd5b94"}}]
19:45:00 INFO - 1500691500594 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5ddc010f-9be5-43d1-b652-b515c6fd5b94"}]
19:45:00 INFO - 1500691500847 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:45:00 INFO - 1500691500848 Marionette TRACE 364 <- [1,4,null,{}]
19:45:00 INFO - 1500691500849 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5ddc010f-9be5-43d1-b652-b515c6fd5b94","name":"selected"}]
19:45:00 INFO - 1500691500854 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:45:00 INFO - 1500691500855 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}]
19:45:00 INFO - TEST-END: test_click.py TestClick.test_container_element1500691500859 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:45:00 INFO - 1500691500860 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:45:00 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 353ms
19:45:00 INFO - 1500691500861 Marionette TRACE 364 <- [1,7,null,{}]
19:45:00 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:45:00 INFO - 1500691500863 Marionette DEBUG Closed connection 364
19:45:00 INFO - 1500691500864 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49787
19:45:00 INFO - 1500691500865 Marionette DEBUG Closed connection 365
19:45:00 INFO - 1500691500866 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49788
19:45:00 INFO - 1500691500866 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500867 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500870 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500873 Marionette TRACE 366 <- [1,1,null,{"sessionId":"f1745b6f-0514-4631-aab1-fe4f70316b33","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500875 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:45:00 INFO - 1500691500875 Marionette TRACE 366 <- [1,2,null,{}]
19:45:00 INFO - 1500691500877 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:00 INFO - 1500691500877 Marionette TRACE 366 <- [1,3,null,{}]
19:45:00 INFO - 1500691500878 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:00 INFO - 1500691500879 Marionette TRACE 366 <- [1,4,null,{}]
19:45:00 INFO - 1500691500881 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}]
19:45:00 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500691500884 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:45:00 INFO - 1500691500885 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:45:00 INFO - 1500691500886 Marionette TRACE 366 <- [1,6,null,{}]
19:45:00 INFO - 1500691500888 Marionette DEBUG Closed connection 366
19:45:00 INFO - 1500691500889 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49789
19:45:00 INFO - 1500691500889 Marionette DEBUG Closed connection 367
19:45:00 INFO - 1500691500890 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49790
19:45:00 INFO - 1500691500891 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:00 INFO - 1500691500892 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500895 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500898 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6fe6bdc2-a97a-430a-8921-ca1abbc0a0f7","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500900 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:45:00 INFO - 1500691500901 Marionette TRACE 368 <- [1,2,null,{}]
19:45:00 INFO - 1500691500903 Marionette DEBUG Closed connection 368
19:45:00 INFO - 1500691500904 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49791
19:45:00 INFO - 1500691500905 Marionette DEBUG Closed connection 369
19:45:00 INFO - 1500691500905 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49792
19:45:00 INFO - 1500691500906 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:00 INFO - 1500691500907 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:00 INFO - 1500691500910 Marionette DEBUG Register listener.js for window 6442450945
19:45:00 INFO - 1500691500913 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3b0f4c68-3181-4541-b752-de9807e13e0c","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:00 INFO - 1500691500915 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"}]
19:45:00 INFO - 1500691500917 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"
19:45:00 INFO - 1500691500918 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"
19:45:00 INFO - 1500691500919 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"
19:45:00 INFO - 1500691500929 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"
19:45:00 INFO - 1500691500931 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"
19:45:00 INFO - 1500691500937 Marionette TRACE 370 <- [1,2,null,{}]
19:45:00 INFO - 1500691500941 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:45:00 INFO - 1500691500948 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"571c126b-3f69-4fdd-96c7-10a90d5370e3","ELEMENT":"571c126b-3f69-4fdd-96c7-10a90d5370e3"}}]
19:45:00 INFO - 1500691500949 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"571c126b-3f69-4fdd-96c7-10a90d5370e3"}]
19:45:01 INFO - 1500691501192 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:45:01 INFO - 1500691501193 Marionette TRACE 370 <- [1,4,null,{}]
19:45:01 INFO - 1500691501195 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"571c126b-3f69-4fdd-96c7-10a90d5370e3","name":"selected"}]
19:45:01 INFO - 1500691501199 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:45:01 INFO - 1500691501201 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}]
19:45:01 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500691501204 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:45:01 INFO - 1500691501205 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:45:01 INFO - 1500691501206 Marionette TRACE 370 <- [1,7,null,{}]
19:45:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 345ms
19:45:01 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:45:01 INFO - 1500691501208 Marionette DEBUG Closed connection 370
19:45:01 INFO - 1500691501209 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49793
19:45:01 INFO - 1500691501210 Marionette DEBUG Closed connection 371
19:45:01 INFO - 1500691501211 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49794
19:45:01 INFO - 1500691501212 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501212 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501215 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501219 Marionette TRACE 372 <- [1,1,null,{"sessionId":"327cbac5-a906-4f23-b1e0-310e23367680","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501220 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:45:01 INFO - 1500691501220 Marionette TRACE 372 <- [1,2,null,{}]
19:45:01 INFO - 1500691501221 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:01 INFO - 1500691501222 Marionette TRACE 372 <- [1,3,null,{}]
19:45:01 INFO - 1500691501223 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:01 INFO - 1500691501223 Marionette TRACE 372 <- [1,4,null,{}]
19:45:01 INFO - 1500691501225 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}]
19:45:01 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500691501228 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:45:01 INFO - 1500691501229 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:45:01 INFO - 1500691501231 Marionette TRACE 372 <- [1,6,null,{}]
19:45:01 INFO - 1500691501233 Marionette DEBUG Closed connection 372
19:45:01 INFO - 1500691501233 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49795
19:45:01 INFO - 1500691501234 Marionette DEBUG Closed connection 373
19:45:01 INFO - 1500691501235 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49796
19:45:01 INFO - 1500691501236 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501236 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501239 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501242 Marionette TRACE 374 <- [1,1,null,{"sessionId":"de70acb7-f7ae-4682-956a-5e6b0dc5cd1c","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501244 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:45:01 INFO - 1500691501245 Marionette TRACE 374 <- [1,2,null,{}]
19:45:01 INFO - 1500691501247 Marionette DEBUG Closed connection 374
19:45:01 INFO - 1500691501247 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49797
19:45:01 INFO - 1500691501248 Marionette DEBUG Closed connection 375
19:45:01 INFO - 1500691501249 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49798
19:45:01 INFO - 1500691501250 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:01 INFO - 1500691501251 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501254 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501257 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a87abdff-a70e-492f-b2c8-add78b1473d9","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501259 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"}]
19:45:01 INFO - 1500691501260 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"
19:45:01 INFO - 1500691501262 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"
19:45:01 INFO - 1500691501263 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"
19:45:01 INFO - 1500691501269 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"
19:45:01 INFO - 1500691501272 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"
19:45:01 INFO - 1500691501276 Marionette TRACE 376 <- [1,2,null,{}]
19:45:01 INFO - 1500691501285 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:45:01 INFO - 1500691501287 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f13d2b2a-e459-4e53-bba0-456a83955534","ELEMENT":"f13d2b2a-e459-4e53-bba0-456a83955534"}}]
19:45:01 INFO - 1500691501288 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f13d2b2a-e459-4e53-bba0-456a83955534"}]
19:45:01 INFO - 1500691501534 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:45:01 INFO - 1500691501535 Marionette TRACE 376 <- [1,4,null,{}]
19:45:01 INFO - 1500691501536 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}]
19:45:01 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500691501539 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:45:01 INFO - 1500691501540 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:45:01 INFO - 1500691501542 Marionette TRACE 376 <- [1,6,null,{}]
19:45:01 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 335ms
19:45:01 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:45:01 INFO - 1500691501543 Marionette DEBUG Closed connection 376
19:45:01 INFO - 1500691501544 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49799
19:45:01 INFO - 1500691501545 Marionette DEBUG Closed connection 377
19:45:01 INFO - 1500691501546 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49800
19:45:01 INFO - 1500691501547 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501548 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501551 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501562 Marionette TRACE 378 <- [1,1,null,{"sessionId":"621a17a9-82f5-448d-8054-fdfffc0b67f5","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501563 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:45:01 INFO - 1500691501564 Marionette TRACE 378 <- [1,2,null,{}]
19:45:01 INFO - 1500691501565 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:01 INFO - 1500691501565 Marionette TRACE 378 <- [1,3,null,{}]
19:45:01 INFO - 1500691501566 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:01 INFO - 1500691501567 Marionette TRACE 378 <- [1,4,null,{}]
19:45:01 INFO - 1500691501569 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}]
19:45:01 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500691501572 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:45:01 INFO - 1500691501573 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:45:01 INFO - 1500691501575 Marionette TRACE 378 <- [1,6,null,{}]
19:45:01 INFO - 1500691501577 Marionette DEBUG Closed connection 378
19:45:01 INFO - 1500691501577 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49801
19:45:01 INFO - 1500691501578 Marionette DEBUG Closed connection 379
19:45:01 INFO - 1500691501579 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49802
19:45:01 INFO - 1500691501580 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501581 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501584 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501588 Marionette TRACE 380 <- [1,1,null,{"sessionId":"29825647-6df4-4f3d-a224-fa7b772493f6","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501589 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:45:01 INFO - 1500691501591 Marionette TRACE 380 <- [1,2,null,{}]
19:45:01 INFO - 1500691501593 Marionette DEBUG Closed connection 380
19:45:01 INFO - 1500691501594 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49803
19:45:01 INFO - 1500691501594 Marionette DEBUG Closed connection 381
19:45:01 INFO - 1500691501595 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49804
19:45:01 INFO - 1500691501596 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:01 INFO - 1500691501597 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501600 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501604 Marionette TRACE 382 <- [1,1,null,{"sessionId":"fa1a0382-a810-4b6a-b270-d0c7268e8ea5","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501605 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"}]
19:45:01 INFO - 1500691501607 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"
19:45:01 INFO - 1500691501609 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"
19:45:01 INFO - 1500691501610 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"
19:45:01 INFO - 1500691501617 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"
19:45:01 INFO - 1500691501620 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"
19:45:01 INFO - 1500691501625 Marionette TRACE 382 <- [1,2,null,{}]
19:45:01 INFO - 1500691501633 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:45:01 INFO - 1500691501636 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ccee3f79-d69c-4450-af73-2277b405ce9e","ELEMENT":"ccee3f79-d69c-4450-af73-2277b405ce9e"}}]
19:45:01 INFO - 1500691501637 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ccee3f79-d69c-4450-af73-2277b405ce9e"}]
19:45:01 INFO - 1500691501881 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:45:01 INFO - 1500691501882 Marionette TRACE 382 <- [1,4,null,{}]
19:45:01 INFO - 1500691501883 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}]
19:45:01 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500691501886 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:45:01 INFO - 1500691501887 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:45:01 INFO - 1500691501889 Marionette TRACE 382 <- [1,6,null,{}]
19:45:01 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
19:45:01 INFO - TEST-START | test_click.py TestClick.test_input_file
19:45:01 INFO - 1500691501890 Marionette DEBUG Closed connection 382
19:45:01 INFO - 1500691501892 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49805
19:45:01 INFO - 1500691501893 Marionette DEBUG Closed connection 383
19:45:01 INFO - 1500691501893 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49806
19:45:01 INFO - 1500691501894 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501895 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501898 Marionette DEBUG Register listener.js for window 6442450945
19:45:01 INFO - 1500691501901 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e76f3f7c-5469-44c8-a631-67c663941e4c","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:01 INFO - 1500691501903 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:45:01 INFO - 1500691501903 Marionette TRACE 384 <- [1,2,null,{}]
19:45:01 INFO - 1500691501904 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:01 INFO - 1500691501904 Marionette TRACE 384 <- [1,3,null,{}]
19:45:01 INFO - 1500691501905 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:01 INFO - 1500691501906 Marionette TRACE 384 <- [1,4,null,{}]
19:45:01 INFO - 1500691501908 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}]
19:45:01 INFO - TEST-START: test_click.py TestClick.test_input_file1500691501910 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:45:01 INFO - 1500691501912 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:45:01 INFO - 1500691501913 Marionette TRACE 384 <- [1,6,null,{}]
19:45:01 INFO - 1500691501975 Marionette DEBUG Closed connection 384
19:45:01 INFO - 1500691501976 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49807
19:45:01 INFO - 1500691501979 Marionette DEBUG Closed connection 385
19:45:01 INFO - 1500691501980 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49808
19:45:01 INFO - 1500691501983 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:01 INFO - 1500691501987 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:01 INFO - 1500691501990 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502008 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7e5caac0-e12b-46ca-b588-118455adac23","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502011 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:45:02 INFO - 1500691502013 Marionette TRACE 386 <- [1,2,null,{}]
19:45:02 INFO - 1500691502016 Marionette DEBUG Closed connection 386
19:45:02 INFO - 1500691502018 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49809
19:45:02 INFO - 1500691502020 Marionette DEBUG Closed connection 387
19:45:02 INFO - 1500691502021 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49810
19:45:02 INFO - 1500691502024 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:02 INFO - 1500691502025 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502066 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502074 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ea55edf0-5f8e-4045-9cdb-9e5a14d07fe7","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502076 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:45:02 INFO - 1500691502097 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"
19:45:02 INFO - 1500691502099 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"
19:45:02 INFO - 1500691502099 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"
19:45:02 INFO - 1500691502105 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:45:02 INFO - 1500691502109 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:45:02 INFO - 1500691502122 Marionette TRACE 388 <- [1,2,null,{}]
19:45:02 INFO - 1500691502145 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:45:02 INFO - 1500691502150 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f45ccba-f656-4edb-8596-64515558f52c","ELEMENT":"4f45ccba-f656-4edb-8596-64515558f52c"}}]
19:45:02 INFO - 1500691502152 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4f45ccba-f656-4edb-8596-64515558f52c"}]
19:45:02 INFO - 1500691502189 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]
19:45:02 INFO - 1500691502195 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}]
19:45:02 INFO - TEST-END: test_click.py TestClick.test_input_file1500691502199 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:45:02 INFO - 1500691502201 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:45:02 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 312ms
19:45:02 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:45:02 INFO - 1500691502203 Marionette TRACE 388 <- [1,6,null,{}]
19:45:02 INFO - 1500691502205 Marionette DEBUG Closed connection 388
19:45:02 INFO - 1500691502206 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49811
19:45:02 INFO - 1500691502207 Marionette DEBUG Closed connection 389
19:45:02 INFO - 1500691502208 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49812
19:45:02 INFO - 1500691502209 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:02 INFO - 1500691502210 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502213 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502217 Marionette TRACE 390 <- [1,1,null,{"sessionId":"865aa9b9-34b6-4a75-a7d9-709be15b86a5","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502219 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:45:02 INFO - 1500691502220 Marionette TRACE 390 <- [1,2,null,{}]
19:45:02 INFO - 1500691502223 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:02 INFO - 1500691502224 Marionette TRACE 390 <- [1,3,null,{}]
19:45:02 INFO - 1500691502225 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:02 INFO - 1500691502226 Marionette TRACE 390 <- [1,4,null,{}]
19:45:02 INFO - 1500691502229 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}]
19:45:02 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500691502233 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:45:02 INFO - 1500691502234 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:45:02 INFO - 1500691502236 Marionette TRACE 390 <- [1,6,null,{}]
19:45:02 INFO - 1500691502238 Marionette DEBUG Closed connection 390
19:45:02 INFO - 1500691502238 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49813
19:45:02 INFO - 1500691502240 Marionette DEBUG Closed connection 391
19:45:02 INFO - 1500691502240 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49814
19:45:02 INFO - 1500691502241 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:02 INFO - 1500691502242 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502245 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502253 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2a2e89c3-6c88-4db7-b7c4-2c7d5de10451","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502255 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:45:02 INFO - 1500691502256 Marionette TRACE 392 <- [1,2,null,{}]
19:45:02 INFO - 1500691502258 Marionette DEBUG Closed connection 392
19:45:02 INFO - 1500691502259 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49815
19:45:02 INFO - 1500691502260 Marionette DEBUG Closed connection 393
19:45:02 INFO - 1500691502261 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49816
19:45:02 INFO - 1500691502262 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:02 INFO - 1500691502264 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502268 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502280 Marionette TRACE 394 <- [1,1,null,{"sessionId":"b4d093c1-f4a1-41e9-914f-4b766d143941","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502282 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"}]
19:45:02 INFO - 1500691502285 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:45:02 INFO - 1500691502287 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:45:02 INFO - 1500691502287 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:45:02 INFO - 1500691502301 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"
19:45:02 INFO - 1500691502302 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"
19:45:02 INFO - 1500691502311 Marionette TRACE 394 <- [1,2,null,{}]
19:45:02 INFO - 1500691502319 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:45:02 INFO - 1500691502322 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0465b101-9f77-465d-b39d-2282fd356506","ELEMENT":"0465b101-9f77-465d-b39d-2282fd356506"}}]
19:45:02 INFO - 1500691502323 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:45:02 INFO - 1500691502326 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2550e0f8-fa92-4d94-b7b5-af40af8ad54e","ELEMENT":"2550e0f8-fa92-4d94-b7b5-af40af8ad54e"}}]
19:45:02 INFO - 1500691502327 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"0465b101-9f77-465d-b39d-2282fd356506"}]
19:45:02 INFO - 1500691502566 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:45:02 INFO - 1500691502567 Marionette TRACE 394 <- [1,5,null,{}]
19:45:02 INFO - 1500691502568 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"2550e0f8-fa92-4d94-b7b5-af40af8ad54e"}]
19:45:02 INFO - 1500691502584 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]
19:45:02 INFO - 1500691502586 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:45:02 INFO - 1500691502590 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:45:02 INFO - 1500691502592 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}]
19:45:02 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500691502594 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:45:02 INFO - 1500691502595 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:45:02 INFO - 1500691502597 Marionette TRACE 394 <- [1,9,null,{}]
19:45:02 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 394ms
19:45:02 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:45:02 INFO - 1500691502600 Marionette DEBUG Closed connection 394
19:45:02 INFO - 1500691502602 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49817
19:45:02 INFO - 1500691502603 Marionette DEBUG Closed connection 395
19:45:02 INFO - 1500691502604 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49818
19:45:02 INFO - 1500691502605 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:02 INFO - 1500691502606 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502609 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502613 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1f235fdc-13f4-4edc-ae01-9c83acd61f91","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502615 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:45:02 INFO - 1500691502615 Marionette TRACE 396 <- [1,2,null,{}]
19:45:02 INFO - 1500691502616 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:45:02 INFO - 1500691502617 Marionette TRACE 396 <- [1,3,null,{}]
19:45:02 INFO - 1500691502618 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:45:02 INFO - 1500691502619 Marionette TRACE 396 <- [1,4,null,{}]
19:45:02 INFO - 1500691502620 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}]
19:45:02 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500691502623 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:45:02 INFO - 1500691502624 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:45:02 INFO - 1500691502626 Marionette TRACE 396 <- [1,6,null,{}]
19:45:02 INFO - 1500691502630 Marionette DEBUG Closed connection 396
19:45:02 INFO - 1500691502630 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49819
19:45:02 INFO - 1500691502631 Marionette DEBUG Closed connection 397
19:45:02 INFO - 1500691502632 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49820
19:45:02 INFO - 1500691502633 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:45:02 INFO - 1500691502634 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502637 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502640 Marionette TRACE 398 <- [1,1,null,{"sessionId":"bf33ed0a-5370-4ac4-9fd5-2f9e4601cde7","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502642 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:45:02 INFO - 1500691502643 Marionette TRACE 398 <- [1,2,null,{}]
19:45:02 INFO - 1500691502645 Marionette DEBUG Closed connection 398
19:45:02 INFO - 1500691502646 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49821
19:45:02 INFO - 1500691502647 Marionette DEBUG Closed connection 399
19:45:02 INFO - 1500691502647 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49822
19:45:02 INFO - 1500691502649 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:45:02 INFO - 1500691502650 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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}
19:45:02 INFO - 1500691502652 Marionette DEBUG Register listener.js for window 6442450945
19:45:02 INFO - 1500691502656 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ea4813eb-9e32-4586-be42-379ef2c27976","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":3752,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-082.001\\AppData\\Local\\Temp\\tmpmippcu.mozrunner","moz:accessibilityChecks":false}}]
19:45:02 INFO - 1500691502658 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"}]
19:45:02 INFO - 1500691502660 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"
19:45:02 INFO - 1500691502662 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"
19:45:02 INFO - 1500691502662 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"
19:45:02 INFO - 1500691502669 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"
19:45:02 INFO - 1500691502671 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"
19:45:02 INFO - 1500691502678 Marionette TRACE 400 <- [1,2,null,{}]
19:45:02 INFO - 1500691502686 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:45:02 INFO - 1500691502689 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"28a50a75-7409-4348-a74a-3bced8679e9a","ELEMENT":"28a50a75-7409-4348-a74a-3bced8679e9a"}}]
19:45:02 INFO - 1500691502690 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"28a50a75-7409-4348-a74a-3bced8679e9a"}]
19:45:02 INFO - 1500691502716 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:45:02 INFO - 1500691502717 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"28a50a75-7409-4348-a74a-3bced8679e9a"}]
19:45:02 INFO - 1500691502733 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element