could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:17:42 INFO - 1500970662522 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
01:17:42 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500970662525 Marionette TRACE 352 <- [1,5,null,{"value":null}]
01:17:42 INFO - 1500970662526 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
01:17:42 INFO - 1500970662527 Marionette TRACE 352 <- [1,6,null,{}]
01:17:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
01:17:42 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:17:42 INFO - 1500970662529 Marionette DEBUG Closed connection 352
01:17:42 INFO - 1500970662530 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49776
01:17:42 INFO - 1500970662531 Marionette DEBUG Closed connection 353
01:17:42 INFO - 1500970662531 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49777
01:17:42 INFO - 1500970662532 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:42 INFO - 1500970662533 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662536 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662547 Marionette TRACE 354 <- [1,1,null,{"sessionId":"22a946a8-19cf-436c-9e45-0f60293c76cc","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662548 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
01:17:42 INFO - 1500970662549 Marionette TRACE 354 <- [1,2,null,{}]
01:17:42 INFO - 1500970662550 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:42 INFO - 1500970662550 Marionette TRACE 354 <- [1,3,null,{}]
01:17:42 INFO - 1500970662551 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:42 INFO - 1500970662551 Marionette TRACE 354 <- [1,4,null,{}]
01:17:42 INFO - 1500970662553 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
01:17:42 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500970662556 Marionette TRACE 354 <- [1,5,null,{"value":null}]
01:17:42 INFO - 1500970662557 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
01:17:42 INFO - 1500970662559 Marionette TRACE 354 <- [1,6,null,{}]
01:17:42 INFO - 1500970662560 Marionette DEBUG Closed connection 354
01:17:42 INFO - 1500970662561 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49778
01:17:42 INFO - 1500970662562 Marionette DEBUG Closed connection 355
01:17:42 INFO - 1500970662562 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49779
01:17:42 INFO - 1500970662564 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:42 INFO - 1500970662564 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662567 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662570 Marionette TRACE 356 <- [1,1,null,{"sessionId":"acddd45c-af37-4a43-b1b1-5c123088c798","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662572 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
01:17:42 INFO - 1500970662573 Marionette TRACE 356 <- [1,2,null,{}]
01:17:42 INFO - 1500970662574 Marionette DEBUG Closed connection 356
01:17:42 INFO - 1500970662575 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49780
01:17:42 INFO - 1500970662576 Marionette DEBUG Closed connection 357
01:17:42 INFO - 1500970662577 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49781
01:17:42 INFO - 1500970662578 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:42 INFO - 1500970662579 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662583 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662586 Marionette TRACE 358 <- [1,1,null,{"sessionId":"ce2c2321-631b-46bb-8605-bebbd478970d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662588 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49339/clicks.html"}]
01:17:42 INFO - 1500970662590 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/hidden.html"
01:17:42 INFO - 1500970662599 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/hidden.html"
01:17:42 INFO - 1500970662599 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/hidden.html"
01:17:42 INFO - 1500970662616 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/clicks.html"
01:17:42 INFO - 1500970662617 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/clicks.html"
01:17:42 INFO - 1500970662624 Marionette TRACE 358 <- [1,2,null,{}]
01:17:42 INFO - 1500970662634 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:17:42 INFO - 1500970662637 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0c316406-3136-4d85-acf2-56dc81390b4f","ELEMENT":"0c316406-3136-4d85-acf2-56dc81390b4f"}}]
01:17:42 INFO - 1500970662638 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0c316406-3136-4d85-acf2-56dc81390b4f"}]
01:17:42 INFO - 1500970662681 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/clicks.html"
01:17:42 INFO - 1500970662694 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/clicks.html"
01:17:42 INFO - 1500970662694 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/clicks.html"
01:17:42 INFO - 1500970662707 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/test.html"
01:17:42 INFO - 1500970662711 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/test.html"
01:17:42 INFO - 1500970662713 Marionette TRACE 358 <- [1,4,null,{}]
01:17:42 INFO - 1500970662716 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:17:42 INFO - 1500970662719 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"faf21463-6433-4856-92ed-de7926ca9918","ELEMENT":"faf21463-6433-4856-92ed-de7926ca9918"}}]
01:17:42 INFO - 1500970662724 Marionette TRACE 358 -> [0,6,"getTitle",{}]
01:17:42 INFO - 1500970662725 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
01:17:42 INFO - 1500970662727 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
01:17:42 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500970662730 Marionette TRACE 358 <- [1,7,null,{"value":null}]
01:17:42 INFO - 1500970662731 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
01:17:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 205ms
01:17:42 INFO - TEST-START | test_click.py TestClick.test_container_element
01:17:42 INFO - 1500970662733 Marionette TRACE 358 <- [1,8,null,{}]
01:17:42 INFO - 1500970662734 Marionette DEBUG Closed connection 358
01:17:42 INFO - 1500970662735 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49784
01:17:42 INFO - 1500970662736 Marionette DEBUG Closed connection 359
01:17:42 INFO - 1500970662737 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49785
01:17:42 INFO - 1500970662738 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:42 INFO - 1500970662739 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662742 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662746 Marionette TRACE 360 <- [1,1,null,{"sessionId":"77e7d9c6-0880-4955-8a16-7d5a32f1b170","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662747 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
01:17:42 INFO - 1500970662747 Marionette TRACE 360 <- [1,2,null,{}]
01:17:42 INFO - 1500970662748 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:42 INFO - 1500970662749 Marionette TRACE 360 <- [1,3,null,{}]
01:17:42 INFO - 1500970662750 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:42 INFO - 1500970662750 Marionette TRACE 360 <- [1,4,null,{}]
01:17:42 INFO - 1500970662752 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
01:17:42 INFO - TEST-START: test_click.py TestClick.test_container_element1500970662755 Marionette TRACE 360 <- [1,5,null,{"value":null}]
01:17:42 INFO - 1500970662756 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
01:17:42 INFO - 1500970662757 Marionette TRACE 360 <- [1,6,null,{}]
01:17:42 INFO - 1500970662759 Marionette DEBUG Closed connection 360
01:17:42 INFO - 1500970662759 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49786
01:17:42 INFO - 1500970662760 Marionette DEBUG Closed connection 361
01:17:42 INFO - 1500970662761 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49787
01:17:42 INFO - 1500970662762 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:42 INFO - 1500970662763 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662765 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662769 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5e4d5d30-af6c-43cd-892e-1262f87c24ce","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662770 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
01:17:42 INFO - 1500970662771 Marionette TRACE 362 <- [1,2,null,{}]
01:17:42 INFO - 1500970662774 Marionette DEBUG Closed connection 362
01:17:42 INFO - 1500970662775 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49788
01:17:42 INFO - 1500970662776 Marionette DEBUG Closed connection 363
01:17:42 INFO - 1500970662776 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49789
01:17:42 INFO - 1500970662777 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:42 INFO - 1500970662778 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:42 INFO - 1500970662781 Marionette DEBUG Register listener.js for window 4294967297
01:17:42 INFO - 1500970662785 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3492f1c2-2cf6-4eb2-931e-cf786e3421c5","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:42 INFO - 1500970662786 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:17:42 INFO - 1500970662788 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/test.html"
01:17:42 INFO - 1500970662790 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/test.html"
01:17:42 INFO - 1500970662791 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/test.html"
01:17:42 INFO - 1500970662797 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:42 INFO - 1500970662800 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:42 INFO - 1500970662808 Marionette TRACE 364 <- [1,2,null,{}]
01:17:42 INFO - 1500970662817 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:17:42 INFO - 1500970662819 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0f8f0bf8-ae79-44fd-bfd5-56d21dbf2c0e","ELEMENT":"0f8f0bf8-ae79-44fd-bfd5-56d21dbf2c0e"}}]
01:17:42 INFO - 1500970662821 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0f8f0bf8-ae79-44fd-bfd5-56d21dbf2c0e"}]
01:17:43 INFO - 1500970663073 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:17:43 INFO - 1500970663074 Marionette TRACE 364 <- [1,4,null,{}]
01:17:43 INFO - 1500970663075 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0f8f0bf8-ae79-44fd-bfd5-56d21dbf2c0e","name":"selected"}]
01:17:43 INFO - 1500970663080 Marionette TRACE 364 <- [1,5,null,{"value":true}]
01:17:43 INFO - 1500970663082 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
01:17:43 INFO - TEST-END: test_click.py TestClick.test_container_element1500970663084 Marionette TRACE 364 <- [1,6,null,{"value":null}]
01:17:43 INFO - 1500970663085 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
01:17:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 355ms
01:17:43 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:17:43 INFO - 1500970663087 Marionette TRACE 364 <- [1,7,null,{}]
01:17:43 INFO - 1500970663088 Marionette DEBUG Closed connection 364
01:17:43 INFO - 1500970663089 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49790
01:17:43 INFO - 1500970663090 Marionette DEBUG Closed connection 365
01:17:43 INFO - 1500970663090 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49791
01:17:43 INFO - 1500970663091 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663092 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663095 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663099 Marionette TRACE 366 <- [1,1,null,{"sessionId":"eb0d0b4b-5e1d-4abd-ae0e-26c1c9ca8d08","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663100 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
01:17:43 INFO - 1500970663100 Marionette TRACE 366 <- [1,2,null,{}]
01:17:43 INFO - 1500970663102 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:43 INFO - 1500970663102 Marionette TRACE 366 <- [1,3,null,{}]
01:17:43 INFO - 1500970663104 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:43 INFO - 1500970663104 Marionette TRACE 366 <- [1,4,null,{}]
01:17:43 INFO - 1500970663107 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
01:17:43 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500970663109 Marionette TRACE 366 <- [1,5,null,{"value":null}]
01:17:43 INFO - 1500970663110 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
01:17:43 INFO - 1500970663112 Marionette TRACE 366 <- [1,6,null,{}]
01:17:43 INFO - 1500970663113 Marionette DEBUG Closed connection 366
01:17:43 INFO - 1500970663114 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49792
01:17:43 INFO - 1500970663115 Marionette DEBUG Closed connection 367
01:17:43 INFO - 1500970663115 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49793
01:17:43 INFO - 1500970663117 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663117 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663120 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663123 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1393e8b5-9f89-4a42-8371-e91f7ae03a71","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663125 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
01:17:43 INFO - 1500970663126 Marionette TRACE 368 <- [1,2,null,{}]
01:17:43 INFO - 1500970663128 Marionette DEBUG Closed connection 368
01:17:43 INFO - 1500970663128 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49794
01:17:43 INFO - 1500970663129 Marionette DEBUG Closed connection 369
01:17:43 INFO - 1500970663130 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49795
01:17:43 INFO - 1500970663131 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:43 INFO - 1500970663132 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663134 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663138 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cb7417f4-16a2-4282-bd59-0188e5d6aca4","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663139 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:17:43 INFO - 1500970663141 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663143 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663143 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663150 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663153 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663161 Marionette TRACE 370 <- [1,2,null,{}]
01:17:43 INFO - 1500970663166 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:17:43 INFO - 1500970663168 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46b4e638-03de-46bb-b5bd-f5eba97c3079","ELEMENT":"46b4e638-03de-46bb-b5bd-f5eba97c3079"}}]
01:17:43 INFO - 1500970663174 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"46b4e638-03de-46bb-b5bd-f5eba97c3079"}]
01:17:43 INFO - 1500970663417 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:17:43 INFO - 1500970663418 Marionette TRACE 370 <- [1,4,null,{}]
01:17:43 INFO - 1500970663419 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"46b4e638-03de-46bb-b5bd-f5eba97c3079","name":"selected"}]
01:17:43 INFO - 1500970663424 Marionette TRACE 370 <- [1,5,null,{"value":true}]
01:17:43 INFO - 1500970663426 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
01:17:43 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500970663428 Marionette TRACE 370 <- [1,6,null,{"value":null}]
01:17:43 INFO - 1500970663429 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
01:17:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 343ms
01:17:43 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:17:43 INFO - 1500970663431 Marionette TRACE 370 <- [1,7,null,{}]
01:17:43 INFO - 1500970663432 Marionette DEBUG Closed connection 370
01:17:43 INFO - 1500970663433 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49796
01:17:43 INFO - 1500970663434 Marionette DEBUG Closed connection 371
01:17:43 INFO - 1500970663434 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49797
01:17:43 INFO - 1500970663435 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663436 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663439 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663442 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7aa50fa9-643b-477d-8d17-8706dd58040f","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663444 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
01:17:43 INFO - 1500970663444 Marionette TRACE 372 <- [1,2,null,{}]
01:17:43 INFO - 1500970663445 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:43 INFO - 1500970663446 Marionette TRACE 372 <- [1,3,null,{}]
01:17:43 INFO - 1500970663447 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:43 INFO - 1500970663447 Marionette TRACE 372 <- [1,4,null,{}]
01:17:43 INFO - 1500970663449 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
01:17:43 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500970663452 Marionette TRACE 372 <- [1,5,null,{"value":null}]
01:17:43 INFO - 1500970663453 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
01:17:43 INFO - 1500970663454 Marionette TRACE 372 <- [1,6,null,{}]
01:17:43 INFO - 1500970663456 Marionette DEBUG Closed connection 372
01:17:43 INFO - 1500970663456 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49798
01:17:43 INFO - 1500970663457 Marionette DEBUG Closed connection 373
01:17:43 INFO - 1500970663458 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49799
01:17:43 INFO - 1500970663459 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663460 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663462 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663466 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9996f9f0-ec0b-4674-a1aa-695cb12cc34b","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663467 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
01:17:43 INFO - 1500970663468 Marionette TRACE 374 <- [1,2,null,{}]
01:17:43 INFO - 1500970663470 Marionette DEBUG Closed connection 374
01:17:43 INFO - 1500970663470 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49800
01:17:43 INFO - 1500970663471 Marionette DEBUG Closed connection 375
01:17:43 INFO - 1500970663472 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49801
01:17:43 INFO - 1500970663473 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:43 INFO - 1500970663474 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663476 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663480 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c9c01d7c-c880-4142-a05b-661e83a2ccbc","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663481 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
01:17:43 INFO - 1500970663483 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663485 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663485 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663493 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:17:43 INFO - 1500970663496 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:17:43 INFO - 1500970663500 Marionette TRACE 376 <- [1,2,null,{}]
01:17:43 INFO - 1500970663503 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:17:43 INFO - 1500970663511 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a18435ad-6262-4e69-bf4a-35588db7a2e2","ELEMENT":"a18435ad-6262-4e69-bf4a-35588db7a2e2"}}]
01:17:43 INFO - 1500970663513 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a18435ad-6262-4e69-bf4a-35588db7a2e2"}]
01:17:43 INFO - 1500970663757 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:17:43 INFO - 1500970663758 Marionette TRACE 376 <- [1,4,null,{}]
01:17:43 INFO - 1500970663760 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
01:17:43 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500970663763 Marionette TRACE 376 <- [1,5,null,{"value":null}]
01:17:43 INFO - 1500970663764 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
01:17:43 INFO - 1500970663765 Marionette TRACE 376 <- [1,6,null,{}]
01:17:43 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 334ms
01:17:43 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:17:43 INFO - 1500970663767 Marionette DEBUG Closed connection 376
01:17:43 INFO - 1500970663768 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49802
01:17:43 INFO - 1500970663769 Marionette DEBUG Closed connection 377
01:17:43 INFO - 1500970663769 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49803
01:17:43 INFO - 1500970663771 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663771 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663774 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663778 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9f285bbc-e0b5-48f8-b1d8-eda5a7bd5031","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663779 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
01:17:43 INFO - 1500970663780 Marionette TRACE 378 <- [1,2,null,{}]
01:17:43 INFO - 1500970663780 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:43 INFO - 1500970663781 Marionette TRACE 378 <- [1,3,null,{}]
01:17:43 INFO - 1500970663782 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:43 INFO - 1500970663782 Marionette TRACE 378 <- [1,4,null,{}]
01:17:43 INFO - 1500970663784 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
01:17:43 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500970663787 Marionette TRACE 378 <- [1,5,null,{"value":null}]
01:17:43 INFO - 1500970663787 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
01:17:43 INFO - 1500970663789 Marionette TRACE 378 <- [1,6,null,{}]
01:17:43 INFO - 1500970663791 Marionette DEBUG Closed connection 378
01:17:43 INFO - 1500970663792 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49804
01:17:43 INFO - 1500970663792 Marionette DEBUG Closed connection 379
01:17:43 INFO - 1500970663793 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49805
01:17:43 INFO - 1500970663794 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:43 INFO - 1500970663795 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663797 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663801 Marionette TRACE 380 <- [1,1,null,{"sessionId":"e8cc4c86-a1da-4c0d-83ef-f69b63d90ef2","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663802 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
01:17:43 INFO - 1500970663803 Marionette TRACE 380 <- [1,2,null,{}]
01:17:43 INFO - 1500970663805 Marionette DEBUG Closed connection 380
01:17:43 INFO - 1500970663806 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49806
01:17:43 INFO - 1500970663807 Marionette DEBUG Closed connection 381
01:17:43 INFO - 1500970663807 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49807
01:17:43 INFO - 1500970663808 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:43 INFO - 1500970663809 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:43 INFO - 1500970663812 Marionette DEBUG Register listener.js for window 4294967297
01:17:43 INFO - 1500970663822 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dad4cf27-deed-414d-8c70-df0683b7ffa1","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:43 INFO - 1500970663823 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:17:43 INFO - 1500970663825 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:17:43 INFO - 1500970663827 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:17:43 INFO - 1500970663827 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:17:43 INFO - 1500970663834 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663836 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:43 INFO - 1500970663845 Marionette TRACE 382 <- [1,2,null,{}]
01:17:43 INFO - 1500970663848 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:17:43 INFO - 1500970663851 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d33239bc-b161-43b5-ac67-35728d349036","ELEMENT":"d33239bc-b161-43b5-ac67-35728d349036"}}]
01:17:43 INFO - 1500970663852 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d33239bc-b161-43b5-ac67-35728d349036"}]
01:17:44 INFO - 1500970664095 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:17:44 INFO - 1500970664096 Marionette TRACE 382 <- [1,4,null,{}]
01:17:44 INFO - 1500970664098 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
01:17:44 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500970664101 Marionette TRACE 382 <- [1,5,null,{"value":null}]
01:17:44 INFO - 1500970664102 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
01:17:44 INFO - 1500970664103 Marionette TRACE 382 <- [1,6,null,{}]
01:17:44 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 338ms
01:17:44 INFO - TEST-START | test_click.py TestClick.test_input_file
01:17:44 INFO - 1500970664105 Marionette DEBUG Closed connection 382
01:17:44 INFO - 1500970664106 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49808
01:17:44 INFO - 1500970664107 Marionette DEBUG Closed connection 383
01:17:44 INFO - 1500970664108 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49809
01:17:44 INFO - 1500970664109 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664110 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664113 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664116 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a765549b-2007-492d-8723-684048b8e93b","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664117 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
01:17:44 INFO - 1500970664118 Marionette TRACE 384 <- [1,2,null,{}]
01:17:44 INFO - 1500970664119 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:44 INFO - 1500970664119 Marionette TRACE 384 <- [1,3,null,{}]
01:17:44 INFO - 1500970664120 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:44 INFO - 1500970664121 Marionette TRACE 384 <- [1,4,null,{}]
01:17:44 INFO - 1500970664122 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
01:17:44 INFO - TEST-START: test_click.py TestClick.test_input_file1500970664125 Marionette TRACE 384 <- [1,5,null,{"value":null}]
01:17:44 INFO - 1500970664126 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
01:17:44 INFO - 1500970664127 Marionette TRACE 384 <- [1,6,null,{}]
01:17:44 INFO - 1500970664129 Marionette DEBUG Closed connection 384
01:17:44 INFO - 1500970664130 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49810
01:17:44 INFO - 1500970664130 Marionette DEBUG Closed connection 385
01:17:44 INFO - 1500970664131 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49811
01:17:44 INFO - 1500970664132 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664133 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664136 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664139 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a0de4bd8-eeb1-4a72-8011-166c117a3cf9","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664140 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
01:17:44 INFO - 1500970664141 Marionette TRACE 386 <- [1,2,null,{}]
01:17:44 INFO - 1500970664143 Marionette DEBUG Closed connection 386
01:17:44 INFO - 1500970664143 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49812
01:17:44 INFO - 1500970664144 Marionette DEBUG Closed connection 387
01:17:44 INFO - 1500970664145 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49813
01:17:44 INFO - 1500970664146 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:44 INFO - 1500970664147 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664149 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664153 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ffefaa2a-02ff-451a-a656-270863d81644","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664154 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:17:44 INFO - 1500970664156 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:44 INFO - 1500970664158 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:44 INFO - 1500970664158 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:17:44 INFO - 1500970664164 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:17:44 INFO - 1500970664168 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:17:44 INFO - 1500970664170 Marionette TRACE 388 <- [1,2,null,{}]
01:17:44 INFO - 1500970664178 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:17:44 INFO - 1500970664181 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ed37dcc-8ba2-4b53-ab4d-2c76e3d3a3f4","ELEMENT":"2ed37dcc-8ba2-4b53-ab4d-2c76e3d3a3f4"}}]
01:17:44 INFO - 1500970664182 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2ed37dcc-8ba2-4b53-ab4d-2c76e3d3a3f4"}]
01:17:44 INFO - 1500970664217 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:17:44 INFO - 1500970664219 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
01:17:44 INFO - TEST-END: test_click.py TestClick.test_input_file1500970664221 Marionette TRACE 388 <- [1,5,null,{"value":null}]
01:17:44 INFO - 1500970664222 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
01:17:44 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 119ms
01:17:44 INFO - 1500970664224 Marionette TRACE 388 <- [1,6,null,{}]
01:17:44 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:17:44 INFO - 1500970664226 Marionette DEBUG Closed connection 388
01:17:44 INFO - 1500970664227 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49814
01:17:44 INFO - 1500970664228 Marionette DEBUG Closed connection 389
01:17:44 INFO - 1500970664228 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49815
01:17:44 INFO - 1500970664229 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664230 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664233 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664236 Marionette TRACE 390 <- [1,1,null,{"sessionId":"73274df9-c3ff-4b11-9af0-3adf1eb634f1","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664237 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
01:17:44 INFO - 1500970664238 Marionette TRACE 390 <- [1,2,null,{}]
01:17:44 INFO - 1500970664239 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:44 INFO - 1500970664239 Marionette TRACE 390 <- [1,3,null,{}]
01:17:44 INFO - 1500970664240 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:44 INFO - 1500970664241 Marionette TRACE 390 <- [1,4,null,{}]
01:17:44 INFO - 1500970664242 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
01:17:44 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500970664245 Marionette TRACE 390 <- [1,5,null,{"value":null}]
01:17:44 INFO - 1500970664246 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
01:17:44 INFO - 1500970664248 Marionette TRACE 390 <- [1,6,null,{}]
01:17:44 INFO - 1500970664250 Marionette DEBUG Closed connection 390
01:17:44 INFO - 1500970664250 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49816
01:17:44 INFO - 1500970664251 Marionette DEBUG Closed connection 391
01:17:44 INFO - 1500970664251 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49817
01:17:44 INFO - 1500970664253 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664253 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664256 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664260 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fb0d3469-5448-4519-abca-9bac50b602c0","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664261 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
01:17:44 INFO - 1500970664263 Marionette TRACE 392 <- [1,2,null,{}]
01:17:44 INFO - 1500970664264 Marionette DEBUG Closed connection 392
01:17:44 INFO - 1500970664265 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49818
01:17:44 INFO - 1500970664266 Marionette DEBUG Closed connection 393
01:17:44 INFO - 1500970664266 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49819
01:17:44 INFO - 1500970664268 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:44 INFO - 1500970664268 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664271 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664275 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2e2864a5-31e0-4cf4-8822-228cda5dad4d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664276 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
01:17:44 INFO - 1500970664278 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:17:44 INFO - 1500970664280 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:17:44 INFO - 1500970664280 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:17:44 INFO - 1500970664289 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:17:44 INFO - 1500970664291 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:17:44 INFO - 1500970664297 Marionette TRACE 394 <- [1,2,null,{}]
01:17:44 INFO - 1500970664300 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:17:44 INFO - 1500970664307 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3dba3814-ee6a-4912-a5da-02810efd2c31","ELEMENT":"3dba3814-ee6a-4912-a5da-02810efd2c31"}}]
01:17:44 INFO - 1500970664308 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:17:44 INFO - 1500970664311 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"74c6b53c-a819-4bb3-90b8-fc7192582fdd","ELEMENT":"74c6b53c-a819-4bb3-90b8-fc7192582fdd"}}]
01:17:44 INFO - 1500970664312 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"3dba3814-ee6a-4912-a5da-02810efd2c31"}]
01:17:44 INFO - 1500970664552 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:17:44 INFO - 1500970664553 Marionette TRACE 394 <- [1,5,null,{}]
01:17:44 INFO - 1500970664555 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"74c6b53c-a819-4bb3-90b8-fc7192582fdd"}]
01:17:44 INFO - 1500970664571 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:17:44 INFO - 1500970664573 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:17:44 INFO - 1500970664576 Marionette TRACE 394 <- [1,7,null,{"value":false}]
01:17:44 INFO - 1500970664577 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
01:17:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 357ms
01:17:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500970664580 Marionette TRACE 394 <- [1,8,null,{"value":null}]
01:17:44 INFO - 1500970664581 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
01:17:44 INFO - 1500970664582 Marionette TRACE 394 <- [1,9,null,{}]
01:17:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:17:44 INFO - 1500970664584 Marionette DEBUG Closed connection 394
01:17:44 INFO - 1500970664585 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49820
01:17:44 INFO - 1500970664585 Marionette DEBUG Closed connection 395
01:17:44 INFO - 1500970664586 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49821
01:17:44 INFO - 1500970664587 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664588 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664591 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664594 Marionette TRACE 396 <- [1,1,null,{"sessionId":"69393161-e521-44a1-b4e3-6bf118d59e7d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664596 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
01:17:44 INFO - 1500970664596 Marionette TRACE 396 <- [1,2,null,{}]
01:17:44 INFO - 1500970664597 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:17:44 INFO - 1500970664598 Marionette TRACE 396 <- [1,3,null,{}]
01:17:44 INFO - 1500970664599 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
01:17:44 INFO - 1500970664599 Marionette TRACE 396 <- [1,4,null,{}]
01:17:44 INFO - 1500970664601 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
01:17:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500970664604 Marionette TRACE 396 <- [1,5,null,{"value":null}]
01:17:44 INFO - 1500970664605 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
01:17:44 INFO - 1500970664607 Marionette TRACE 396 <- [1,6,null,{}]
01:17:44 INFO - 1500970664608 Marionette DEBUG Closed connection 396
01:17:44 INFO - 1500970664609 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49822
01:17:44 INFO - 1500970664610 Marionette DEBUG Closed connection 397
01:17:44 INFO - 1500970664611 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49823
01:17:44 INFO - 1500970664612 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:17:44 INFO - 1500970664612 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664615 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664619 Marionette TRACE 398 <- [1,1,null,{"sessionId":"8a42c06c-e13c-42f9-9a35-57d3a9995c13","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664622 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
01:17:44 INFO - 1500970664624 Marionette TRACE 398 <- [1,2,null,{}]
01:17:44 INFO - 1500970664626 Marionette DEBUG Closed connection 398
01:17:44 INFO - 1500970664627 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49824
01:17:44 INFO - 1500970664628 Marionette DEBUG Closed connection 399
01:17:44 INFO - 1500970664628 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49825
01:17:44 INFO - 1500970664630 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:17:44 INFO - 1500970664630 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}
01:17:44 INFO - 1500970664633 Marionette DEBUG Register listener.js for window 4294967297
01:17:44 INFO - 1500970664637 Marionette TRACE 400 <- [1,1,null,{"sessionId":"63c76e5c-ea1a-4370-96da-f77a48b42780","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-283.001\\AppData\\Local\\Temp\\tmpk1yjun.mozrunner","moz:accessibilityChecks":false}}]
01:17:44 INFO - 1500970664639 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
01:17:44 INFO - 1500970664641 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:17:44 INFO - 1500970664643 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:17:44 INFO - 1500970664643 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:17:44 INFO - 1500970664651 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
01:17:44 INFO - 1500970664654 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
01:17:44 INFO - 1500970664663 Marionette TRACE 400 <- [1,2,null,{}]
01:17:44 INFO - 1500970664666 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:17:44 INFO - 1500970664669 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a8647ccb-108a-49cf-891d-7cab40bc8b19","ELEMENT":"a8647ccb-108a-49cf-891d-7cab40bc8b19"}}]
01:17:44 INFO - 1500970664675 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a8647ccb-108a-49cf-891d-7cab40bc8b19"}]
01:17:44 INFO - 1500970664701 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
01:17:44 INFO - 1500970664702 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a8647ccb-108a-49cf-891d-7cab40bc8b19"}]
01:17:44 INFO - 1500970664718 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element