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]
22:05:19 INFO - 1501045519443 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
22:05:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501045519445 Marionette TRACE 352 <- [1,5,null,{"value":null}]
22:05:19 INFO - 1501045519446 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
22:05:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
22:05:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:05:19 INFO - 1501045519447 Marionette TRACE 352 <- [1,6,null,{}]
22:05:19 INFO - 1501045519449 Marionette DEBUG Closed connection 352
22:05:19 INFO - 1501045519450 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49750
22:05:19 INFO - 1501045519451 Marionette DEBUG Closed connection 353
22:05:19 INFO - 1501045519451 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49751
22:05:19 INFO - 1501045519452 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519453 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519455 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519459 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f2885d51-b9f3-4cf1-89a7-c3d528bd965d","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519461 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
22:05:19 INFO - 1501045519462 Marionette TRACE 354 <- [1,2,null,{}]
22:05:19 INFO - 1501045519463 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:19 INFO - 1501045519464 Marionette TRACE 354 <- [1,3,null,{}]
22:05:19 INFO - 1501045519465 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:19 INFO - 1501045519465 Marionette TRACE 354 <- [1,4,null,{}]
22:05:19 INFO - 1501045519466 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
22:05:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501045519469 Marionette TRACE 354 <- [1,5,null,{"value":null}]
22:05:19 INFO - 1501045519469 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
22:05:19 INFO - 1501045519471 Marionette TRACE 354 <- [1,6,null,{}]
22:05:19 INFO - 1501045519473 Marionette DEBUG Closed connection 354
22:05:19 INFO - 1501045519473 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49752
22:05:19 INFO - 1501045519474 Marionette DEBUG Closed connection 355
22:05:19 INFO - 1501045519474 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49753
22:05:19 INFO - 1501045519475 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519476 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519477 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519480 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6c15e05e-1e69-42c7-8abf-3cbd0a1331f1","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519481 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
22:05:19 INFO - 1501045519482 Marionette TRACE 356 <- [1,2,null,{}]
22:05:19 INFO - 1501045519484 Marionette DEBUG Closed connection 356
22:05:19 INFO - 1501045519484 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49754
22:05:19 INFO - 1501045519485 Marionette DEBUG Closed connection 357
22:05:19 INFO - 1501045519485 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49755
22:05:19 INFO - 1501045519486 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:19 INFO - 1501045519487 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519488 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519491 Marionette TRACE 358 <- [1,1,null,{"sessionId":"322f6cc1-3bc5-41b0-b870-c2bd58b6e2af","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519493 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49325/clicks.html"}]
22:05:19 INFO - 1501045519494 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/hidden.html"
22:05:19 INFO - 1501045519498 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/hidden.html"
22:05:19 INFO - 1501045519499 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/hidden.html"
22:05:19 INFO - 1501045519514 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/clicks.html"
22:05:19 INFO - 1501045519518 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/clicks.html"
22:05:19 INFO - 1501045519521 Marionette TRACE 358 <- [1,2,null,{}]
22:05:19 INFO - 1501045519528 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:05:19 INFO - 1501045519530 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22f3c945-71f9-455a-87aa-d2ea5a018f7e","ELEMENT":"22f3c945-71f9-455a-87aa-d2ea5a018f7e"}}]
22:05:19 INFO - 1501045519531 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"22f3c945-71f9-455a-87aa-d2ea5a018f7e"}]
22:05:19 INFO - 1501045519573 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/clicks.html"
22:05:19 INFO - 1501045519580 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/clicks.html"
22:05:19 INFO - 1501045519581 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/clicks.html"
22:05:19 INFO - 1501045519597 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49325/test.html"
22:05:19 INFO - 1501045519598 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49325/test.html"
22:05:19 INFO - 1501045519600 Marionette TRACE 358 <- [1,4,null,{}]
22:05:19 INFO - 1501045519601 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:05:19 INFO - 1501045519603 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"96627766-4e0a-4509-b385-c5c16c69dadb","ELEMENT":"96627766-4e0a-4509-b385-c5c16c69dadb"}}]
22:05:19 INFO - 1501045519604 Marionette TRACE 358 -> [0,6,"getTitle",{}]
22:05:19 INFO - 1501045519604 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
22:05:19 INFO - 1501045519611 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
22:05:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501045519613 Marionette TRACE 358 <- [1,7,null,{"value":null}]
22:05:19 INFO - 1501045519615 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
22:05:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 169ms
22:05:19 INFO - 1501045519616 Marionette TRACE 358 <- [1,8,null,{}]
22:05:19 INFO - TEST-START | test_click.py TestClick.test_container_element
22:05:19 INFO - 1501045519618 Marionette DEBUG Closed connection 358
22:05:19 INFO - 1501045519619 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49758
22:05:19 INFO - 1501045519619 Marionette DEBUG Closed connection 359
22:05:19 INFO - 1501045519620 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49759
22:05:19 INFO - 1501045519621 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519621 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519623 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519626 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9ae0dc4a-ed48-49ed-a8df-3e6d540ad240","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519628 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
22:05:19 INFO - 1501045519628 Marionette TRACE 360 <- [1,2,null,{}]
22:05:19 INFO - 1501045519629 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:19 INFO - 1501045519629 Marionette TRACE 360 <- [1,3,null,{}]
22:05:19 INFO - 1501045519630 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:19 INFO - 1501045519630 Marionette TRACE 360 <- [1,4,null,{}]
22:05:19 INFO - 1501045519632 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
22:05:19 INFO - TEST-START: test_click.py TestClick.test_container_element1501045519634 Marionette TRACE 360 <- [1,5,null,{"value":null}]
22:05:19 INFO - 1501045519635 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
22:05:19 INFO - 1501045519636 Marionette TRACE 360 <- [1,6,null,{}]
22:05:19 INFO - 1501045519638 Marionette DEBUG Closed connection 360
22:05:19 INFO - 1501045519638 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49760
22:05:19 INFO - 1501045519639 Marionette DEBUG Closed connection 361
22:05:19 INFO - 1501045519639 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49761
22:05:19 INFO - 1501045519640 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519641 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519642 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519645 Marionette TRACE 362 <- [1,1,null,{"sessionId":"07f73aa4-299a-49ac-b521-158a68346b49","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519650 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
22:05:19 INFO - 1501045519651 Marionette TRACE 362 <- [1,2,null,{}]
22:05:19 INFO - 1501045519653 Marionette DEBUG Closed connection 362
22:05:19 INFO - 1501045519654 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49762
22:05:19 INFO - 1501045519654 Marionette DEBUG Closed connection 363
22:05:19 INFO - 1501045519655 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49763
22:05:19 INFO - 1501045519656 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:19 INFO - 1501045519657 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519658 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519661 Marionette TRACE 364 <- [1,1,null,{"sessionId":"545c00aa-a711-4b6e-9520-74f6dde7e8e4","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519662 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:05:19 INFO - 1501045519663 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49325/test.html"
22:05:19 INFO - 1501045519665 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49325/test.html"
22:05:19 INFO - 1501045519665 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49325/test.html"
22:05:19 INFO - 1501045519685 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:19 INFO - 1501045519687 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:19 INFO - 1501045519695 Marionette TRACE 364 <- [1,2,null,{}]
22:05:19 INFO - 1501045519697 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:05:19 INFO - 1501045519699 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f244a4f-53fb-409f-9030-6976c6b07062","ELEMENT":"2f244a4f-53fb-409f-9030-6976c6b07062"}}]
22:05:19 INFO - 1501045519699 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"2f244a4f-53fb-409f-9030-6976c6b07062"}]
22:05:19 INFO - 1501045519935 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:19 INFO - 1501045519936 Marionette TRACE 364 <- [1,4,null,{}]
22:05:19 INFO - 1501045519937 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"2f244a4f-53fb-409f-9030-6976c6b07062","name":"selected"}]
22:05:19 INFO - 1501045519940 Marionette TRACE 364 <- [1,5,null,{"value":true}]
22:05:19 INFO - 1501045519942 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
22:05:19 INFO - TEST-END: test_click.py TestClick.test_container_element1501045519944 Marionette TRACE 364 <- [1,6,null,{"value":null}]
22:05:19 INFO - 1501045519945 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
22:05:19 INFO - 1501045519947 Marionette TRACE 364 <- [1,7,null,{}]
22:05:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 330ms
22:05:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:05:19 INFO - 1501045519948 Marionette DEBUG Closed connection 364
22:05:19 INFO - 1501045519949 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49764
22:05:19 INFO - 1501045519950 Marionette DEBUG Closed connection 365
22:05:19 INFO - 1501045519950 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49765
22:05:19 INFO - 1501045519951 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519952 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519953 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519956 Marionette TRACE 366 <- [1,1,null,{"sessionId":"142df20d-1fd6-46a3-9110-91fea13123b8","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519958 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
22:05:19 INFO - 1501045519958 Marionette TRACE 366 <- [1,2,null,{}]
22:05:19 INFO - 1501045519959 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:19 INFO - 1501045519959 Marionette TRACE 366 <- [1,3,null,{}]
22:05:19 INFO - 1501045519960 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:19 INFO - 1501045519961 Marionette TRACE 366 <- [1,4,null,{}]
22:05:19 INFO - 1501045519963 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
22:05:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501045519965 Marionette TRACE 366 <- [1,5,null,{"value":null}]
22:05:19 INFO - 1501045519966 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
22:05:19 INFO - 1501045519967 Marionette TRACE 366 <- [1,6,null,{}]
22:05:19 INFO - 1501045519969 Marionette DEBUG Closed connection 366
22:05:19 INFO - 1501045519970 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49766
22:05:19 INFO - 1501045519970 Marionette DEBUG Closed connection 367
22:05:19 INFO - 1501045519971 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49767
22:05:19 INFO - 1501045519972 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:19 INFO - 1501045519972 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519974 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519977 Marionette TRACE 368 <- [1,1,null,{"sessionId":"fe5cf10e-5e86-4c09-a4b5-4328a22008df","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519979 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
22:05:19 INFO - 1501045519980 Marionette TRACE 368 <- [1,2,null,{}]
22:05:19 INFO - 1501045519981 Marionette DEBUG Closed connection 368
22:05:19 INFO - 1501045519982 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49768
22:05:19 INFO - 1501045519982 Marionette DEBUG Closed connection 369
22:05:19 INFO - 1501045519983 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49769
22:05:19 INFO - 1501045519984 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:19 INFO - 1501045519984 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:19 INFO - 1501045519986 Marionette DEBUG Register listener.js for window 12
22:05:19 INFO - 1501045519988 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3f9ded87-3eeb-4d14-a61a-1c4310f3468e","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:19 INFO - 1501045519990 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:05:19 INFO - 1501045519991 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:19 INFO - 1501045519993 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:19 INFO - 1501045519993 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520003 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520012 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520015 Marionette TRACE 370 <- [1,2,null,{}]
22:05:20 INFO - 1501045520016 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:05:20 INFO - 1501045520018 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1fcc195c-b17d-4214-b2de-593635c6ee38","ELEMENT":"1fcc195c-b17d-4214-b2de-593635c6ee38"}}]
22:05:20 INFO - 1501045520019 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1fcc195c-b17d-4214-b2de-593635c6ee38"}]
22:05:20 INFO - 1501045520271 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:20 INFO - 1501045520272 Marionette TRACE 370 <- [1,4,null,{}]
22:05:20 INFO - 1501045520273 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1fcc195c-b17d-4214-b2de-593635c6ee38","name":"selected"}]
22:05:20 INFO - 1501045520277 Marionette TRACE 370 <- [1,5,null,{"value":true}]
22:05:20 INFO - 1501045520278 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
22:05:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501045520280 Marionette TRACE 370 <- [1,6,null,{"value":null}]
22:05:20 INFO - 1501045520281 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
22:05:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
22:05:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:05:20 INFO - 1501045520283 Marionette TRACE 370 <- [1,7,null,{}]
22:05:20 INFO - 1501045520285 Marionette DEBUG Closed connection 370
22:05:20 INFO - 1501045520285 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49770
22:05:20 INFO - 1501045520286 Marionette DEBUG Closed connection 371
22:05:20 INFO - 1501045520287 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49771
22:05:20 INFO - 1501045520288 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520289 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520290 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520293 Marionette TRACE 372 <- [1,1,null,{"sessionId":"268cb60b-b053-444f-b24e-3ae2cb7bd105","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520294 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
22:05:20 INFO - 1501045520295 Marionette TRACE 372 <- [1,2,null,{}]
22:05:20 INFO - 1501045520295 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:20 INFO - 1501045520296 Marionette TRACE 372 <- [1,3,null,{}]
22:05:20 INFO - 1501045520297 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:20 INFO - 1501045520297 Marionette TRACE 372 <- [1,4,null,{}]
22:05:20 INFO - 1501045520299 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
22:05:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501045520301 Marionette TRACE 372 <- [1,5,null,{"value":null}]
22:05:20 INFO - 1501045520302 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
22:05:20 INFO - 1501045520303 Marionette TRACE 372 <- [1,6,null,{}]
22:05:20 INFO - 1501045520305 Marionette DEBUG Closed connection 372
22:05:20 INFO - 1501045520305 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49772
22:05:20 INFO - 1501045520306 Marionette DEBUG Closed connection 373
22:05:20 INFO - 1501045520307 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49773
22:05:20 INFO - 1501045520308 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520308 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520310 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520312 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f44d1d17-590f-4127-8960-073372b94288","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520313 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
22:05:20 INFO - 1501045520314 Marionette TRACE 374 <- [1,2,null,{}]
22:05:20 INFO - 1501045520316 Marionette DEBUG Closed connection 374
22:05:20 INFO - 1501045520316 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49774
22:05:20 INFO - 1501045520317 Marionette DEBUG Closed connection 375
22:05:20 INFO - 1501045520317 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49775
22:05:20 INFO - 1501045520318 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:20 INFO - 1501045520319 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520321 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520323 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0a3359b1-8623-4cad-8959-7b898e4e33e4","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520325 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
22:05:20 INFO - 1501045520326 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520328 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520328 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520344 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:05:20 INFO - 1501045520345 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:05:20 INFO - 1501045520348 Marionette TRACE 376 <- [1,2,null,{}]
22:05:20 INFO - 1501045520349 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:05:20 INFO - 1501045520351 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2f23fea-acd4-4b6c-a525-1d8fbd623b67","ELEMENT":"f2f23fea-acd4-4b6c-a525-1d8fbd623b67"}}]
22:05:20 INFO - 1501045520352 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f2f23fea-acd4-4b6c-a525-1d8fbd623b67"}]
22:05:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 328ms
22:05:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:05:20 INFO - 1501045520604 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:20 INFO - 1501045520605 Marionette TRACE 376 <- [1,4,null,{}]
22:05:20 INFO - 1501045520606 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
22:05:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501045520608 Marionette TRACE 376 <- [1,5,null,{"value":null}]
22:05:20 INFO - 1501045520609 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
22:05:20 INFO - 1501045520610 Marionette TRACE 376 <- [1,6,null,{}]
22:05:20 INFO - 1501045520612 Marionette DEBUG Closed connection 376
22:05:20 INFO - 1501045520613 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49776
22:05:20 INFO - 1501045520614 Marionette DEBUG Closed connection 377
22:05:20 INFO - 1501045520614 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49777
22:05:20 INFO - 1501045520615 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520616 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520617 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520621 Marionette TRACE 378 <- [1,1,null,{"sessionId":"100f01bd-bc08-4fef-9d23-10500cb4be9a","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520623 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
22:05:20 INFO - 1501045520623 Marionette TRACE 378 <- [1,2,null,{}]
22:05:20 INFO - 1501045520624 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:20 INFO - 1501045520625 Marionette TRACE 378 <- [1,3,null,{}]
22:05:20 INFO - 1501045520625 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:20 INFO - 1501045520626 Marionette TRACE 378 <- [1,4,null,{}]
22:05:20 INFO - 1501045520627 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
22:05:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501045520629 Marionette TRACE 378 <- [1,5,null,{"value":null}]
22:05:20 INFO - 1501045520630 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
22:05:20 INFO - 1501045520631 Marionette TRACE 378 <- [1,6,null,{}]
22:05:20 INFO - 1501045520633 Marionette DEBUG Closed connection 378
22:05:20 INFO - 1501045520633 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49778
22:05:20 INFO - 1501045520634 Marionette DEBUG Closed connection 379
22:05:20 INFO - 1501045520634 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49779
22:05:20 INFO - 1501045520635 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520636 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520637 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520640 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ce37f530-e974-4f80-88d4-f73bfae6f3a8","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520642 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
22:05:20 INFO - 1501045520643 Marionette TRACE 380 <- [1,2,null,{}]
22:05:20 INFO - 1501045520645 Marionette DEBUG Closed connection 380
22:05:20 INFO - 1501045520645 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49780
22:05:20 INFO - 1501045520646 Marionette DEBUG Closed connection 381
22:05:20 INFO - 1501045520646 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49781
22:05:20 INFO - 1501045520647 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:20 INFO - 1501045520648 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520649 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520652 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c1df4fc4-82be-4fd7-bbb9-e6419a536b7e","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520654 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:05:20 INFO - 1501045520655 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:05:20 INFO - 1501045520657 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:05:20 INFO - 1501045520657 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:05:20 INFO - 1501045520666 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520670 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520679 Marionette TRACE 382 <- [1,2,null,{}]
22:05:20 INFO - 1501045520681 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:05:20 INFO - 1501045520682 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e652a860-2d3a-4d48-bd78-f029db541af1","ELEMENT":"e652a860-2d3a-4d48-bd78-f029db541af1"}}]
22:05:20 INFO - 1501045520683 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e652a860-2d3a-4d48-bd78-f029db541af1"}]
22:05:20 INFO - 1501045520920 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:20 INFO - 1501045520920 Marionette TRACE 382 <- [1,4,null,{}]
22:05:20 INFO - 1501045520922 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
22:05:20 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501045520924 Marionette TRACE 382 <- [1,5,null,{"value":null}]
22:05:20 INFO - 1501045520925 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
22:05:20 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
22:05:20 INFO - TEST-START | test_click.py TestClick.test_input_file
22:05:20 INFO - 1501045520926 Marionette TRACE 382 <- [1,6,null,{}]
22:05:20 INFO - 1501045520928 Marionette DEBUG Closed connection 382
22:05:20 INFO - 1501045520929 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49782
22:05:20 INFO - 1501045520930 Marionette DEBUG Closed connection 383
22:05:20 INFO - 1501045520930 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49783
22:05:20 INFO - 1501045520931 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520932 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520933 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520936 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f8c1ff72-2daa-43e0-8f95-dbf205446305","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520937 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
22:05:20 INFO - 1501045520938 Marionette TRACE 384 <- [1,2,null,{}]
22:05:20 INFO - 1501045520939 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:20 INFO - 1501045520939 Marionette TRACE 384 <- [1,3,null,{}]
22:05:20 INFO - 1501045520940 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:20 INFO - 1501045520940 Marionette TRACE 384 <- [1,4,null,{}]
22:05:20 INFO - 1501045520942 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
22:05:20 INFO - TEST-START: test_click.py TestClick.test_input_file1501045520944 Marionette TRACE 384 <- [1,5,null,{"value":null}]
22:05:20 INFO - 1501045520944 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
22:05:20 INFO - 1501045520945 Marionette TRACE 384 <- [1,6,null,{}]
22:05:20 INFO - 1501045520947 Marionette DEBUG Closed connection 384
22:05:20 INFO - 1501045520948 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49784
22:05:20 INFO - 1501045520948 Marionette DEBUG Closed connection 385
22:05:20 INFO - 1501045520949 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49785
22:05:20 INFO - 1501045520950 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:20 INFO - 1501045520950 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520952 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520954 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5ec22e48-4a65-4c07-9db3-c45ba82a7359","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520956 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
22:05:20 INFO - 1501045520957 Marionette TRACE 386 <- [1,2,null,{}]
22:05:20 INFO - 1501045520959 Marionette DEBUG Closed connection 386
22:05:20 INFO - 1501045520959 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49786
22:05:20 INFO - 1501045520960 Marionette DEBUG Closed connection 387
22:05:20 INFO - 1501045520960 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49787
22:05:20 INFO - 1501045520961 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:20 INFO - 1501045520962 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:20 INFO - 1501045520963 Marionette DEBUG Register listener.js for window 12
22:05:20 INFO - 1501045520966 Marionette TRACE 388 <- [1,1,null,{"sessionId":"39e9f65a-8181-41b6-93bb-0ae22012bd59","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:20 INFO - 1501045520968 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:05:20 INFO - 1501045520969 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520970 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520971 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:05:20 INFO - 1501045520979 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:20 INFO - 1501045520983 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:20 INFO - 1501045520986 Marionette TRACE 388 <- [1,2,null,{}]
22:05:20 INFO - 1501045520993 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:05:20 INFO - 1501045520996 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a08d69c9-b973-4ce6-a1b2-56fe78ed5779","ELEMENT":"a08d69c9-b973-4ce6-a1b2-56fe78ed5779"}}]
22:05:20 INFO - 1501045520997 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a08d69c9-b973-4ce6-a1b2-56fe78ed5779"}]
22:05:21 INFO - 1501045521027 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]
22:05:21 INFO - 1501045521029 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
22:05:21 INFO - TEST-END: test_click.py TestClick.test_input_file1501045521031 Marionette TRACE 388 <- [1,5,null,{"value":null}]
22:05:21 INFO - 1501045521032 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
22:05:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 106ms
22:05:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:05:21 INFO - 1501045521033 Marionette TRACE 388 <- [1,6,null,{}]
22:05:21 INFO - 1501045521034 Marionette DEBUG Closed connection 388
22:05:21 INFO - 1501045521035 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49788
22:05:21 INFO - 1501045521036 Marionette DEBUG Closed connection 389
22:05:21 INFO - 1501045521037 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49789
22:05:21 INFO - 1501045521037 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:21 INFO - 1501045521038 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521040 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521042 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5a457102-952e-416e-8d47-0bc5e6aa4868","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521044 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
22:05:21 INFO - 1501045521044 Marionette TRACE 390 <- [1,2,null,{}]
22:05:21 INFO - 1501045521045 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:21 INFO - 1501045521046 Marionette TRACE 390 <- [1,3,null,{}]
22:05:21 INFO - 1501045521047 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:21 INFO - 1501045521047 Marionette TRACE 390 <- [1,4,null,{}]
22:05:21 INFO - 1501045521048 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
22:05:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501045521051 Marionette TRACE 390 <- [1,5,null,{"value":null}]
22:05:21 INFO - 1501045521051 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
22:05:21 INFO - 1501045521053 Marionette TRACE 390 <- [1,6,null,{}]
22:05:21 INFO - 1501045521054 Marionette DEBUG Closed connection 390
22:05:21 INFO - 1501045521055 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49790
22:05:21 INFO - 1501045521055 Marionette DEBUG Closed connection 391
22:05:21 INFO - 1501045521056 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49791
22:05:21 INFO - 1501045521057 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:21 INFO - 1501045521057 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521059 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521061 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a31e7075-ecda-4011-985c-f560da584c2f","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521063 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
22:05:21 INFO - 1501045521064 Marionette TRACE 392 <- [1,2,null,{}]
22:05:21 INFO - 1501045521066 Marionette DEBUG Closed connection 392
22:05:21 INFO - 1501045521066 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49792
22:05:21 INFO - 1501045521067 Marionette DEBUG Closed connection 393
22:05:21 INFO - 1501045521067 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49793
22:05:21 INFO - 1501045521068 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:21 INFO - 1501045521069 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521070 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521073 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d86e6760-855f-4801-8f87-16d143317874","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521074 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
22:05:21 INFO - 1501045521075 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:21 INFO - 1501045521077 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:21 INFO - 1501045521077 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:05:21 INFO - 1501045521094 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:05:21 INFO - 1501045521095 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:05:21 INFO - 1501045521097 Marionette TRACE 394 <- [1,2,null,{}]
22:05:21 INFO - 1501045521098 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:05:21 INFO - 1501045521100 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"976ede48-aeaa-4999-b105-7f004914eaa5","ELEMENT":"976ede48-aeaa-4999-b105-7f004914eaa5"}}]
22:05:21 INFO - 1501045521101 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:05:21 INFO - 1501045521108 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8334a9bd-3e53-476f-ba05-5756cda07ef9","ELEMENT":"8334a9bd-3e53-476f-ba05-5756cda07ef9"}}]
22:05:21 INFO - 1501045521109 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"976ede48-aeaa-4999-b105-7f004914eaa5"}]
22:05:21 INFO - 1501045521343 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:05:21 INFO - 1501045521343 Marionette TRACE 394 <- [1,5,null,{}]
22:05:21 INFO - 1501045521345 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"8334a9bd-3e53-476f-ba05-5756cda07ef9"}]
22:05:21 INFO - 1501045521358 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]
22:05:21 INFO - 1501045521361 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:05:21 INFO - 1501045521363 Marionette TRACE 394 <- [1,7,null,{"value":false}]
22:05:21 INFO - 1501045521364 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
22:05:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501045521366 Marionette TRACE 394 <- [1,8,null,{"value":null}]
22:05:21 INFO - 1501045521368 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
22:05:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
22:05:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:05:21 INFO - 1501045521369 Marionette TRACE 394 <- [1,9,null,{}]
22:05:21 INFO - 1501045521370 Marionette DEBUG Closed connection 394
22:05:21 INFO - 1501045521371 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49794
22:05:21 INFO - 1501045521372 Marionette DEBUG Closed connection 395
22:05:21 INFO - 1501045521372 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49795
22:05:21 INFO - 1501045521373 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:21 INFO - 1501045521374 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521375 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521384 Marionette TRACE 396 <- [1,1,null,{"sessionId":"d7dd6b32-fab6-4bc4-91f6-5046e0e26ecf","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521386 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
22:05:21 INFO - 1501045521387 Marionette TRACE 396 <- [1,2,null,{}]
22:05:21 INFO - 1501045521387 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:05:21 INFO - 1501045521388 Marionette TRACE 396 <- [1,3,null,{}]
22:05:21 INFO - 1501045521388 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
22:05:21 INFO - 1501045521389 Marionette TRACE 396 <- [1,4,null,{}]
22:05:21 INFO - 1501045521390 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
22:05:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501045521392 Marionette TRACE 396 <- [1,5,null,{"value":null}]
22:05:21 INFO - 1501045521393 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
22:05:21 INFO - 1501045521394 Marionette TRACE 396 <- [1,6,null,{}]
22:05:21 INFO - 1501045521397 Marionette DEBUG Closed connection 396
22:05:21 INFO - 1501045521397 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49796
22:05:21 INFO - 1501045521398 Marionette DEBUG Closed connection 397
22:05:21 INFO - 1501045521399 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49797
22:05:21 INFO - 1501045521399 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:05:21 INFO - 1501045521400 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521402 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521404 Marionette TRACE 398 <- [1,1,null,{"sessionId":"10048d7a-6bed-4137-a193-01e2fc172b8c","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521406 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
22:05:21 INFO - 1501045521407 Marionette TRACE 398 <- [1,2,null,{}]
22:05:21 INFO - 1501045521408 Marionette DEBUG Closed connection 398
22:05:21 INFO - 1501045521409 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49798
22:05:21 INFO - 1501045521410 Marionette DEBUG Closed connection 399
22:05:21 INFO - 1501045521410 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49799
22:05:21 INFO - 1501045521411 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:05:21 INFO - 1501045521412 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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}
22:05:21 INFO - 1501045521413 Marionette DEBUG Register listener.js for window 12
22:05:21 INFO - 1501045521416 Marionette TRACE 400 <- [1,1,null,{"sessionId":"2a7e79b1-8152-4169-a2c1-0f2d63281ded","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":2244,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-356.000\\AppData\\Local\\Temp\\tmpuac3b7.mozrunner","moz:accessibilityChecks":false}}]
22:05:21 INFO - 1501045521418 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
22:05:21 INFO - 1501045521419 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:05:21 INFO - 1501045521421 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:05:21 INFO - 1501045521421 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:05:21 INFO - 1501045521432 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
22:05:21 INFO - 1501045521435 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
22:05:21 INFO - 1501045521443 Marionette TRACE 400 <- [1,2,null,{}]
22:05:21 INFO - 1501045521444 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:05:21 INFO - 1501045521446 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b15b04fb-7654-4af3-886f-d63944648e04","ELEMENT":"b15b04fb-7654-4af3-886f-d63944648e04"}}]
22:05:21 INFO - 1501045521447 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b15b04fb-7654-4af3-886f-d63944648e04"}]
22:05:21 INFO - 1501045521468 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
22:05:21 INFO - 1501045521469 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b15b04fb-7654-4af3-886f-d63944648e04"}]
22:05:21 INFO - 1501045521483 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element