could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
21:37:44 INFO - 1498970264483 Marionette TRACE 353 -> [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}]
21:37:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498970264485 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:37:44 INFO - 1498970264486 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:37:44 INFO - 1498970264487 Marionette TRACE 353 <- [1,6,null,{}]
21:37:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 118ms
21:37:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:37:44 INFO - 1498970264488 Marionette DEBUG Closed connection 353
21:37:44 INFO - 1498970264490 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49778
21:37:44 INFO - 1498970264490 Marionette DEBUG Closed connection 354
21:37:44 INFO - 1498970264491 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49779
21:37:44 INFO - 1498970264492 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264493 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264495 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264497 Marionette TRACE 355 <- [1,1,null,{"sessionId":"6cc1fe9d-af59-47aa-a844-a45bee4a666a","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264498 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:37:44 INFO - 1498970264499 Marionette TRACE 355 <- [1,2,null,{}]
21:37:44 INFO - 1498970264500 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:44 INFO - 1498970264500 Marionette TRACE 355 <- [1,3,null,{}]
21:37:44 INFO - 1498970264501 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:44 INFO - 1498970264501 Marionette TRACE 355 <- [1,4,null,{}]
21:37:44 INFO - 1498970264503 Marionette TRACE 355 -> [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}]
21:37:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498970264505 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:37:44 INFO - 1498970264506 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:37:44 INFO - 1498970264507 Marionette TRACE 355 <- [1,6,null,{}]
21:37:44 INFO - 1498970264508 Marionette DEBUG Closed connection 355
21:37:44 INFO - 1498970264509 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49780
21:37:44 INFO - 1498970264510 Marionette DEBUG Closed connection 356
21:37:44 INFO - 1498970264510 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49781
21:37:44 INFO - 1498970264511 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264512 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264514 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264516 Marionette TRACE 357 <- [1,1,null,{"sessionId":"9510d5bf-0dca-4eca-b548-bf868196680e","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264517 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:37:44 INFO - 1498970264518 Marionette TRACE 357 <- [1,2,null,{}]
21:37:44 INFO - 1498970264520 Marionette DEBUG Closed connection 357
21:37:44 INFO - 1498970264520 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49782
21:37:44 INFO - 1498970264521 Marionette DEBUG Closed connection 358
21:37:44 INFO - 1498970264521 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49783
21:37:44 INFO - 1498970264522 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:44 INFO - 1498970264523 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264525 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264528 Marionette TRACE 359 <- [1,1,null,{"sessionId":"e61673b1-df52-406f-81dd-426d707c37c0","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264529 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49341/clicks.html"}]
21:37:44 INFO - 1498970264531 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/hidden.html"
21:37:44 INFO - 1498970264537 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/hidden.html"
21:37:44 INFO - 1498970264538 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/hidden.html"
21:37:44 INFO - 1498970264548 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49341/clicks.html"
21:37:44 INFO - 1498970264549 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49341/clicks.html"
21:37:44 INFO - 1498970264551 Marionette TRACE 359 <- [1,2,null,{}]
21:37:44 INFO - 1498970264554 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:37:44 INFO - 1498970264556 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a8e67ef-4303-42c8-a6f4-03bf837bc31d","ELEMENT":"9a8e67ef-4303-42c8-a6f4-03bf837bc31d"}}]
21:37:44 INFO - 1498970264557 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"9a8e67ef-4303-42c8-a6f4-03bf837bc31d"}]
21:37:44 INFO - 1498970264596 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/clicks.html"
21:37:44 INFO - 1498970264603 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/clicks.html"
21:37:44 INFO - 1498970264603 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/clicks.html"
21:37:44 INFO - 1498970264613 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49341/test.html"
21:37:44 INFO - 1498970264616 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49341/test.html"
21:37:44 INFO - 1498970264617 Marionette TRACE 359 <- [1,4,null,{}]
21:37:44 INFO - 1498970264620 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:37:44 INFO - 1498970264622 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"893dcc84-b96f-4223-8f14-a830654adcef","ELEMENT":"893dcc84-b96f-4223-8f14-a830654adcef"}}]
21:37:44 INFO - 1498970264622 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:37:44 INFO - 1498970264624 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:37:44 INFO - 1498970264631 Marionette TRACE 359 -> [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}]
21:37:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498970264633 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:37:44 INFO - 1498970264635 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:37:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 148ms
21:37:44 INFO - TEST-START | test_click.py TestClick.test_container_element
21:37:44 INFO - 1498970264636 Marionette TRACE 359 <- [1,8,null,{}]
21:37:44 INFO - 1498970264637 Marionette DEBUG Closed connection 359
21:37:44 INFO - 1498970264638 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49786
21:37:44 INFO - 1498970264639 Marionette DEBUG Closed connection 360
21:37:44 INFO - 1498970264639 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49787
21:37:44 INFO - 1498970264640 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264641 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264643 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264646 Marionette TRACE 361 <- [1,1,null,{"sessionId":"1961e0dd-a046-4204-a95b-49bbcc01f692","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264647 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:37:44 INFO - 1498970264647 Marionette TRACE 361 <- [1,2,null,{}]
21:37:44 INFO - 1498970264648 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:44 INFO - 1498970264649 Marionette TRACE 361 <- [1,3,null,{}]
21:37:44 INFO - 1498970264649 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:44 INFO - 1498970264650 Marionette TRACE 361 <- [1,4,null,{}]
21:37:44 INFO - 1498970264651 Marionette TRACE 361 -> [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}]
21:37:44 INFO - TEST-START: test_click.py TestClick.test_container_element1498970264653 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:37:44 INFO - 1498970264654 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:37:44 INFO - 1498970264655 Marionette TRACE 361 <- [1,6,null,{}]
21:37:44 INFO - 1498970264657 Marionette DEBUG Closed connection 361
21:37:44 INFO - 1498970264657 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49788
21:37:44 INFO - 1498970264658 Marionette DEBUG Closed connection 362
21:37:44 INFO - 1498970264658 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49789
21:37:44 INFO - 1498970264659 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264660 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264662 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264665 Marionette TRACE 363 <- [1,1,null,{"sessionId":"4a37a9cf-6116-4dea-ae1b-2eebc2b580af","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264667 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:37:44 INFO - 1498970264668 Marionette TRACE 363 <- [1,2,null,{}]
21:37:44 INFO - 1498970264669 Marionette DEBUG Closed connection 363
21:37:44 INFO - 1498970264670 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49790
21:37:44 INFO - 1498970264670 Marionette DEBUG Closed connection 364
21:37:44 INFO - 1498970264671 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49791
21:37:44 INFO - 1498970264672 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:44 INFO - 1498970264672 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264675 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264678 Marionette TRACE 365 <- [1,1,null,{"sessionId":"786a7b97-2c00-4cd0-8b5f-9f3001488aab","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264680 Marionette TRACE 365 -> [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"}]
21:37:44 INFO - 1498970264682 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49341/test.html"
21:37:44 INFO - 1498970264683 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49341/test.html"
21:37:44 INFO - 1498970264684 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49341/test.html"
21:37:44 INFO - 1498970264691 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"
21:37:44 INFO - 1498970264694 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"
21:37:44 INFO - 1498970264701 Marionette TRACE 365 <- [1,2,null,{}]
21:37:44 INFO - 1498970264705 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:37:44 INFO - 1498970264707 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"73766618-cad0-4f38-a083-e70cce4d3dd9","ELEMENT":"73766618-cad0-4f38-a083-e70cce4d3dd9"}}]
21:37:44 INFO - 1498970264708 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"73766618-cad0-4f38-a083-e70cce4d3dd9"}]
21:37:44 INFO - 1498970264952 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:44 INFO - 1498970264952 Marionette TRACE 365 <- [1,4,null,{}]
21:37:44 INFO - 1498970264953 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"73766618-cad0-4f38-a083-e70cce4d3dd9","name":"selected"}]
21:37:44 INFO - 1498970264957 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:37:44 INFO - 1498970264959 Marionette TRACE 365 -> [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}]
21:37:44 INFO - TEST-END: test_click.py TestClick.test_container_element1498970264962 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:37:44 INFO - 1498970264963 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:37:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 328ms
21:37:44 INFO - 1498970264964 Marionette TRACE 365 <- [1,7,null,{}]
21:37:44 INFO - 1498970264965 Marionette DEBUG Closed connection 365
21:37:44 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:37:44 INFO - 1498970264968 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49792
21:37:44 INFO - 1498970264968 Marionette DEBUG Closed connection 366
21:37:44 INFO - 1498970264969 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49793
21:37:44 INFO - 1498970264970 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264970 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264972 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264975 Marionette TRACE 367 <- [1,1,null,{"sessionId":"55e65984-d9b5-4b58-92da-acda9e48caf2","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264976 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:37:44 INFO - 1498970264976 Marionette TRACE 367 <- [1,2,null,{}]
21:37:44 INFO - 1498970264977 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:44 INFO - 1498970264977 Marionette TRACE 367 <- [1,3,null,{}]
21:37:44 INFO - 1498970264978 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:44 INFO - 1498970264979 Marionette TRACE 367 <- [1,4,null,{}]
21:37:44 INFO - 1498970264980 Marionette TRACE 367 -> [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}]
21:37:44 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498970264983 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:37:44 INFO - 1498970264984 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:37:44 INFO - 1498970264985 Marionette TRACE 367 <- [1,6,null,{}]
21:37:44 INFO - 1498970264987 Marionette DEBUG Closed connection 367
21:37:44 INFO - 1498970264987 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49794
21:37:44 INFO - 1498970264988 Marionette DEBUG Closed connection 368
21:37:44 INFO - 1498970264989 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49795
21:37:44 INFO - 1498970264989 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:44 INFO - 1498970264990 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:44 INFO - 1498970264992 Marionette DEBUG Register listener.js for window 4294967297
21:37:44 INFO - 1498970264995 Marionette TRACE 369 <- [1,1,null,{"sessionId":"4e36ecde-c26b-43a4-926c-a7538e3bb99f","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:44 INFO - 1498970264996 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:37:44 INFO - 1498970264997 Marionette TRACE 369 <- [1,2,null,{}]
21:37:45 INFO - 1498970264998 Marionette DEBUG Closed connection 369
21:37:45 INFO - 1498970264998 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49796
21:37:45 INFO - 1498970264999 Marionette DEBUG Closed connection 370
21:37:45 INFO - 1498970265000 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49797
21:37:45 INFO - 1498970265001 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:45 INFO - 1498970265001 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265003 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265006 Marionette TRACE 371 <- [1,1,null,{"sessionId":"e36f78f3-21ae-4072-9531-6e4d00bccefc","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265007 Marionette TRACE 371 -> [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"}]
21:37:45 INFO - 1498970265008 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"
21:37:45 INFO - 1498970265010 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"
21:37:45 INFO - 1498970265010 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"
21:37:45 INFO - 1498970265016 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"
21:37:45 INFO - 1498970265018 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"
21:37:45 INFO - 1498970265020 Marionette TRACE 371 <- [1,2,null,{}]
21:37:45 INFO - 1498970265023 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:37:45 INFO - 1498970265025 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"29a36bef-d7bc-4bb9-9400-121fef10842a","ELEMENT":"29a36bef-d7bc-4bb9-9400-121fef10842a"}}]
21:37:45 INFO - 1498970265026 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"29a36bef-d7bc-4bb9-9400-121fef10842a"}]
21:37:45 INFO - 1498970265268 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:45 INFO - 1498970265269 Marionette TRACE 371 <- [1,4,null,{}]
21:37:45 INFO - 1498970265270 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"29a36bef-d7bc-4bb9-9400-121fef10842a","name":"selected"}]
21:37:45 INFO - 1498970265274 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:37:45 INFO - 1498970265275 Marionette TRACE 371 -> [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}]
21:37:45 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498970265277 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:37:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 313ms
21:37:45 INFO - 1498970265279 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:37:45 INFO - 1498970265280 Marionette TRACE 371 <- [1,7,null,{}]
21:37:45 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:37:45 INFO - 1498970265281 Marionette DEBUG Closed connection 371
21:37:45 INFO - 1498970265282 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49798
21:37:45 INFO - 1498970265283 Marionette DEBUG Closed connection 372
21:37:45 INFO - 1498970265284 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49799
21:37:45 INFO - 1498970265284 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265285 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265288 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265290 Marionette TRACE 373 <- [1,1,null,{"sessionId":"6566be79-b80d-4b55-bb42-dde9eb79cace","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265291 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:37:45 INFO - 1498970265292 Marionette TRACE 373 <- [1,2,null,{}]
21:37:45 INFO - 1498970265292 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:45 INFO - 1498970265293 Marionette TRACE 373 <- [1,3,null,{}]
21:37:45 INFO - 1498970265294 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:45 INFO - 1498970265294 Marionette TRACE 373 <- [1,4,null,{}]
21:37:45 INFO - 1498970265295 Marionette TRACE 373 -> [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}]
21:37:45 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498970265298 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:37:45 INFO - 1498970265299 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:37:45 INFO - 1498970265300 Marionette TRACE 373 <- [1,6,null,{}]
21:37:45 INFO - 1498970265301 Marionette DEBUG Closed connection 373
21:37:45 INFO - 1498970265301 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49800
21:37:45 INFO - 1498970265302 Marionette DEBUG Closed connection 374
21:37:45 INFO - 1498970265303 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49801
21:37:45 INFO - 1498970265304 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265304 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265306 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265309 Marionette TRACE 375 <- [1,1,null,{"sessionId":"2e57e968-51c2-434f-927a-ab4469d28799","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265310 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:37:45 INFO - 1498970265311 Marionette TRACE 375 <- [1,2,null,{}]
21:37:45 INFO - 1498970265313 Marionette DEBUG Closed connection 375
21:37:45 INFO - 1498970265313 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49802
21:37:45 INFO - 1498970265314 Marionette DEBUG Closed connection 376
21:37:45 INFO - 1498970265315 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49803
21:37:45 INFO - 1498970265315 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:45 INFO - 1498970265316 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265318 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265321 Marionette TRACE 377 <- [1,1,null,{"sessionId":"5959af22-537a-44b6-ac07-5eed49310096","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265322 Marionette TRACE 377 -> [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"}]
21:37:45 INFO - 1498970265323 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"
21:37:45 INFO - 1498970265325 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"
21:37:45 INFO - 1498970265325 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"
21:37:45 INFO - 1498970265331 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"
21:37:45 INFO - 1498970265334 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"
21:37:45 INFO - 1498970265337 Marionette TRACE 377 <- [1,2,null,{}]
21:37:45 INFO - 1498970265340 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:37:45 INFO - 1498970265343 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"001bbf80-dc58-472f-b054-881e1f35c3ce","ELEMENT":"001bbf80-dc58-472f-b054-881e1f35c3ce"}}]
21:37:45 INFO - 1498970265349 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"001bbf80-dc58-472f-b054-881e1f35c3ce"}]
21:37:45 INFO - 1498970265590 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:45 INFO - 1498970265591 Marionette TRACE 377 <- [1,4,null,{}]
21:37:45 INFO - 1498970265592 Marionette TRACE 377 -> [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}]
21:37:45 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498970265594 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:37:45 INFO - 1498970265595 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:37:45 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 316ms
21:37:45 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:37:45 INFO - 1498970265596 Marionette TRACE 377 <- [1,6,null,{}]
21:37:45 INFO - 1498970265598 Marionette DEBUG Closed connection 377
21:37:45 INFO - 1498970265599 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49804
21:37:45 INFO - 1498970265599 Marionette DEBUG Closed connection 378
21:37:45 INFO - 1498970265600 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49805
21:37:45 INFO - 1498970265601 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265602 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265604 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265606 Marionette TRACE 379 <- [1,1,null,{"sessionId":"a4c6a3dd-8082-4f79-962e-f27a8a721ad8","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265608 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:37:45 INFO - 1498970265608 Marionette TRACE 379 <- [1,2,null,{}]
21:37:45 INFO - 1498970265609 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:45 INFO - 1498970265609 Marionette TRACE 379 <- [1,3,null,{}]
21:37:45 INFO - 1498970265610 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:45 INFO - 1498970265610 Marionette TRACE 379 <- [1,4,null,{}]
21:37:45 INFO - 1498970265612 Marionette TRACE 379 -> [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}]
21:37:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498970265614 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:37:45 INFO - 1498970265615 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:37:45 INFO - 1498970265616 Marionette TRACE 379 <- [1,6,null,{}]
21:37:45 INFO - 1498970265617 Marionette DEBUG Closed connection 379
21:37:45 INFO - 1498970265617 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49806
21:37:45 INFO - 1498970265618 Marionette DEBUG Closed connection 380
21:37:45 INFO - 1498970265619 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49807
21:37:45 INFO - 1498970265620 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265620 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265622 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265625 Marionette TRACE 381 <- [1,1,null,{"sessionId":"ca508cf4-79cb-4dfa-9134-ca7508e8d681","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265626 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:37:45 INFO - 1498970265627 Marionette TRACE 381 <- [1,2,null,{}]
21:37:45 INFO - 1498970265628 Marionette DEBUG Closed connection 381
21:37:45 INFO - 1498970265628 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49808
21:37:45 INFO - 1498970265629 Marionette DEBUG Closed connection 382
21:37:45 INFO - 1498970265630 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49809
21:37:45 INFO - 1498970265631 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:45 INFO - 1498970265631 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265633 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265636 Marionette TRACE 383 <- [1,1,null,{"sessionId":"34d5e51d-34a9-4bf5-8be7-fca4df9e4b07","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265637 Marionette TRACE 383 -> [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"}]
21:37:45 INFO - 1498970265638 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"
21:37:45 INFO - 1498970265640 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"
21:37:45 INFO - 1498970265640 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"
21:37:45 INFO - 1498970265649 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"
21:37:45 INFO - 1498970265650 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"
21:37:45 INFO - 1498970265654 Marionette TRACE 383 <- [1,2,null,{}]
21:37:45 INFO - 1498970265656 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:37:45 INFO - 1498970265658 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33270435-c1ce-4c6c-880e-6c94d65be941","ELEMENT":"33270435-c1ce-4c6c-880e-6c94d65be941"}}]
21:37:45 INFO - 1498970265659 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"33270435-c1ce-4c6c-880e-6c94d65be941"}]
21:37:45 INFO - 1498970265900 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:45 INFO - 1498970265901 Marionette TRACE 383 <- [1,4,null,{}]
21:37:45 INFO - 1498970265903 Marionette TRACE 383 -> [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}]
21:37:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498970265906 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:37:45 INFO - 1498970265908 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:37:45 INFO - 1498970265911 Marionette TRACE 383 <- [1,6,null,{}]
21:37:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 314ms
21:37:45 INFO - TEST-START | test_click.py TestClick.test_input_file
21:37:45 INFO - 1498970265913 Marionette DEBUG Closed connection 383
21:37:45 INFO - 1498970265914 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49810
21:37:45 INFO - 1498970265918 Marionette DEBUG Closed connection 384
21:37:45 INFO - 1498970265919 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49811
21:37:45 INFO - 1498970265926 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265928 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265931 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265935 Marionette TRACE 385 <- [1,1,null,{"sessionId":"4017fb75-c1aa-4c20-ada7-c16d17a1dd91","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265938 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:37:45 INFO - 1498970265938 Marionette TRACE 385 <- [1,2,null,{}]
21:37:45 INFO - 1498970265939 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:45 INFO - 1498970265940 Marionette TRACE 385 <- [1,3,null,{}]
21:37:45 INFO - 1498970265942 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:45 INFO - 1498970265942 Marionette TRACE 385 <- [1,4,null,{}]
21:37:45 INFO - 1498970265944 Marionette TRACE 385 -> [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}]
21:37:45 INFO - TEST-START: test_click.py TestClick.test_input_file1498970265947 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:37:45 INFO - 1498970265948 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:37:45 INFO - 1498970265950 Marionette TRACE 385 <- [1,6,null,{}]
21:37:45 INFO - 1498970265952 Marionette DEBUG Closed connection 385
21:37:45 INFO - 1498970265953 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49812
21:37:45 INFO - 1498970265954 Marionette DEBUG Closed connection 386
21:37:45 INFO - 1498970265955 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49813
21:37:45 INFO - 1498970265956 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:45 INFO - 1498970265957 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265959 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265963 Marionette TRACE 387 <- [1,1,null,{"sessionId":"9d61e2ec-bedb-490f-92d0-bd63aed06a0a","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265965 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:37:45 INFO - 1498970265966 Marionette TRACE 387 <- [1,2,null,{}]
21:37:45 INFO - 1498970265968 Marionette DEBUG Closed connection 387
21:37:45 INFO - 1498970265968 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49814
21:37:45 INFO - 1498970265969 Marionette DEBUG Closed connection 388
21:37:45 INFO - 1498970265970 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49815
21:37:45 INFO - 1498970265972 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:45 INFO - 1498970265974 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:45 INFO - 1498970265976 Marionette DEBUG Register listener.js for window 4294967297
21:37:45 INFO - 1498970265979 Marionette TRACE 389 <- [1,1,null,{"sessionId":"e3f8d8de-c1ba-4434-939c-350be110536d","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:45 INFO - 1498970265980 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:37:45 INFO - 1498970265982 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"
21:37:45 INFO - 1498970265983 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"
21:37:45 INFO - 1498970265984 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"
21:37:45 INFO - 1498970265991 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:45 INFO - 1498970265995 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:46 INFO - 1498970266002 Marionette TRACE 389 <- [1,2,null,{}]
21:37:46 INFO - 1498970266006 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:37:46 INFO - 1498970266008 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6159bf35-5fef-4a70-a87a-74c28ae7e364","ELEMENT":"6159bf35-5fef-4a70-a87a-74c28ae7e364"}}]
21:37:46 INFO - 1498970266009 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"6159bf35-5fef-4a70-a87a-74c28ae7e364"}]
21:37:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
21:37:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:37:46 INFO - 1498970266042 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
21:37:46 INFO - 1498970266043 Marionette TRACE 389 -> [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}]
21:37:46 INFO - TEST-END: test_click.py TestClick.test_input_file1498970266046 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:37:46 INFO - 1498970266047 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:37:46 INFO - 1498970266048 Marionette TRACE 389 <- [1,6,null,{}]
21:37:46 INFO - 1498970266050 Marionette DEBUG Closed connection 389
21:37:46 INFO - 1498970266051 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49816
21:37:46 INFO - 1498970266051 Marionette DEBUG Closed connection 390
21:37:46 INFO - 1498970266052 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49817
21:37:46 INFO - 1498970266053 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:46 INFO - 1498970266054 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266056 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266060 Marionette TRACE 391 <- [1,1,null,{"sessionId":"282fe65d-2201-425f-9c5c-878d8612f795","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266061 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:37:46 INFO - 1498970266062 Marionette TRACE 391 <- [1,2,null,{}]
21:37:46 INFO - 1498970266063 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:46 INFO - 1498970266063 Marionette TRACE 391 <- [1,3,null,{}]
21:37:46 INFO - 1498970266064 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:46 INFO - 1498970266064 Marionette TRACE 391 <- [1,4,null,{}]
21:37:46 INFO - 1498970266066 Marionette TRACE 391 -> [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}]
21:37:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498970266068 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:37:46 INFO - 1498970266069 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:37:46 INFO - 1498970266071 Marionette TRACE 391 <- [1,6,null,{}]
21:37:46 INFO - 1498970266072 Marionette DEBUG Closed connection 391
21:37:46 INFO - 1498970266073 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49818
21:37:46 INFO - 1498970266073 Marionette DEBUG Closed connection 392
21:37:46 INFO - 1498970266074 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49819
21:37:46 INFO - 1498970266075 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:46 INFO - 1498970266076 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266078 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266081 Marionette TRACE 393 <- [1,1,null,{"sessionId":"79c9bee7-5035-41b2-9c6e-fc0278ba4b63","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266083 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:37:46 INFO - 1498970266085 Marionette TRACE 393 <- [1,2,null,{}]
21:37:46 INFO - 1498970266087 Marionette DEBUG Closed connection 393
21:37:46 INFO - 1498970266089 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49820
21:37:46 INFO - 1498970266090 Marionette DEBUG Closed connection 394
21:37:46 INFO - 1498970266090 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49821
21:37:46 INFO - 1498970266091 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:46 INFO - 1498970266093 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266096 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266099 Marionette TRACE 395 <- [1,1,null,{"sessionId":"c73c6e33-265a-4f9d-800b-c476b7e6aae7","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266100 Marionette TRACE 395 -> [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"}]
21:37:46 INFO - 1498970266102 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:46 INFO - 1498970266103 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:46 INFO - 1498970266104 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:37:46 INFO - 1498970266109 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"
21:37:46 INFO - 1498970266112 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"
21:37:46 INFO - 1498970266122 Marionette TRACE 395 <- [1,2,null,{}]
21:37:46 INFO - 1498970266125 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:37:46 INFO - 1498970266130 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"be84d40e-af56-4558-bf6c-c6726e16a3dc","ELEMENT":"be84d40e-af56-4558-bf6c-c6726e16a3dc"}}]
21:37:46 INFO - 1498970266131 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:37:46 INFO - 1498970266133 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35299b4d-f2a0-4b97-88b6-a1b570346a0d","ELEMENT":"35299b4d-f2a0-4b97-88b6-a1b570346a0d"}}]
21:37:46 INFO - 1498970266134 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"be84d40e-af56-4558-bf6c-c6726e16a3dc"}]
21:37:46 INFO - 1498970266370 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:37:46 INFO - 1498970266371 Marionette TRACE 395 <- [1,5,null,{}]
21:37:46 INFO - 1498970266372 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"35299b4d-f2a0-4b97-88b6-a1b570346a0d"}]
21:37:46 INFO - 1498970266386 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
21:37:46 INFO - 1498970266388 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:37:46 INFO - 1498970266390 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:37:46 INFO - 1498970266392 Marionette TRACE 395 -> [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}]
21:37:46 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498970266394 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:37:46 INFO - 1498970266395 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:37:46 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
21:37:46 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:37:46 INFO - 1498970266396 Marionette TRACE 395 <- [1,9,null,{}]
21:37:46 INFO - 1498970266398 Marionette DEBUG Closed connection 395
21:37:46 INFO - 1498970266398 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49822
21:37:46 INFO - 1498970266399 Marionette DEBUG Closed connection 396
21:37:46 INFO - 1498970266399 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49823
21:37:46 INFO - 1498970266400 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:46 INFO - 1498970266401 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266403 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266405 Marionette TRACE 397 <- [1,1,null,{"sessionId":"29001ecd-52a2-4c65-8ecf-5cdb184fc7ee","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266407 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:37:46 INFO - 1498970266407 Marionette TRACE 397 <- [1,2,null,{}]
21:37:46 INFO - 1498970266408 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:37:46 INFO - 1498970266408 Marionette TRACE 397 <- [1,3,null,{}]
21:37:46 INFO - 1498970266410 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:37:46 INFO - 1498970266410 Marionette TRACE 397 <- [1,4,null,{}]
21:37:46 INFO - 1498970266412 Marionette TRACE 397 -> [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}]
21:37:46 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498970266414 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:37:46 INFO - 1498970266415 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:37:46 INFO - 1498970266416 Marionette TRACE 397 <- [1,6,null,{}]
21:37:46 INFO - 1498970266418 Marionette DEBUG Closed connection 397
21:37:46 INFO - 1498970266418 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49824
21:37:46 INFO - 1498970266419 Marionette DEBUG Closed connection 398
21:37:46 INFO - 1498970266420 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49825
21:37:46 INFO - 1498970266420 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:37:46 INFO - 1498970266421 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266423 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266426 Marionette TRACE 399 <- [1,1,null,{"sessionId":"c25cfab3-97c8-4b4d-a27f-e4f691a057d3","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266427 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:37:46 INFO - 1498970266428 Marionette TRACE 399 <- [1,2,null,{}]
21:37:46 INFO - 1498970266429 Marionette DEBUG Closed connection 399
21:37:46 INFO - 1498970266430 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49826
21:37:46 INFO - 1498970266431 Marionette DEBUG Closed connection 400
21:37:46 INFO - 1498970266431 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49827
21:37:46 INFO - 1498970266432 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:37:46 INFO - 1498970266433 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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}
21:37:46 INFO - 1498970266436 Marionette DEBUG Register listener.js for window 4294967297
21:37:46 INFO - 1498970266438 Marionette TRACE 401 <- [1,1,null,{"sessionId":"2cd156d3-07f2-493e-a539-f642bc992e8e","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":2876,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpvbcp6w.mozrunner","moz:accessibilityChecks":false}}]
21:37:46 INFO - 1498970266439 Marionette TRACE 401 -> [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"}]
21:37:46 INFO - 1498970266441 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"
21:37:46 INFO - 1498970266443 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"
21:37:46 INFO - 1498970266443 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"
21:37:46 INFO - 1498970266449 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"
21:37:46 INFO - 1498970266451 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"
21:37:46 INFO - 1498970266455 Marionette TRACE 401 <- [1,2,null,{}]
21:37:46 INFO - 1498970266458 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:37:46 INFO - 1498970266465 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83c3c125-632c-40b2-8790-fed73592ac08","ELEMENT":"83c3c125-632c-40b2-8790-fed73592ac08"}}]
21:37:46 INFO - 1498970266466 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"83c3c125-632c-40b2-8790-fed73592ac08"}]
21:37:46 INFO - 1498970266488 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:37:46 INFO - 1498970266490 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"83c3c125-632c-40b2-8790-fed73592ac08"}]
21:37:46 INFO - 1498970266503 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element