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]
06:44:15 INFO - 1500731055779 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}]
06:44:15 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500731055781 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:44:15 INFO - 1500731055782 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:44:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
06:44:15 INFO - 1500731055783 Marionette TRACE 352 <- [1,6,null,{}]
06:44:15 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:44:15 INFO - 1500731055785 Marionette DEBUG Closed connection 352
06:44:15 INFO - 1500731055786 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49767
06:44:15 INFO - 1500731055786 Marionette DEBUG Closed connection 353
06:44:15 INFO - 1500731055787 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49768
06:44:15 INFO - 1500731055788 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:15 INFO - 1500731055788 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:15 INFO - 1500731055790 Marionette DEBUG Register listener.js for window 15
06:44:15 INFO - 1500731055793 Marionette TRACE 354 <- [1,1,null,{"sessionId":"bb9d35e6-e5bc-4c78-ab1a-d7506ef6703d","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:15 INFO - 1500731055795 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:44:15 INFO - 1500731055796 Marionette TRACE 354 <- [1,2,null,{}]
06:44:15 INFO - 1500731055797 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:15 INFO - 1500731055797 Marionette TRACE 354 <- [1,3,null,{}]
06:44:15 INFO - 1500731055798 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:15 INFO - 1500731055798 Marionette TRACE 354 <- [1,4,null,{}]
06:44:15 INFO - 1500731055799 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}]
06:44:15 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500731055802 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:44:15 INFO - 1500731055802 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:44:15 INFO - 1500731055804 Marionette TRACE 354 <- [1,6,null,{}]
06:44:15 INFO - 1500731055805 Marionette DEBUG Closed connection 354
06:44:15 INFO - 1500731055806 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49769
06:44:15 INFO - 1500731055807 Marionette DEBUG Closed connection 355
06:44:15 INFO - 1500731055807 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49770
06:44:15 INFO - 1500731055808 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:15 INFO - 1500731055808 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:15 INFO - 1500731055811 Marionette DEBUG Register listener.js for window 15
06:44:15 INFO - 1500731055813 Marionette TRACE 356 <- [1,1,null,{"sessionId":"42ce43ea-0582-4710-83a2-42e353ce98df","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:15 INFO - 1500731055815 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:44:15 INFO - 1500731055816 Marionette TRACE 356 <- [1,2,null,{}]
06:44:15 INFO - 1500731055817 Marionette DEBUG Closed connection 356
06:44:15 INFO - 1500731055818 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49771
06:44:15 INFO - 1500731055818 Marionette DEBUG Closed connection 357
06:44:15 INFO - 1500731055819 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49772
06:44:15 INFO - 1500731055820 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:15 INFO - 1500731055820 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:15 INFO - 1500731055822 Marionette DEBUG Register listener.js for window 15
06:44:15 INFO - 1500731055825 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a2339437-5039-4df0-b684-0ef5269053e3","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:15 INFO - 1500731055826 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49339/clicks.html"}]
06:44:15 INFO - 1500731055828 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/hidden.html"
06:44:15 INFO - 1500731055832 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/hidden.html"
06:44:15 INFO - 1500731055832 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/hidden.html"
06:44:15 INFO - 1500731055842 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/clicks.html"
06:44:15 INFO - 1500731055846 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/clicks.html"
06:44:15 INFO - 1500731055854 Marionette TRACE 358 <- [1,2,null,{}]
06:44:15 INFO - 1500731055855 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:44:15 INFO - 1500731055857 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f09f15c8-b067-4587-9eb1-220e92d93140","ELEMENT":"f09f15c8-b067-4587-9eb1-220e92d93140"}}]
06:44:15 INFO - 1500731055858 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f09f15c8-b067-4587-9eb1-220e92d93140"}]
06:44:15 INFO - 1500731055902 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/clicks.html"
06:44:15 INFO - 1500731055907 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/clicks.html"
06:44:15 INFO - 1500731055907 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/clicks.html"
06:44:15 INFO - 1500731055924 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/test.html"
06:44:15 INFO - 1500731055926 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/test.html"
06:44:15 INFO - 1500731055929 Marionette TRACE 358 <- [1,4,null,{}]
06:44:15 INFO - 1500731055938 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:44:15 INFO - 1500731055940 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2457e8f3-9f69-4bd3-8d22-5ed51744e0ab","ELEMENT":"2457e8f3-9f69-4bd3-8d22-5ed51744e0ab"}}]
06:44:15 INFO - 1500731055941 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:44:15 INFO - 1500731055941 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:44:15 INFO - 1500731055942 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}]
06:44:15 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500731055945 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:44:15 INFO - 1500731055947 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:44:15 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 164ms
06:44:15 INFO - TEST-START | test_click.py TestClick.test_container_element
06:44:15 INFO - 1500731055948 Marionette TRACE 358 <- [1,8,null,{}]
06:44:15 INFO - 1500731055950 Marionette DEBUG Closed connection 358
06:44:15 INFO - 1500731055950 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49775
06:44:15 INFO - 1500731055951 Marionette DEBUG Closed connection 359
06:44:15 INFO - 1500731055953 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49776
06:44:15 INFO - 1500731055958 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:15 INFO - 1500731055959 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:15 INFO - 1500731055962 Marionette DEBUG Register listener.js for window 15
06:44:15 INFO - 1500731055965 Marionette TRACE 360 <- [1,1,null,{"sessionId":"be8a60cf-7534-47ea-8e1d-062dac7e0b4c","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:15 INFO - 1500731055967 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:44:15 INFO - 1500731055968 Marionette TRACE 360 <- [1,2,null,{}]
06:44:15 INFO - 1500731055968 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:15 INFO - 1500731055969 Marionette TRACE 360 <- [1,3,null,{}]
06:44:15 INFO - 1500731055969 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:15 INFO - 1500731055970 Marionette TRACE 360 <- [1,4,null,{}]
06:44:15 INFO - 1500731055976 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}]
06:44:15 INFO - TEST-START: test_click.py TestClick.test_container_element1500731055979 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:44:15 INFO - 1500731055982 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:44:15 INFO - 1500731055984 Marionette TRACE 360 <- [1,6,null,{}]
06:44:15 INFO - 1500731055990 Marionette DEBUG Closed connection 360
06:44:15 INFO - 1500731055990 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49777
06:44:15 INFO - 1500731055991 Marionette DEBUG Closed connection 361
06:44:15 INFO - 1500731055991 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49778
06:44:15 INFO - 1500731055992 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:15 INFO - 1500731055993 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:15 INFO - 1500731055995 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731055999 Marionette TRACE 362 <- [1,1,null,{"sessionId":"db58b029-dfdc-46db-b11d-bf3b9ab950ad","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056003 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:44:16 INFO - 1500731056004 Marionette TRACE 362 <- [1,2,null,{}]
06:44:16 INFO - 1500731056005 Marionette DEBUG Closed connection 362
06:44:16 INFO - 1500731056006 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49779
06:44:16 INFO - 1500731056007 Marionette DEBUG Closed connection 363
06:44:16 INFO - 1500731056007 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49780
06:44:16 INFO - 1500731056008 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:16 INFO - 1500731056009 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056011 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056014 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9962351f-313b-4c6e-9772-ad4922deaea6","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056016 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"}]
06:44:16 INFO - 1500731056017 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/test.html"
06:44:16 INFO - 1500731056019 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/test.html"
06:44:16 INFO - 1500731056019 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/test.html"
06:44:16 INFO - 1500731056040 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"
06:44:16 INFO - 1500731056043 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"
06:44:16 INFO - 1500731056046 Marionette TRACE 364 <- [1,2,null,{}]
06:44:16 INFO - 1500731056052 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:44:16 INFO - 1500731056055 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d28dd4a3-45ae-4373-8573-af61507af0c1","ELEMENT":"d28dd4a3-45ae-4373-8573-af61507af0c1"}}]
06:44:16 INFO - 1500731056056 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d28dd4a3-45ae-4373-8573-af61507af0c1"}]
06:44:16 INFO - 1500731056294 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:44:16 INFO - 1500731056294 Marionette TRACE 364 <- [1,4,null,{}]
06:44:16 INFO - 1500731056295 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d28dd4a3-45ae-4373-8573-af61507af0c1","name":"selected"}]
06:44:16 INFO - 1500731056299 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:44:16 INFO - 1500731056301 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}]
06:44:16 INFO - TEST-END: test_click.py TestClick.test_container_element1500731056303 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:44:16 INFO - 1500731056303 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:44:16 INFO - 1500731056304 Marionette TRACE 364 <- [1,7,null,{}]
06:44:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
06:44:16 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:44:16 INFO - 1500731056306 Marionette DEBUG Closed connection 364
06:44:16 INFO - 1500731056308 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49781
06:44:16 INFO - 1500731056308 Marionette DEBUG Closed connection 365
06:44:16 INFO - 1500731056309 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49782
06:44:16 INFO - 1500731056310 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056310 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056312 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056315 Marionette TRACE 366 <- [1,1,null,{"sessionId":"828a7594-1f9b-4b00-ab7f-d26dd3eca1bc","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056317 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:44:16 INFO - 1500731056317 Marionette TRACE 366 <- [1,2,null,{}]
06:44:16 INFO - 1500731056318 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:16 INFO - 1500731056318 Marionette TRACE 366 <- [1,3,null,{}]
06:44:16 INFO - 1500731056319 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:16 INFO - 1500731056321 Marionette TRACE 366 <- [1,4,null,{}]
06:44:16 INFO - 1500731056322 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}]
06:44:16 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500731056324 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:44:16 INFO - 1500731056325 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:44:16 INFO - 1500731056326 Marionette TRACE 366 <- [1,6,null,{}]
06:44:16 INFO - 1500731056328 Marionette DEBUG Closed connection 366
06:44:16 INFO - 1500731056329 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49783
06:44:16 INFO - 1500731056329 Marionette DEBUG Closed connection 367
06:44:16 INFO - 1500731056330 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49784
06:44:16 INFO - 1500731056331 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056332 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056334 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056343 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cf61379b-be51-4a89-82b7-b441e6aab052","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056345 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:44:16 INFO - 1500731056346 Marionette TRACE 368 <- [1,2,null,{}]
06:44:16 INFO - 1500731056348 Marionette DEBUG Closed connection 368
06:44:16 INFO - 1500731056349 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49785
06:44:16 INFO - 1500731056349 Marionette DEBUG Closed connection 369
06:44:16 INFO - 1500731056350 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49786
06:44:16 INFO - 1500731056351 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:16 INFO - 1500731056351 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056353 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056355 Marionette TRACE 370 <- [1,1,null,{"sessionId":"dab86c30-5d21-4899-b9a2-d4d0b82ec621","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056357 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"}]
06:44:16 INFO - 1500731056359 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"
06:44:16 INFO - 1500731056360 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"
06:44:16 INFO - 1500731056360 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"
06:44:16 INFO - 1500731056376 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"
06:44:16 INFO - 1500731056378 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"
06:44:16 INFO - 1500731056387 Marionette TRACE 370 <- [1,2,null,{}]
06:44:16 INFO - 1500731056389 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:44:16 INFO - 1500731056390 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"892319d3-77a3-4f7b-92a4-034f99ec7d26","ELEMENT":"892319d3-77a3-4f7b-92a4-034f99ec7d26"}}]
06:44:16 INFO - 1500731056391 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"892319d3-77a3-4f7b-92a4-034f99ec7d26"}]
06:44:16 INFO - 1500731056630 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:44:16 INFO - 1500731056630 Marionette TRACE 370 <- [1,4,null,{}]
06:44:16 INFO - 1500731056631 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"892319d3-77a3-4f7b-92a4-034f99ec7d26","name":"selected"}]
06:44:16 INFO - 1500731056635 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:44:16 INFO - 1500731056637 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}]
06:44:16 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500731056639 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:44:16 INFO - 1500731056640 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:44:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
06:44:16 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:44:16 INFO - 1500731056641 Marionette TRACE 370 <- [1,7,null,{}]
06:44:16 INFO - 1500731056642 Marionette DEBUG Closed connection 370
06:44:16 INFO - 1500731056643 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49787
06:44:16 INFO - 1500731056645 Marionette DEBUG Closed connection 371
06:44:16 INFO - 1500731056646 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49788
06:44:16 INFO - 1500731056647 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056647 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056650 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056653 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c2a2a2e9-9333-4541-bf04-edb826c0a9f8","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056655 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:44:16 INFO - 1500731056655 Marionette TRACE 372 <- [1,2,null,{}]
06:44:16 INFO - 1500731056656 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:16 INFO - 1500731056657 Marionette TRACE 372 <- [1,3,null,{}]
06:44:16 INFO - 1500731056657 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:16 INFO - 1500731056658 Marionette TRACE 372 <- [1,4,null,{}]
06:44:16 INFO - 1500731056659 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}]
06:44:16 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500731056661 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:44:16 INFO - 1500731056662 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:44:16 INFO - 1500731056663 Marionette TRACE 372 <- [1,6,null,{}]
06:44:16 INFO - 1500731056665 Marionette DEBUG Closed connection 372
06:44:16 INFO - 1500731056665 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49789
06:44:16 INFO - 1500731056666 Marionette DEBUG Closed connection 373
06:44:16 INFO - 1500731056667 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49790
06:44:16 INFO - 1500731056668 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056668 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056670 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056673 Marionette TRACE 374 <- [1,1,null,{"sessionId":"56abb439-9850-4833-ac5d-750ede7ff78a","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056674 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:44:16 INFO - 1500731056675 Marionette TRACE 374 <- [1,2,null,{}]
06:44:16 INFO - 1500731056677 Marionette DEBUG Closed connection 374
06:44:16 INFO - 1500731056677 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49791
06:44:16 INFO - 1500731056678 Marionette DEBUG Closed connection 375
06:44:16 INFO - 1500731056679 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49792
06:44:16 INFO - 1500731056679 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:16 INFO - 1500731056680 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056682 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056684 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e991eac7-c1b5-4fa2-bd46-f2a29d31f41a","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056686 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"}]
06:44:16 INFO - 1500731056687 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"
06:44:16 INFO - 1500731056689 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"
06:44:16 INFO - 1500731056689 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"
06:44:16 INFO - 1500731056706 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"
06:44:16 INFO - 1500731056707 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"
06:44:16 INFO - 1500731056710 Marionette TRACE 376 <- [1,2,null,{}]
06:44:16 INFO - 1500731056717 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:44:16 INFO - 1500731056719 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8bcf77ee-39af-487e-a810-ea421901b044","ELEMENT":"8bcf77ee-39af-487e-a810-ea421901b044"}}]
06:44:16 INFO - 1500731056720 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8bcf77ee-39af-487e-a810-ea421901b044"}]
06:44:16 INFO - 1500731056963 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:44:16 INFO - 1500731056963 Marionette TRACE 376 <- [1,4,null,{}]
06:44:16 INFO - 1500731056965 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}]
06:44:16 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500731056967 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:44:16 INFO - 1500731056968 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:44:16 INFO - 1500731056969 Marionette TRACE 376 <- [1,6,null,{}]
06:44:16 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 328ms
06:44:16 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:44:16 INFO - 1500731056971 Marionette DEBUG Closed connection 376
06:44:16 INFO - 1500731056971 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49793
06:44:16 INFO - 1500731056972 Marionette DEBUG Closed connection 377
06:44:16 INFO - 1500731056973 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49794
06:44:16 INFO - 1500731056974 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056974 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:16 INFO - 1500731056976 Marionette DEBUG Register listener.js for window 15
06:44:16 INFO - 1500731056979 Marionette TRACE 378 <- [1,1,null,{"sessionId":"3f903e53-546d-430f-9496-2b41481cfbd2","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:16 INFO - 1500731056981 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:44:16 INFO - 1500731056981 Marionette TRACE 378 <- [1,2,null,{}]
06:44:16 INFO - 1500731056982 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:16 INFO - 1500731056982 Marionette TRACE 378 <- [1,3,null,{}]
06:44:16 INFO - 1500731056983 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:16 INFO - 1500731056983 Marionette TRACE 378 <- [1,4,null,{}]
06:44:16 INFO - 1500731056985 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}]
06:44:16 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500731056987 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:44:16 INFO - 1500731056988 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:44:16 INFO - 1500731056989 Marionette TRACE 378 <- [1,6,null,{}]
06:44:16 INFO - 1500731056990 Marionette DEBUG Closed connection 378
06:44:16 INFO - 1500731056991 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49795
06:44:16 INFO - 1500731056992 Marionette DEBUG Closed connection 379
06:44:16 INFO - 1500731056992 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49796
06:44:16 INFO - 1500731056993 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:16 INFO - 1500731056994 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731056996 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731056998 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c323e1fd-2114-464b-9548-c8fc7012252e","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057000 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:44:17 INFO - 1500731057001 Marionette TRACE 380 <- [1,2,null,{}]
06:44:17 INFO - 1500731057002 Marionette DEBUG Closed connection 380
06:44:17 INFO - 1500731057003 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49797
06:44:17 INFO - 1500731057003 Marionette DEBUG Closed connection 381
06:44:17 INFO - 1500731057004 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49798
06:44:17 INFO - 1500731057005 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:17 INFO - 1500731057005 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057007 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057009 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e14b9e26-4110-4634-9e83-626872d0ad21","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057011 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"}]
06:44:17 INFO - 1500731057012 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"
06:44:17 INFO - 1500731057014 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"
06:44:17 INFO - 1500731057014 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"
06:44:17 INFO - 1500731057023 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"
06:44:17 INFO - 1500731057027 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"
06:44:17 INFO - 1500731057036 Marionette TRACE 382 <- [1,2,null,{}]
06:44:17 INFO - 1500731057039 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:44:17 INFO - 1500731057041 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab419668-dccc-401e-98d9-768c6864f439","ELEMENT":"ab419668-dccc-401e-98d9-768c6864f439"}}]
06:44:17 INFO - 1500731057042 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ab419668-dccc-401e-98d9-768c6864f439"}]
06:44:17 INFO - 1500731057279 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:44:17 INFO - 1500731057279 Marionette TRACE 382 <- [1,4,null,{}]
06:44:17 INFO - 1500731057281 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}]
06:44:17 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500731057283 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:44:17 INFO - 1500731057284 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:44:17 INFO - 1500731057285 Marionette TRACE 382 <- [1,6,null,{}]
06:44:17 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
06:44:17 INFO - TEST-START | test_click.py TestClick.test_input_file
06:44:17 INFO - 1500731057287 Marionette DEBUG Closed connection 382
06:44:17 INFO - 1500731057288 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49799
06:44:17 INFO - 1500731057288 Marionette DEBUG Closed connection 383
06:44:17 INFO - 1500731057289 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49800
06:44:17 INFO - 1500731057290 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057290 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057292 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057295 Marionette TRACE 384 <- [1,1,null,{"sessionId":"fdf76d0a-ba27-4df8-a182-3518e7779106","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057297 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:44:17 INFO - 1500731057297 Marionette TRACE 384 <- [1,2,null,{}]
06:44:17 INFO - 1500731057298 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:17 INFO - 1500731057299 Marionette TRACE 384 <- [1,3,null,{}]
06:44:17 INFO - 1500731057300 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:17 INFO - 1500731057300 Marionette TRACE 384 <- [1,4,null,{}]
06:44:17 INFO - 1500731057301 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}]
06:44:17 INFO - TEST-START: test_click.py TestClick.test_input_file1500731057303 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:44:17 INFO - 1500731057304 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:44:17 INFO - 1500731057305 Marionette TRACE 384 <- [1,6,null,{}]
06:44:17 INFO - 1500731057307 Marionette DEBUG Closed connection 384
06:44:17 INFO - 1500731057307 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49801
06:44:17 INFO - 1500731057308 Marionette DEBUG Closed connection 385
06:44:17 INFO - 1500731057309 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49802
06:44:17 INFO - 1500731057310 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057310 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057312 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057315 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fa1a601f-ab19-4ff4-bc0f-4cae3efbe1a9","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057317 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:44:17 INFO - 1500731057318 Marionette TRACE 386 <- [1,2,null,{}]
06:44:17 INFO - 1500731057319 Marionette DEBUG Closed connection 386
06:44:17 INFO - 1500731057320 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49803
06:44:17 INFO - 1500731057320 Marionette DEBUG Closed connection 387
06:44:17 INFO - 1500731057321 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49804
06:44:17 INFO - 1500731057322 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:17 INFO - 1500731057323 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057324 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057334 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0b3a5a3e-8e2a-4f7c-a586-8555df0c0851","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057335 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:44:17 INFO - 1500731057337 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"
06:44:17 INFO - 1500731057338 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"
06:44:17 INFO - 1500731057339 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"
06:44:17 INFO - 1500731057356 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:44:17 INFO - 1500731057358 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:44:17 INFO - 1500731057361 Marionette TRACE 388 <- [1,2,null,{}]
06:44:17 INFO - 1500731057367 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:44:17 INFO - 1500731057369 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12c629a1-0b08-425a-8982-55c48c7c83c2","ELEMENT":"12c629a1-0b08-425a-8982-55c48c7c83c2"}}]
06:44:17 INFO - 1500731057370 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"12c629a1-0b08-425a-8982-55c48c7c83c2"}]
06:44:17 INFO - 1500731057395 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]
06:44:17 INFO - 1500731057398 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}]
06:44:17 INFO - TEST-END: test_click.py TestClick.test_input_file1500731057400 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:44:17 INFO - 1500731057409 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:44:17 INFO - 1500731057410 Marionette TRACE 388 <- [1,6,null,{}]
06:44:17 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 125ms
06:44:17 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:44:17 INFO - 1500731057412 Marionette DEBUG Closed connection 388
06:44:17 INFO - 1500731057413 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49805
06:44:17 INFO - 1500731057414 Marionette DEBUG Closed connection 389
06:44:17 INFO - 1500731057415 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49806
06:44:17 INFO - 1500731057416 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057416 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057418 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057421 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fcfd895b-116b-42d4-96a9-9273c0490f8d","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057426 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:44:17 INFO - 1500731057426 Marionette TRACE 390 <- [1,2,null,{}]
06:44:17 INFO - 1500731057427 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:17 INFO - 1500731057427 Marionette TRACE 390 <- [1,3,null,{}]
06:44:17 INFO - 1500731057428 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:17 INFO - 1500731057428 Marionette TRACE 390 <- [1,4,null,{}]
06:44:17 INFO - 1500731057431 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}]
06:44:17 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500731057433 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:44:17 INFO - 1500731057433 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:44:17 INFO - 1500731057435 Marionette TRACE 390 <- [1,6,null,{}]
06:44:17 INFO - 1500731057436 Marionette DEBUG Closed connection 390
06:44:17 INFO - 1500731057437 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49807
06:44:17 INFO - 1500731057437 Marionette DEBUG Closed connection 391
06:44:17 INFO - 1500731057438 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49808
06:44:17 INFO - 1500731057439 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057439 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057441 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057444 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e2fb94e5-d5bc-4375-93c7-f9ad508a448d","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057445 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:44:17 INFO - 1500731057446 Marionette TRACE 392 <- [1,2,null,{}]
06:44:17 INFO - 1500731057448 Marionette DEBUG Closed connection 392
06:44:17 INFO - 1500731057448 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49809
06:44:17 INFO - 1500731057449 Marionette DEBUG Closed connection 393
06:44:17 INFO - 1500731057449 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49810
06:44:17 INFO - 1500731057450 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:17 INFO - 1500731057451 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057453 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057455 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fe2bde55-c3e1-4018-955c-9daa54c47fec","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057457 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"}]
06:44:17 INFO - 1500731057458 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:44:17 INFO - 1500731057460 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:44:17 INFO - 1500731057461 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:44:17 INFO - 1500731057477 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"
06:44:17 INFO - 1500731057479 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"
06:44:17 INFO - 1500731057486 Marionette TRACE 394 <- [1,2,null,{}]
06:44:17 INFO - 1500731057487 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:44:17 INFO - 1500731057489 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"41b9290d-b65d-4d01-9fd5-021258595177","ELEMENT":"41b9290d-b65d-4d01-9fd5-021258595177"}}]
06:44:17 INFO - 1500731057490 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:44:17 INFO - 1500731057496 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b8da780-939a-45ee-af75-4d8710afb174","ELEMENT":"3b8da780-939a-45ee-af75-4d8710afb174"}}]
06:44:17 INFO - 1500731057500 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"41b9290d-b65d-4d01-9fd5-021258595177"}]
06:44:17 INFO - 1500731057741 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:44:17 INFO - 1500731057741 Marionette TRACE 394 <- [1,5,null,{}]
06:44:17 INFO - 1500731057742 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3b8da780-939a-45ee-af75-4d8710afb174"}]
06:44:17 INFO - 1500731057755 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]
06:44:17 INFO - 1500731057757 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:44:17 INFO - 1500731057759 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:44:17 INFO - 1500731057760 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}]
06:44:17 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500731057762 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:44:17 INFO - 1500731057763 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:44:17 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 353ms
06:44:17 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:44:17 INFO - 1500731057764 Marionette TRACE 394 <- [1,9,null,{}]
06:44:17 INFO - 1500731057766 Marionette DEBUG Closed connection 394
06:44:17 INFO - 1500731057767 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49811
06:44:17 INFO - 1500731057768 Marionette DEBUG Closed connection 395
06:44:17 INFO - 1500731057768 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49812
06:44:17 INFO - 1500731057769 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057769 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057771 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057774 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7313bd73-2a11-4ed7-a1d9-b20f4d0edffa","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057775 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:44:17 INFO - 1500731057776 Marionette TRACE 396 <- [1,2,null,{}]
06:44:17 INFO - 1500731057777 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:44:17 INFO - 1500731057777 Marionette TRACE 396 <- [1,3,null,{}]
06:44:17 INFO - 1500731057778 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:44:17 INFO - 1500731057778 Marionette TRACE 396 <- [1,4,null,{}]
06:44:17 INFO - 1500731057780 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}]
06:44:17 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500731057782 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:44:17 INFO - 1500731057783 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:44:17 INFO - 1500731057784 Marionette TRACE 396 <- [1,6,null,{}]
06:44:17 INFO - 1500731057785 Marionette DEBUG Closed connection 396
06:44:17 INFO - 1500731057786 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49813
06:44:17 INFO - 1500731057787 Marionette DEBUG Closed connection 397
06:44:17 INFO - 1500731057787 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49814
06:44:17 INFO - 1500731057788 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:44:17 INFO - 1500731057789 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057791 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057793 Marionette TRACE 398 <- [1,1,null,{"sessionId":"862fcdf7-71dd-4053-b077-750e94804054","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057795 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:44:17 INFO - 1500731057796 Marionette TRACE 398 <- [1,2,null,{}]
06:44:17 INFO - 1500731057797 Marionette DEBUG Closed connection 398
06:44:17 INFO - 1500731057798 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49815
06:44:17 INFO - 1500731057799 Marionette DEBUG Closed connection 399
06:44:17 INFO - 1500731057799 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49816
06:44:17 INFO - 1500731057800 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:44:17 INFO - 1500731057801 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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}
06:44:17 INFO - 1500731057803 Marionette DEBUG Register listener.js for window 15
06:44:17 INFO - 1500731057805 Marionette TRACE 400 <- [1,1,null,{"sessionId":"13d477a6-9ac0-4e62-8b34-9efd4f61fb05","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":2520,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpz2sywz.mozrunner","moz:accessibilityChecks":false}}]
06:44:17 INFO - 1500731057807 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"}]
06:44:17 INFO - 1500731057808 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"
06:44:17 INFO - 1500731057810 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"
06:44:17 INFO - 1500731057810 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"
06:44:17 INFO - 1500731057829 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"
06:44:17 INFO - 1500731057830 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"
06:44:17 INFO - 1500731057833 Marionette TRACE 400 <- [1,2,null,{}]
06:44:17 INFO - 1500731057834 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:44:17 INFO - 1500731057836 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3c240c1-f3ff-42b3-a333-1b71796087c1","ELEMENT":"b3c240c1-f3ff-42b3-a333-1b71796087c1"}}]
06:44:17 INFO - 1500731057837 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b3c240c1-f3ff-42b3-a333-1b71796087c1"}]
06:44:17 INFO - 1500731057864 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:44:17 INFO - 1500731057865 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b3c240c1-f3ff-42b3-a333-1b71796087c1"}]
06:44:17 INFO - 1500731057882 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element