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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:21:07 INFO - 1499952067884 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:21:07 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499952067887 Marionette TRACE 353 <- [1,5,null,{"value":null}]
06:21:07 INFO - 1499952067888 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
06:21:07 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 118ms
06:21:07 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:21:07 INFO - 1499952067890 Marionette TRACE 353 <- [1,6,null,{}]
06:21:07 INFO - 1499952067891 Marionette DEBUG Closed connection 353
06:21:07 INFO - 1499952067892 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50055
06:21:07 INFO - 1499952067893 Marionette DEBUG Closed connection 354
06:21:07 INFO - 1499952067894 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50056
06:21:07 INFO - 1499952067895 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:07 INFO - 1499952067896 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:07 INFO - 1499952067898 Marionette DEBUG Register listener.js for window 4294967297
06:21:07 INFO - 1499952067902 Marionette TRACE 355 <- [1,1,null,{"sessionId":"ff925b53-c65e-4cd2-be52-84863a7ecc01","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:07 INFO - 1499952067903 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
06:21:07 INFO - 1499952067903 Marionette TRACE 355 <- [1,2,null,{}]
06:21:07 INFO - 1499952067904 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:07 INFO - 1499952067905 Marionette TRACE 355 <- [1,3,null,{}]
06:21:07 INFO - 1499952067905 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:07 INFO - 1499952067906 Marionette TRACE 355 <- [1,4,null,{}]
06:21:07 INFO - 1499952067908 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:21:07 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499952067910 Marionette TRACE 355 <- [1,5,null,{"value":null}]
06:21:07 INFO - 1499952067911 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
06:21:07 INFO - 1499952067913 Marionette TRACE 355 <- [1,6,null,{}]
06:21:07 INFO - 1499952067914 Marionette DEBUG Closed connection 355
06:21:07 INFO - 1499952067914 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50057
06:21:07 INFO - 1499952067915 Marionette DEBUG Closed connection 356
06:21:07 INFO - 1499952067916 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50058
06:21:07 INFO - 1499952067917 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:07 INFO - 1499952067917 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:07 INFO - 1499952067919 Marionette DEBUG Register listener.js for window 4294967297
06:21:07 INFO - 1499952067922 Marionette TRACE 357 <- [1,1,null,{"sessionId":"6c660208-d29e-4a03-9e47-a0a7c7356453","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:07 INFO - 1499952067923 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
06:21:07 INFO - 1499952067924 Marionette TRACE 357 <- [1,2,null,{}]
06:21:07 INFO - 1499952067926 Marionette DEBUG Closed connection 357
06:21:07 INFO - 1499952067926 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50059
06:21:07 INFO - 1499952067927 Marionette DEBUG Closed connection 358
06:21:07 INFO - 1499952067928 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50060
06:21:07 INFO - 1499952067929 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:07 INFO - 1499952067929 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:07 INFO - 1499952067931 Marionette DEBUG Register listener.js for window 4294967297
06:21:07 INFO - 1499952067943 Marionette TRACE 359 <- [1,1,null,{"sessionId":"3d2d4083-af51-4d33-a20b-2fe3fa06240b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:07 INFO - 1499952067944 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49609/clicks.html"}]
06:21:07 INFO - 1499952067946 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49609/hidden.html"
06:21:07 INFO - 1499952067953 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49609/hidden.html"
06:21:07 INFO - 1499952067954 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49609/hidden.html"
06:21:07 INFO - 1499952067960 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49609/clicks.html"
06:21:07 INFO - 1499952067963 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49609/clicks.html"
06:21:07 INFO - 1499952067967 Marionette TRACE 359 <- [1,2,null,{}]
06:21:07 INFO - 1499952067976 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:21:07 INFO - 1499952067979 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79095f37-e167-45c3-b3df-1a18420dec05","ELEMENT":"79095f37-e167-45c3-b3df-1a18420dec05"}}]
06:21:07 INFO - 1499952067980 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"79095f37-e167-45c3-b3df-1a18420dec05"}]
06:21:08 INFO - 1499952068018 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49609/clicks.html"
06:21:08 INFO - 1499952068028 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49609/clicks.html"
06:21:08 INFO - 1499952068029 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49609/clicks.html"
06:21:08 INFO - 1499952068035 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49609/test.html"
06:21:08 INFO - 1499952068038 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49609/test.html"
06:21:08 INFO - 1499952068043 Marionette TRACE 359 <- [1,4,null,{}]
06:21:08 INFO - 1499952068047 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:21:08 INFO - 1499952068066 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9fae5828-454a-4bde-9bed-39e8c53ef70d","ELEMENT":"9fae5828-454a-4bde-9bed-39e8c53ef70d"}}]
06:21:08 INFO - 1499952068072 Marionette TRACE 359 -> [0,6,"getTitle",{}]
06:21:08 INFO - 1499952068072 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
06:21:08 INFO - 1499952068075 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:21:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 197ms
06:21:08 INFO - TEST-START | test_click.py TestClick.test_container_element
06:21:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499952068085 Marionette TRACE 359 <- [1,7,null,{"value":null}]
06:21:08 INFO - 1499952068086 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
06:21:08 INFO - 1499952068087 Marionette TRACE 359 <- [1,8,null,{}]
06:21:08 INFO - 1499952068088 Marionette DEBUG Closed connection 359
06:21:08 INFO - 1499952068089 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50063
06:21:08 INFO - 1499952068091 Marionette DEBUG Closed connection 360
06:21:08 INFO - 1499952068091 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50064
06:21:08 INFO - 1499952068097 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068098 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068100 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068105 Marionette TRACE 361 <- [1,1,null,{"sessionId":"6ea5707e-7be8-4d5c-a830-ce9f741bafb1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068107 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
06:21:08 INFO - 1499952068108 Marionette TRACE 361 <- [1,2,null,{}]
06:21:08 INFO - 1499952068109 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:08 INFO - 1499952068110 Marionette TRACE 361 <- [1,3,null,{}]
06:21:08 INFO - 1499952068111 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:08 INFO - 1499952068111 Marionette TRACE 361 <- [1,4,null,{}]
06:21:08 INFO - 1499952068113 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:21:08 INFO - TEST-START: test_click.py TestClick.test_container_element1499952068115 Marionette TRACE 361 <- [1,5,null,{"value":null}]
06:21:08 INFO - 1499952068116 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
06:21:08 INFO - 1499952068117 Marionette TRACE 361 <- [1,6,null,{}]
06:21:08 INFO - 1499952068118 Marionette DEBUG Closed connection 361
06:21:08 INFO - 1499952068119 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50065
06:21:08 INFO - 1499952068120 Marionette DEBUG Closed connection 362
06:21:08 INFO - 1499952068121 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50066
06:21:08 INFO - 1499952068122 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068122 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068124 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068127 Marionette TRACE 363 <- [1,1,null,{"sessionId":"582ce531-8a27-4d45-912b-653494c549df","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068128 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
06:21:08 INFO - 1499952068129 Marionette TRACE 363 <- [1,2,null,{}]
06:21:08 INFO - 1499952068130 Marionette DEBUG Closed connection 363
06:21:08 INFO - 1499952068131 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50067
06:21:08 INFO - 1499952068131 Marionette DEBUG Closed connection 364
06:21:08 INFO - 1499952068132 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50068
06:21:08 INFO - 1499952068133 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:08 INFO - 1499952068134 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068136 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068139 Marionette TRACE 365 <- [1,1,null,{"sessionId":"1bdc9a7a-b506-45b5-a96b-493ef47d524b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068140 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:21:08 INFO - 1499952068141 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49609/test.html"
06:21:08 INFO - 1499952068143 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49609/test.html"
06:21:08 INFO - 1499952068143 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49609/test.html"
06:21:08 INFO - 1499952068149 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:21:08 INFO - 1499952068152 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:21:08 INFO - 1499952068155 Marionette TRACE 365 <- [1,2,null,{}]
06:21:08 INFO - 1499952068164 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:21:08 INFO - 1499952068167 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"62162d13-6dfc-4e37-860c-dbef1c60c5c0","ELEMENT":"62162d13-6dfc-4e37-860c-dbef1c60c5c0"}}]
06:21:08 INFO - 1499952068168 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"62162d13-6dfc-4e37-860c-dbef1c60c5c0"}]
06:21:08 INFO - 1499952068413 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:21:08 INFO - 1499952068413 Marionette TRACE 365 <- [1,4,null,{}]
06:21:08 INFO - 1499952068415 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"62162d13-6dfc-4e37-860c-dbef1c60c5c0","name":"selected"}]
06:21:08 INFO - 1499952068419 Marionette TRACE 365 <- [1,5,null,{"value":true}]
06:21:08 INFO - 1499952068421 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:21:08 INFO - TEST-END: test_click.py TestClick.test_container_element1499952068423 Marionette TRACE 365 <- [1,6,null,{"value":null}]
06:21:08 INFO - 1499952068424 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
06:21:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 338ms
06:21:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:21:08 INFO - 1499952068426 Marionette TRACE 365 <- [1,7,null,{}]
06:21:08 INFO - 1499952068427 Marionette DEBUG Closed connection 365
06:21:08 INFO - 1499952068428 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50069
06:21:08 INFO - 1499952068429 Marionette DEBUG Closed connection 366
06:21:08 INFO - 1499952068429 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50070
06:21:08 INFO - 1499952068430 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068431 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068433 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068436 Marionette TRACE 367 <- [1,1,null,{"sessionId":"0971137b-b646-4f41-9136-4b966788c44f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068437 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
06:21:08 INFO - 1499952068437 Marionette TRACE 367 <- [1,2,null,{}]
06:21:08 INFO - 1499952068438 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:08 INFO - 1499952068439 Marionette TRACE 367 <- [1,3,null,{}]
06:21:08 INFO - 1499952068440 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:08 INFO - 1499952068440 Marionette TRACE 367 <- [1,4,null,{}]
06:21:08 INFO - 1499952068442 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:21:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499952068444 Marionette TRACE 367 <- [1,5,null,{"value":null}]
06:21:08 INFO - 1499952068445 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
06:21:08 INFO - 1499952068446 Marionette TRACE 367 <- [1,6,null,{}]
06:21:08 INFO - 1499952068448 Marionette DEBUG Closed connection 367
06:21:08 INFO - 1499952068448 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50071
06:21:08 INFO - 1499952068449 Marionette DEBUG Closed connection 368
06:21:08 INFO - 1499952068450 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50072
06:21:08 INFO - 1499952068451 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068451 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068453 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068456 Marionette TRACE 369 <- [1,1,null,{"sessionId":"06ffca8d-f039-452b-bffb-af5e396b30f8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068457 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
06:21:08 INFO - 1499952068458 Marionette TRACE 369 <- [1,2,null,{}]
06:21:08 INFO - 1499952068460 Marionette DEBUG Closed connection 369
06:21:08 INFO - 1499952068461 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50073
06:21:08 INFO - 1499952068461 Marionette DEBUG Closed connection 370
06:21:08 INFO - 1499952068462 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50074
06:21:08 INFO - 1499952068463 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:08 INFO - 1499952068464 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068466 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068469 Marionette TRACE 371 <- [1,1,null,{"sessionId":"31693d7a-e4f2-41f8-b294-af2a44386fa6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068470 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:21:08 INFO - 1499952068472 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:21:08 INFO - 1499952068473 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:21:08 INFO - 1499952068474 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:21:08 INFO - 1499952068479 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:21:08 INFO - 1499952068482 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:21:08 INFO - 1499952068499 Marionette TRACE 371 <- [1,2,null,{}]
06:21:08 INFO - 1499952068511 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:21:08 INFO - 1499952068515 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"76173abd-5c69-4bc6-a69e-e0ebb5c0e201","ELEMENT":"76173abd-5c69-4bc6-a69e-e0ebb5c0e201"}}]
06:21:08 INFO - 1499952068516 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"76173abd-5c69-4bc6-a69e-e0ebb5c0e201"}]
06:21:08 INFO - 1499952068761 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:21:08 INFO - 1499952068762 Marionette TRACE 371 <- [1,4,null,{}]
06:21:08 INFO - 1499952068763 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"76173abd-5c69-4bc6-a69e-e0ebb5c0e201","name":"selected"}]
06:21:08 INFO - 1499952068767 Marionette TRACE 371 <- [1,5,null,{"value":true}]
06:21:08 INFO - 1499952068769 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:21:08 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499952068771 Marionette TRACE 371 <- [1,6,null,{"value":null}]
06:21:08 INFO - 1499952068772 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
06:21:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 347ms
06:21:08 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:21:08 INFO - 1499952068774 Marionette TRACE 371 <- [1,7,null,{}]
06:21:08 INFO - 1499952068775 Marionette DEBUG Closed connection 371
06:21:08 INFO - 1499952068776 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50075
06:21:08 INFO - 1499952068777 Marionette DEBUG Closed connection 372
06:21:08 INFO - 1499952068777 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50076
06:21:08 INFO - 1499952068778 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068779 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068781 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068784 Marionette TRACE 373 <- [1,1,null,{"sessionId":"a9361135-8d93-416e-880e-608b141be403","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068785 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
06:21:08 INFO - 1499952068785 Marionette TRACE 373 <- [1,2,null,{}]
06:21:08 INFO - 1499952068786 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:08 INFO - 1499952068787 Marionette TRACE 373 <- [1,3,null,{}]
06:21:08 INFO - 1499952068788 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:08 INFO - 1499952068788 Marionette TRACE 373 <- [1,4,null,{}]
06:21:08 INFO - 1499952068790 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:21:08 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499952068793 Marionette TRACE 373 <- [1,5,null,{"value":null}]
06:21:08 INFO - 1499952068793 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
06:21:08 INFO - 1499952068795 Marionette TRACE 373 <- [1,6,null,{}]
06:21:08 INFO - 1499952068796 Marionette DEBUG Closed connection 373
06:21:08 INFO - 1499952068797 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50077
06:21:08 INFO - 1499952068797 Marionette DEBUG Closed connection 374
06:21:08 INFO - 1499952068798 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50078
06:21:08 INFO - 1499952068799 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:08 INFO - 1499952068799 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068801 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068804 Marionette TRACE 375 <- [1,1,null,{"sessionId":"2ffe46bc-ec78-4c0d-a85f-a98b3c5307d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068805 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
06:21:08 INFO - 1499952068806 Marionette TRACE 375 <- [1,2,null,{}]
06:21:08 INFO - 1499952068808 Marionette DEBUG Closed connection 375
06:21:08 INFO - 1499952068809 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50079
06:21:08 INFO - 1499952068810 Marionette DEBUG Closed connection 376
06:21:08 INFO - 1499952068810 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50080
06:21:08 INFO - 1499952068811 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:08 INFO - 1499952068812 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:08 INFO - 1499952068814 Marionette DEBUG Register listener.js for window 4294967297
06:21:08 INFO - 1499952068817 Marionette TRACE 377 <- [1,1,null,{"sessionId":"671f9f55-fbd1-4d8d-9164-3577e47ceae9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:08 INFO - 1499952068818 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:21:08 INFO - 1499952068819 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:21:08 INFO - 1499952068823 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:21:08 INFO - 1499952068823 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:21:08 INFO - 1499952068830 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:21:08 INFO - 1499952068834 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:21:08 INFO - 1499952068835 Marionette TRACE 377 <- [1,2,null,{}]
06:21:08 INFO - 1499952068845 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:21:08 INFO - 1499952068849 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"565063d8-f019-4022-978a-006d382f6dac","ELEMENT":"565063d8-f019-4022-978a-006d382f6dac"}}]
06:21:08 INFO - 1499952068850 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"565063d8-f019-4022-978a-006d382f6dac"}]
06:21:09 INFO - 1499952069086 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:21:09 INFO - 1499952069087 Marionette TRACE 377 <- [1,4,null,{}]
06:21:09 INFO - 1499952069088 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:21:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499952069091 Marionette TRACE 377 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069092 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
06:21:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 319ms
06:21:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:21:09 INFO - 1499952069094 Marionette TRACE 377 <- [1,6,null,{}]
06:21:09 INFO - 1499952069095 Marionette DEBUG Closed connection 377
06:21:09 INFO - 1499952069096 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50081
06:21:09 INFO - 1499952069097 Marionette DEBUG Closed connection 378
06:21:09 INFO - 1499952069097 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50082
06:21:09 INFO - 1499952069098 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069099 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069101 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069104 Marionette TRACE 379 <- [1,1,null,{"sessionId":"1308277f-8f40-48d5-b5f4-06ea97f75d5f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069105 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
06:21:09 INFO - 1499952069105 Marionette TRACE 379 <- [1,2,null,{}]
06:21:09 INFO - 1499952069106 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:09 INFO - 1499952069107 Marionette TRACE 379 <- [1,3,null,{}]
06:21:09 INFO - 1499952069107 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:09 INFO - 1499952069108 Marionette TRACE 379 <- [1,4,null,{}]
06:21:09 INFO - 1499952069110 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:21:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499952069112 Marionette TRACE 379 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069113 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
06:21:09 INFO - 1499952069114 Marionette TRACE 379 <- [1,6,null,{}]
06:21:09 INFO - 1499952069116 Marionette DEBUG Closed connection 379
06:21:09 INFO - 1499952069116 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50083
06:21:09 INFO - 1499952069117 Marionette DEBUG Closed connection 380
06:21:09 INFO - 1499952069118 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50084
06:21:09 INFO - 1499952069119 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069119 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069121 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069124 Marionette TRACE 381 <- [1,1,null,{"sessionId":"9d6d822d-e96a-4765-b9c6-8cab41b3db6c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069125 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
06:21:09 INFO - 1499952069126 Marionette TRACE 381 <- [1,2,null,{}]
06:21:09 INFO - 1499952069127 Marionette DEBUG Closed connection 381
06:21:09 INFO - 1499952069128 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50085
06:21:09 INFO - 1499952069128 Marionette DEBUG Closed connection 382
06:21:09 INFO - 1499952069129 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50086
06:21:09 INFO - 1499952069130 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:09 INFO - 1499952069131 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069133 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069135 Marionette TRACE 383 <- [1,1,null,{"sessionId":"0fb7e6e1-37ab-4fe1-a54a-14c86a0ebfc2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069136 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:21:09 INFO - 1499952069139 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:21:09 INFO - 1499952069140 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:21:09 INFO - 1499952069141 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:21:09 INFO - 1499952069146 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:21:09 INFO - 1499952069149 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:21:09 INFO - 1499952069151 Marionette TRACE 383 <- [1,2,null,{}]
06:21:09 INFO - 1499952069154 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:21:09 INFO - 1499952069160 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05c13c0a-a7bd-4530-9dd9-aa3b74a0f07d","ELEMENT":"05c13c0a-a7bd-4530-9dd9-aa3b74a0f07d"}}]
06:21:09 INFO - 1499952069162 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"05c13c0a-a7bd-4530-9dd9-aa3b74a0f07d"}]
06:21:09 INFO - 1499952069405 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:21:09 INFO - 1499952069406 Marionette TRACE 383 <- [1,4,null,{}]
06:21:09 INFO - 1499952069408 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:21:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499952069410 Marionette TRACE 383 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069411 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
06:21:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 318ms
06:21:09 INFO - TEST-START | test_click.py TestClick.test_input_file
06:21:09 INFO - 1499952069413 Marionette TRACE 383 <- [1,6,null,{}]
06:21:09 INFO - 1499952069414 Marionette DEBUG Closed connection 383
06:21:09 INFO - 1499952069415 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50087
06:21:09 INFO - 1499952069416 Marionette DEBUG Closed connection 384
06:21:09 INFO - 1499952069417 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50088
06:21:09 INFO - 1499952069418 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069418 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069420 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069423 Marionette TRACE 385 <- [1,1,null,{"sessionId":"e58fc9d4-22b6-43a5-a7f2-1b011b5fe2c7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069424 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
06:21:09 INFO - 1499952069425 Marionette TRACE 385 <- [1,2,null,{}]
06:21:09 INFO - 1499952069426 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:09 INFO - 1499952069426 Marionette TRACE 385 <- [1,3,null,{}]
06:21:09 INFO - 1499952069427 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:09 INFO - 1499952069427 Marionette TRACE 385 <- [1,4,null,{}]
06:21:09 INFO - 1499952069429 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:21:09 INFO - TEST-START: test_click.py TestClick.test_input_file1499952069432 Marionette TRACE 385 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069433 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
06:21:09 INFO - 1499952069434 Marionette TRACE 385 <- [1,6,null,{}]
06:21:09 INFO - 1499952069435 Marionette DEBUG Closed connection 385
06:21:09 INFO - 1499952069436 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50089
06:21:09 INFO - 1499952069436 Marionette DEBUG Closed connection 386
06:21:09 INFO - 1499952069437 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50090
06:21:09 INFO - 1499952069438 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069439 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069441 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069444 Marionette TRACE 387 <- [1,1,null,{"sessionId":"0f010cca-dd5c-4239-bfaf-366aed09a1a5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069445 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
06:21:09 INFO - 1499952069446 Marionette TRACE 387 <- [1,2,null,{}]
06:21:09 INFO - 1499952069447 Marionette DEBUG Closed connection 387
06:21:09 INFO - 1499952069448 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50091
06:21:09 INFO - 1499952069448 Marionette DEBUG Closed connection 388
06:21:09 INFO - 1499952069449 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50092
06:21:09 INFO - 1499952069450 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:09 INFO - 1499952069451 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069453 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069455 Marionette TRACE 389 <- [1,1,null,{"sessionId":"3df1dd23-83ea-4091-8da3-e5b80afe0b65","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069456 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:21:09 INFO - 1499952069458 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:21:09 INFO - 1499952069460 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:21:09 INFO - 1499952069461 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:21:09 INFO - 1499952069466 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:21:09 INFO - 1499952069470 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:21:09 INFO - 1499952069471 Marionette TRACE 389 <- [1,2,null,{}]
06:21:09 INFO - 1499952069486 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:21:09 INFO - 1499952069492 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"698bcf06-f4ac-4f99-b1c9-024f716b9664","ELEMENT":"698bcf06-f4ac-4f99-b1c9-024f716b9664"}}]
06:21:09 INFO - 1499952069493 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"698bcf06-f4ac-4f99-b1c9-024f716b9664"}]
06:21:09 INFO - 1499952069518 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:21:09 INFO - 1499952069520 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:21:09 INFO - TEST-END: test_click.py TestClick.test_input_file1499952069523 Marionette TRACE 389 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069523 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
06:21:09 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 111ms
06:21:09 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:21:09 INFO - 1499952069525 Marionette TRACE 389 <- [1,6,null,{}]
06:21:09 INFO - 1499952069526 Marionette DEBUG Closed connection 389
06:21:09 INFO - 1499952069527 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50093
06:21:09 INFO - 1499952069528 Marionette DEBUG Closed connection 390
06:21:09 INFO - 1499952069528 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50094
06:21:09 INFO - 1499952069529 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069530 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069532 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069535 Marionette TRACE 391 <- [1,1,null,{"sessionId":"0a75dad3-7ae1-4ee6-9059-c10974c4bda2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069536 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
06:21:09 INFO - 1499952069537 Marionette TRACE 391 <- [1,2,null,{}]
06:21:09 INFO - 1499952069538 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:09 INFO - 1499952069538 Marionette TRACE 391 <- [1,3,null,{}]
06:21:09 INFO - 1499952069539 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:09 INFO - 1499952069539 Marionette TRACE 391 <- [1,4,null,{}]
06:21:09 INFO - 1499952069541 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:21:09 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499952069543 Marionette TRACE 391 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069544 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
06:21:09 INFO - 1499952069545 Marionette TRACE 391 <- [1,6,null,{}]
06:21:09 INFO - 1499952069547 Marionette DEBUG Closed connection 391
06:21:09 INFO - 1499952069547 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50095
06:21:09 INFO - 1499952069548 Marionette DEBUG Closed connection 392
06:21:09 INFO - 1499952069549 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50096
06:21:09 INFO - 1499952069550 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069550 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069552 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069555 Marionette TRACE 393 <- [1,1,null,{"sessionId":"39db2b3c-2aa6-447d-9ebb-e313fd38a626","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069556 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
06:21:09 INFO - 1499952069557 Marionette TRACE 393 <- [1,2,null,{}]
06:21:09 INFO - 1499952069559 Marionette DEBUG Closed connection 393
06:21:09 INFO - 1499952069559 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50097
06:21:09 INFO - 1499952069560 Marionette DEBUG Closed connection 394
06:21:09 INFO - 1499952069561 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50098
06:21:09 INFO - 1499952069561 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:09 INFO - 1499952069562 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069564 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069567 Marionette TRACE 395 <- [1,1,null,{"sessionId":"626ae87b-0a45-470e-8752-2020d681e987","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069568 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:21:09 INFO - 1499952069570 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:21:09 INFO - 1499952069572 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:21:09 INFO - 1499952069572 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:21:09 INFO - 1499952069579 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:21:09 INFO - 1499952069581 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:21:09 INFO - 1499952069583 Marionette TRACE 395 <- [1,2,null,{}]
06:21:09 INFO - 1499952069585 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:21:09 INFO - 1499952069587 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e30fe36e-04be-46a8-85ba-d4d75b374f28","ELEMENT":"e30fe36e-04be-46a8-85ba-d4d75b374f28"}}]
06:21:09 INFO - 1499952069595 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:21:09 INFO - 1499952069597 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4379733a-552f-4f13-9fd8-4c0f955751ed","ELEMENT":"4379733a-552f-4f13-9fd8-4c0f955751ed"}}]
06:21:09 INFO - 1499952069598 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"e30fe36e-04be-46a8-85ba-d4d75b374f28"}]
06:21:09 INFO - 1499952069834 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:21:09 INFO - 1499952069835 Marionette TRACE 395 <- [1,5,null,{}]
06:21:09 INFO - 1499952069836 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"4379733a-552f-4f13-9fd8-4c0f955751ed"}]
06:21:09 INFO - 1499952069850 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
06:21:09 INFO - 1499952069851 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:21:09 INFO - 1499952069854 Marionette TRACE 395 <- [1,7,null,{"value":false}]
06:21:09 INFO - 1499952069856 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:21:09 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499952069858 Marionette TRACE 395 <- [1,8,null,{"value":null}]
06:21:09 INFO - 1499952069859 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
06:21:09 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
06:21:09 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:21:09 INFO - 1499952069861 Marionette TRACE 395 <- [1,9,null,{}]
06:21:09 INFO - 1499952069862 Marionette DEBUG Closed connection 395
06:21:09 INFO - 1499952069863 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50099
06:21:09 INFO - 1499952069863 Marionette DEBUG Closed connection 396
06:21:09 INFO - 1499952069864 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50100
06:21:09 INFO - 1499952069865 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069866 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069868 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069879 Marionette TRACE 397 <- [1,1,null,{"sessionId":"ac9f4597-3e6f-4ac3-a7b2-1fab17b9e685","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069880 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
06:21:09 INFO - 1499952069880 Marionette TRACE 397 <- [1,2,null,{}]
06:21:09 INFO - 1499952069881 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:21:09 INFO - 1499952069882 Marionette TRACE 397 <- [1,3,null,{}]
06:21:09 INFO - 1499952069883 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
06:21:09 INFO - 1499952069883 Marionette TRACE 397 <- [1,4,null,{}]
06:21:09 INFO - 1499952069885 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:21:09 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499952069888 Marionette TRACE 397 <- [1,5,null,{"value":null}]
06:21:09 INFO - 1499952069889 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
06:21:09 INFO - 1499952069890 Marionette TRACE 397 <- [1,6,null,{}]
06:21:09 INFO - 1499952069891 Marionette DEBUG Closed connection 397
06:21:09 INFO - 1499952069892 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50101
06:21:09 INFO - 1499952069892 Marionette DEBUG Closed connection 398
06:21:09 INFO - 1499952069893 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50102
06:21:09 INFO - 1499952069894 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:21:09 INFO - 1499952069895 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069897 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069900 Marionette TRACE 399 <- [1,1,null,{"sessionId":"836b6224-75e4-4eb7-ae7f-d9cbb06a5869","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069901 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
06:21:09 INFO - 1499952069902 Marionette TRACE 399 <- [1,2,null,{}]
06:21:09 INFO - 1499952069903 Marionette DEBUG Closed connection 399
06:21:09 INFO - 1499952069904 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50103
06:21:09 INFO - 1499952069904 Marionette DEBUG Closed connection 400
06:21:09 INFO - 1499952069905 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50104
06:21:09 INFO - 1499952069906 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:21:09 INFO - 1499952069907 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}
06:21:09 INFO - 1499952069909 Marionette DEBUG Register listener.js for window 4294967297
06:21:09 INFO - 1499952069911 Marionette TRACE 401 <- [1,1,null,{"sessionId":"59109638-ebee-4276-9c05-c95a85a743b4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3572,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpafxoqh.mozrunner","moz:accessibilityChecks":false}}]
06:21:09 INFO - 1499952069912 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:21:09 INFO - 1499952069914 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:21:09 INFO - 1499952069916 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:21:09 INFO - 1499952069916 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:21:09 INFO - 1499952069924 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:21:09 INFO - 1499952069925 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:21:09 INFO - 1499952069932 Marionette TRACE 401 <- [1,2,null,{}]
06:21:09 INFO - 1499952069935 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:21:09 INFO - 1499952069937 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0db18370-7648-4fb0-b5c7-5e21392e6e5b","ELEMENT":"0db18370-7648-4fb0-b5c7-5e21392e6e5b"}}]
06:21:09 INFO - 1499952069942 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0db18370-7648-4fb0-b5c7-5e21392e6e5b"}]
06:21:09 INFO - 1499952069969 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
06:21:09 INFO - 1499952069971 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"0db18370-7648-4fb0-b5c7-5e21392e6e5b"}]
06:21:09 INFO - 1499952069985 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element