could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
22:58:38 INFO - 1500962318843 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
22:58:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500962318846 Marionette TRACE 352 <- [1,5,null,{"value":null}]
22:58:38 INFO - 1500962318849 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
22:58:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 142ms
22:58:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:58:38 INFO - 1500962318851 Marionette TRACE 352 <- [1,6,null,{}]
22:58:38 INFO - 1500962318852 Marionette DEBUG Closed connection 352
22:58:38 INFO - 1500962318853 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50301
22:58:38 INFO - 1500962318854 Marionette DEBUG Closed connection 353
22:58:38 INFO - 1500962318855 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50302
22:58:38 INFO - 1500962318856 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:38 INFO - 1500962318857 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:38 INFO - 1500962318860 Marionette DEBUG Register listener.js for window 4294967297
22:58:38 INFO - 1500962318864 Marionette TRACE 354 <- [1,1,null,{"sessionId":"011e8f87-2260-4e56-b216-74f39012c794","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:38 INFO - 1500962318865 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
22:58:38 INFO - 1500962318866 Marionette TRACE 354 <- [1,2,null,{}]
22:58:38 INFO - 1500962318867 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:38 INFO - 1500962318867 Marionette TRACE 354 <- [1,3,null,{}]
22:58:38 INFO - 1500962318868 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:38 INFO - 1500962318869 Marionette TRACE 354 <- [1,4,null,{}]
22:58:38 INFO - 1500962318871 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
22:58:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500962318873 Marionette TRACE 354 <- [1,5,null,{"value":null}]
22:58:38 INFO - 1500962318874 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
22:58:38 INFO - 1500962318876 Marionette TRACE 354 <- [1,6,null,{}]
22:58:38 INFO - 1500962318878 Marionette DEBUG Closed connection 354
22:58:38 INFO - 1500962318879 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50303
22:58:38 INFO - 1500962318879 Marionette DEBUG Closed connection 355
22:58:38 INFO - 1500962318880 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50304
22:58:38 INFO - 1500962318881 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:38 INFO - 1500962318882 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:38 INFO - 1500962318885 Marionette DEBUG Register listener.js for window 4294967297
22:58:38 INFO - 1500962318888 Marionette TRACE 356 <- [1,1,null,{"sessionId":"de154575-5b9a-44ac-98eb-c753053290c7","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:38 INFO - 1500962318889 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
22:58:38 INFO - 1500962318891 Marionette TRACE 356 <- [1,2,null,{}]
22:58:38 INFO - 1500962318893 Marionette DEBUG Closed connection 356
22:58:38 INFO - 1500962318894 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50305
22:58:38 INFO - 1500962318894 Marionette DEBUG Closed connection 357
22:58:38 INFO - 1500962318895 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50306
22:58:38 INFO - 1500962318896 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:38 INFO - 1500962318897 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:38 INFO - 1500962318900 Marionette DEBUG Register listener.js for window 4294967297
22:58:38 INFO - 1500962318903 Marionette TRACE 358 <- [1,1,null,{"sessionId":"bd776563-5152-4249-8fb5-3fa9362c8c2b","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:38 INFO - 1500962318905 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49861/clicks.html"}]
22:58:38 INFO - 1500962318907 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49861/hidden.html"
22:58:38 INFO - 1500962318916 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49861/hidden.html"
22:58:38 INFO - 1500962318917 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49861/hidden.html"
22:58:38 INFO - 1500962318925 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49861/clicks.html"
22:58:38 INFO - 1500962318933 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49861/clicks.html"
22:58:38 INFO - 1500962318942 Marionette TRACE 358 <- [1,2,null,{}]
22:58:38 INFO - 1500962318954 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:58:38 INFO - 1500962318957 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40cfbf0a-e1bd-48fe-9360-b717948203b8","ELEMENT":"40cfbf0a-e1bd-48fe-9360-b717948203b8"}}]
22:58:38 INFO - 1500962318959 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"40cfbf0a-e1bd-48fe-9360-b717948203b8"}]
22:58:39 INFO - 1500962319004 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49861/clicks.html"
22:58:39 INFO - 1500962319014 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49861/clicks.html"
22:58:39 INFO - 1500962319014 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49861/clicks.html"
22:58:39 INFO - 1500962319022 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49861/test.html"
22:58:39 INFO - 1500962319026 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49861/test.html"
22:58:39 INFO - 1500962319032 Marionette TRACE 358 <- [1,4,null,{}]
22:58:39 INFO - 1500962319038 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:58:39 INFO - 1500962319048 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"32432b03-572f-41f2-93a1-8a4d6a3d5832","ELEMENT":"32432b03-572f-41f2-93a1-8a4d6a3d5832"}}]
22:58:39 INFO - 1500962319049 Marionette TRACE 358 -> [0,6,"getTitle",{}]
22:58:39 INFO - 1500962319050 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
22:58:39 INFO - 1500962319051 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
22:58:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500962319055 Marionette TRACE 358 <- [1,7,null,{"value":null}]
22:58:39 INFO - 1500962319056 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
22:58:39 INFO - 1500962319057 Marionette TRACE 358 <- [1,8,null,{}]
22:58:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 206ms
22:58:39 INFO - TEST-START | test_click.py TestClick.test_container_element
22:58:39 INFO - 1500962319059 Marionette DEBUG Closed connection 358
22:58:39 INFO - 1500962319060 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50309
22:58:39 INFO - 1500962319061 Marionette DEBUG Closed connection 359
22:58:39 INFO - 1500962319062 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50310
22:58:39 INFO - 1500962319063 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319063 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319066 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319070 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e514fd85-039e-4235-98ae-5d45d85ff869","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319072 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
22:58:39 INFO - 1500962319073 Marionette TRACE 360 <- [1,2,null,{}]
22:58:39 INFO - 1500962319074 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:39 INFO - 1500962319074 Marionette TRACE 360 <- [1,3,null,{}]
22:58:39 INFO - 1500962319075 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:39 INFO - 1500962319076 Marionette TRACE 360 <- [1,4,null,{}]
22:58:39 INFO - 1500962319078 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
22:58:39 INFO - TEST-START: test_click.py TestClick.test_container_element1500962319081 Marionette TRACE 360 <- [1,5,null,{"value":null}]
22:58:39 INFO - 1500962319082 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
22:58:39 INFO - 1500962319083 Marionette TRACE 360 <- [1,6,null,{}]
22:58:39 INFO - 1500962319085 Marionette DEBUG Closed connection 360
22:58:39 INFO - 1500962319085 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50311
22:58:39 INFO - 1500962319086 Marionette DEBUG Closed connection 361
22:58:39 INFO - 1500962319087 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50312
22:58:39 INFO - 1500962319088 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319089 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319092 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319095 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bd743fdf-04da-4080-bda7-2696059ee889","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319096 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
22:58:39 INFO - 1500962319098 Marionette TRACE 362 <- [1,2,null,{}]
22:58:39 INFO - 1500962319099 Marionette DEBUG Closed connection 362
22:58:39 INFO - 1500962319100 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50313
22:58:39 INFO - 1500962319101 Marionette DEBUG Closed connection 363
22:58:39 INFO - 1500962319102 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50314
22:58:39 INFO - 1500962319103 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:39 INFO - 1500962319104 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319107 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319110 Marionette TRACE 364 <- [1,1,null,{"sessionId":"89d01729-514b-4920-9e44-27eddbe265c4","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319112 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:58:39 INFO - 1500962319115 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49861/test.html"
22:58:39 INFO - 1500962319119 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49861/test.html"
22:58:39 INFO - 1500962319119 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49861/test.html"
22:58:39 INFO - 1500962319129 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319135 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319146 Marionette TRACE 364 <- [1,2,null,{}]
22:58:39 INFO - 1500962319156 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:58:39 INFO - 1500962319159 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ba1677e-d0ed-49e0-8416-19b36c5838d1","ELEMENT":"5ba1677e-d0ed-49e0-8416-19b36c5838d1"}}]
22:58:39 INFO - 1500962319161 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5ba1677e-d0ed-49e0-8416-19b36c5838d1"}]
22:58:39 INFO - 1500962319408 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:58:39 INFO - 1500962319409 Marionette TRACE 364 <- [1,4,null,{}]
22:58:39 INFO - 1500962319410 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5ba1677e-d0ed-49e0-8416-19b36c5838d1","name":"selected"}]
22:58:39 INFO - 1500962319415 Marionette TRACE 364 <- [1,5,null,{"value":true}]
22:58:39 INFO - 1500962319417 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
22:58:39 INFO - TEST-END: test_click.py TestClick.test_container_element1500962319420 Marionette TRACE 364 <- [1,6,null,{"value":null}]
22:58:39 INFO - 1500962319421 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
22:58:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 365ms
22:58:39 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:58:39 INFO - 1500962319423 Marionette TRACE 364 <- [1,7,null,{}]
22:58:39 INFO - 1500962319424 Marionette DEBUG Closed connection 364
22:58:39 INFO - 1500962319427 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50315
22:58:39 INFO - 1500962319428 Marionette DEBUG Closed connection 365
22:58:39 INFO - 1500962319428 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50316
22:58:39 INFO - 1500962319429 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319430 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319433 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319436 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b2b1fe2b-e449-492f-bae1-e6dab18ea939","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319438 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
22:58:39 INFO - 1500962319438 Marionette TRACE 366 <- [1,2,null,{}]
22:58:39 INFO - 1500962319439 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:39 INFO - 1500962319440 Marionette TRACE 366 <- [1,3,null,{}]
22:58:39 INFO - 1500962319441 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:39 INFO - 1500962319441 Marionette TRACE 366 <- [1,4,null,{}]
22:58:39 INFO - 1500962319443 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
22:58:39 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500962319446 Marionette TRACE 366 <- [1,5,null,{"value":null}]
22:58:39 INFO - 1500962319447 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
22:58:39 INFO - 1500962319448 Marionette TRACE 366 <- [1,6,null,{}]
22:58:39 INFO - 1500962319450 Marionette DEBUG Closed connection 366
22:58:39 INFO - 1500962319451 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50317
22:58:39 INFO - 1500962319451 Marionette DEBUG Closed connection 367
22:58:39 INFO - 1500962319452 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50318
22:58:39 INFO - 1500962319453 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319454 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319457 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319460 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b6d34390-94d8-4c8c-9e80-e773743bc452","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319461 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
22:58:39 INFO - 1500962319463 Marionette TRACE 368 <- [1,2,null,{}]
22:58:39 INFO - 1500962319464 Marionette DEBUG Closed connection 368
22:58:39 INFO - 1500962319465 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50319
22:58:39 INFO - 1500962319466 Marionette DEBUG Closed connection 369
22:58:39 INFO - 1500962319467 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50320
22:58:39 INFO - 1500962319468 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:39 INFO - 1500962319468 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319471 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319474 Marionette TRACE 370 <- [1,1,null,{"sessionId":"caef7c76-596f-451a-8b8f-fc5195ef1f2b","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319476 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:58:39 INFO - 1500962319477 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319480 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319480 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319487 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319490 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319494 Marionette TRACE 370 <- [1,2,null,{}]
22:58:39 INFO - 1500962319504 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:58:39 INFO - 1500962319507 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4dc6734-5766-403c-b615-58463080a006","ELEMENT":"b4dc6734-5766-403c-b615-58463080a006"}}]
22:58:39 INFO - 1500962319513 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b4dc6734-5766-403c-b615-58463080a006"}]
22:58:39 INFO - 1500962319763 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:58:39 INFO - 1500962319764 Marionette TRACE 370 <- [1,4,null,{}]
22:58:39 INFO - 1500962319766 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b4dc6734-5766-403c-b615-58463080a006","name":"selected"}]
22:58:39 INFO - 1500962319770 Marionette TRACE 370 <- [1,5,null,{"value":true}]
22:58:39 INFO - 1500962319772 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
22:58:39 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500962319775 Marionette TRACE 370 <- [1,6,null,{"value":null}]
22:58:39 INFO - 1500962319776 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
22:58:39 INFO - 1500962319778 Marionette TRACE 370 <- [1,7,null,{}]
22:58:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 355ms
22:58:39 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:58:39 INFO - 1500962319780 Marionette DEBUG Closed connection 370
22:58:39 INFO - 1500962319781 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50321
22:58:39 INFO - 1500962319781 Marionette DEBUG Closed connection 371
22:58:39 INFO - 1500962319782 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50322
22:58:39 INFO - 1500962319783 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319784 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319787 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319798 Marionette TRACE 372 <- [1,1,null,{"sessionId":"74b1cda6-99b0-445f-b6fe-900e2e455aae","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319799 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
22:58:39 INFO - 1500962319800 Marionette TRACE 372 <- [1,2,null,{}]
22:58:39 INFO - 1500962319801 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:39 INFO - 1500962319801 Marionette TRACE 372 <- [1,3,null,{}]
22:58:39 INFO - 1500962319802 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:39 INFO - 1500962319803 Marionette TRACE 372 <- [1,4,null,{}]
22:58:39 INFO - 1500962319805 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
22:58:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500962319808 Marionette TRACE 372 <- [1,5,null,{"value":null}]
22:58:39 INFO - 1500962319809 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
22:58:39 INFO - 1500962319811 Marionette TRACE 372 <- [1,6,null,{}]
22:58:39 INFO - 1500962319812 Marionette DEBUG Closed connection 372
22:58:39 INFO - 1500962319813 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50323
22:58:39 INFO - 1500962319814 Marionette DEBUG Closed connection 373
22:58:39 INFO - 1500962319815 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50324
22:58:39 INFO - 1500962319816 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:39 INFO - 1500962319817 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319820 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319823 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a935b96b-e8fd-4aa5-9656-8436713f708a","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319824 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
22:58:39 INFO - 1500962319826 Marionette TRACE 374 <- [1,2,null,{}]
22:58:39 INFO - 1500962319829 Marionette DEBUG Closed connection 374
22:58:39 INFO - 1500962319830 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50325
22:58:39 INFO - 1500962319830 Marionette DEBUG Closed connection 375
22:58:39 INFO - 1500962319831 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50326
22:58:39 INFO - 1500962319832 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:39 INFO - 1500962319833 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:39 INFO - 1500962319836 Marionette DEBUG Register listener.js for window 4294967297
22:58:39 INFO - 1500962319839 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7e1ee249-b8a1-4da6-85aa-49e7f86e3312","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:39 INFO - 1500962319840 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
22:58:39 INFO - 1500962319843 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319845 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319846 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:39 INFO - 1500962319854 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:58:39 INFO - 1500962319856 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:58:39 INFO - 1500962319859 Marionette TRACE 376 <- [1,2,null,{}]
22:58:39 INFO - 1500962319870 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:58:39 INFO - 1500962319882 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f9db485-bf4c-43b5-a6a7-777866e44459","ELEMENT":"1f9db485-bf4c-43b5-a6a7-777866e44459"}}]
22:58:39 INFO - 1500962319884 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1f9db485-bf4c-43b5-a6a7-777866e44459"}]
22:58:40 INFO - 1500962320129 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:58:40 INFO - 1500962320131 Marionette TRACE 376 <- [1,4,null,{}]
22:58:40 INFO - 1500962320133 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
22:58:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500962320136 Marionette TRACE 376 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320137 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
22:58:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 360ms
22:58:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:58:40 INFO - 1500962320139 Marionette TRACE 376 <- [1,6,null,{}]
22:58:40 INFO - 1500962320140 Marionette DEBUG Closed connection 376
22:58:40 INFO - 1500962320141 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50327
22:58:40 INFO - 1500962320142 Marionette DEBUG Closed connection 377
22:58:40 INFO - 1500962320143 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50328
22:58:40 INFO - 1500962320144 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320145 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320148 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320151 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5adf5000-2812-4377-8dbd-29fcb2da2ce5","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320152 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
22:58:40 INFO - 1500962320153 Marionette TRACE 378 <- [1,2,null,{}]
22:58:40 INFO - 1500962320154 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:40 INFO - 1500962320154 Marionette TRACE 378 <- [1,3,null,{}]
22:58:40 INFO - 1500962320155 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:40 INFO - 1500962320156 Marionette TRACE 378 <- [1,4,null,{}]
22:58:40 INFO - 1500962320158 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
22:58:40 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500962320161 Marionette TRACE 378 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320162 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
22:58:40 INFO - 1500962320163 Marionette TRACE 378 <- [1,6,null,{}]
22:58:40 INFO - 1500962320165 Marionette DEBUG Closed connection 378
22:58:40 INFO - 1500962320165 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50329
22:58:40 INFO - 1500962320166 Marionette DEBUG Closed connection 379
22:58:40 INFO - 1500962320167 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50330
22:58:40 INFO - 1500962320168 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320169 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320172 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320176 Marionette TRACE 380 <- [1,1,null,{"sessionId":"111ec1a0-5e35-4edc-b316-9004148eae62","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320177 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
22:58:40 INFO - 1500962320178 Marionette TRACE 380 <- [1,2,null,{}]
22:58:40 INFO - 1500962320180 Marionette DEBUG Closed connection 380
22:58:40 INFO - 1500962320180 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50331
22:58:40 INFO - 1500962320181 Marionette DEBUG Closed connection 381
22:58:40 INFO - 1500962320182 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50332
22:58:40 INFO - 1500962320183 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:40 INFO - 1500962320184 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320187 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320190 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ba7bd80b-1a6f-4b39-89f9-b9b8bae58193","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320191 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
22:58:40 INFO - 1500962320193 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:58:40 INFO - 1500962320195 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:58:40 INFO - 1500962320196 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
22:58:40 INFO - 1500962320202 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:40 INFO - 1500962320206 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:40 INFO - 1500962320209 Marionette TRACE 382 <- [1,2,null,{}]
22:58:40 INFO - 1500962320220 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:58:40 INFO - 1500962320223 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"48260f54-4012-406a-98dc-0e30b5abb1f3","ELEMENT":"48260f54-4012-406a-98dc-0e30b5abb1f3"}}]
22:58:40 INFO - 1500962320224 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"48260f54-4012-406a-98dc-0e30b5abb1f3"}]
22:58:40 INFO - 1500962320468 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:58:40 INFO - 1500962320469 Marionette TRACE 382 <- [1,4,null,{}]
22:58:40 INFO - 1500962320471 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
22:58:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500962320474 Marionette TRACE 382 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320475 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
22:58:40 INFO - 1500962320477 Marionette TRACE 382 <- [1,6,null,{}]
22:58:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 338ms
22:58:40 INFO - TEST-START | test_click.py TestClick.test_input_file
22:58:40 INFO - 1500962320479 Marionette DEBUG Closed connection 382
22:58:40 INFO - 1500962320480 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50333
22:58:40 INFO - 1500962320480 Marionette DEBUG Closed connection 383
22:58:40 INFO - 1500962320481 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50334
22:58:40 INFO - 1500962320482 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320483 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320486 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320489 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d6d2e0c7-d78b-435f-a9fb-7482d61c0e02","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320490 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
22:58:40 INFO - 1500962320491 Marionette TRACE 384 <- [1,2,null,{}]
22:58:40 INFO - 1500962320492 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:40 INFO - 1500962320493 Marionette TRACE 384 <- [1,3,null,{}]
22:58:40 INFO - 1500962320494 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:40 INFO - 1500962320494 Marionette TRACE 384 <- [1,4,null,{}]
22:58:40 INFO - 1500962320496 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
22:58:40 INFO - TEST-START: test_click.py TestClick.test_input_file1500962320499 Marionette TRACE 384 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320500 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
22:58:40 INFO - 1500962320502 Marionette TRACE 384 <- [1,6,null,{}]
22:58:40 INFO - 1500962320503 Marionette DEBUG Closed connection 384
22:58:40 INFO - 1500962320504 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50335
22:58:40 INFO - 1500962320505 Marionette DEBUG Closed connection 385
22:58:40 INFO - 1500962320506 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50336
22:58:40 INFO - 1500962320507 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320507 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320510 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320514 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e769fec9-4e95-4ef7-aaab-d2a006ad52d2","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320515 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
22:58:40 INFO - 1500962320516 Marionette TRACE 386 <- [1,2,null,{}]
22:58:40 INFO - 1500962320518 Marionette DEBUG Closed connection 386
22:58:40 INFO - 1500962320519 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50337
22:58:40 INFO - 1500962320519 Marionette DEBUG Closed connection 387
22:58:40 INFO - 1500962320520 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50338
22:58:40 INFO - 1500962320521 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:40 INFO - 1500962320522 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320525 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320528 Marionette TRACE 388 <- [1,1,null,{"sessionId":"507bbe74-2c8c-43c5-b3c9-5111374aae21","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320529 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:58:40 INFO - 1500962320531 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:40 INFO - 1500962320533 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:40 INFO - 1500962320534 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
22:58:40 INFO - 1500962320541 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:58:40 INFO - 1500962320546 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:58:40 INFO - 1500962320553 Marionette TRACE 388 <- [1,2,null,{}]
22:58:40 INFO - 1500962320557 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:58:40 INFO - 1500962320564 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70c8fd7a-8991-4efa-a3e2-534de79027bd","ELEMENT":"70c8fd7a-8991-4efa-a3e2-534de79027bd"}}]
22:58:40 INFO - 1500962320565 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"70c8fd7a-8991-4efa-a3e2-534de79027bd"}]
22:58:40 INFO - 1500962320599 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
22:58:40 INFO - 1500962320601 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
22:58:40 INFO - TEST-END: test_click.py TestClick.test_input_file1500962320605 Marionette TRACE 388 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320606 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
22:58:40 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 130ms
22:58:40 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:58:40 INFO - 1500962320607 Marionette TRACE 388 <- [1,6,null,{}]
22:58:40 INFO - 1500962320609 Marionette DEBUG Closed connection 388
22:58:40 INFO - 1500962320610 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50339
22:58:40 INFO - 1500962320611 Marionette DEBUG Closed connection 389
22:58:40 INFO - 1500962320612 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50340
22:58:40 INFO - 1500962320613 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320613 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320616 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320620 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8a76da83-34ee-4e5a-8f03-529e30dac6c2","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320621 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
22:58:40 INFO - 1500962320622 Marionette TRACE 390 <- [1,2,null,{}]
22:58:40 INFO - 1500962320623 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:40 INFO - 1500962320623 Marionette TRACE 390 <- [1,3,null,{}]
22:58:40 INFO - 1500962320624 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:40 INFO - 1500962320625 Marionette TRACE 390 <- [1,4,null,{}]
22:58:40 INFO - 1500962320627 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
22:58:40 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500962320630 Marionette TRACE 390 <- [1,5,null,{"value":null}]
22:58:40 INFO - 1500962320631 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
22:58:40 INFO - 1500962320632 Marionette TRACE 390 <- [1,6,null,{}]
22:58:40 INFO - 1500962320634 Marionette DEBUG Closed connection 390
22:58:40 INFO - 1500962320634 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50341
22:58:40 INFO - 1500962320635 Marionette DEBUG Closed connection 391
22:58:40 INFO - 1500962320636 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50342
22:58:40 INFO - 1500962320637 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320638 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320640 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320644 Marionette TRACE 392 <- [1,1,null,{"sessionId":"439bf417-38eb-4c0e-a340-9cd7393a214b","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320646 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
22:58:40 INFO - 1500962320647 Marionette TRACE 392 <- [1,2,null,{}]
22:58:40 INFO - 1500962320649 Marionette DEBUG Closed connection 392
22:58:40 INFO - 1500962320650 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50343
22:58:40 INFO - 1500962320650 Marionette DEBUG Closed connection 393
22:58:40 INFO - 1500962320651 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50344
22:58:40 INFO - 1500962320652 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:40 INFO - 1500962320653 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320656 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320659 Marionette TRACE 394 <- [1,1,null,{"sessionId":"045a193b-1757-49c8-90fe-88760e2921e8","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320660 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
22:58:40 INFO - 1500962320662 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:58:40 INFO - 1500962320665 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:58:40 INFO - 1500962320665 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:58:40 INFO - 1500962320672 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:58:40 INFO - 1500962320675 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:58:40 INFO - 1500962320685 Marionette TRACE 394 <- [1,2,null,{}]
22:58:40 INFO - 1500962320689 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:58:40 INFO - 1500962320692 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f01bc3c8-c8de-4a47-a9b3-944516731f8d","ELEMENT":"f01bc3c8-c8de-4a47-a9b3-944516731f8d"}}]
22:58:40 INFO - 1500962320698 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:58:40 INFO - 1500962320701 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9bf90462-54bd-4d7f-8c42-c9cb348b1a41","ELEMENT":"9bf90462-54bd-4d7f-8c42-c9cb348b1a41"}}]
22:58:40 INFO - 1500962320703 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f01bc3c8-c8de-4a47-a9b3-944516731f8d"}]
22:58:40 INFO - 1500962320945 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:58:40 INFO - 1500962320946 Marionette TRACE 394 <- [1,5,null,{}]
22:58:40 INFO - 1500962320948 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9bf90462-54bd-4d7f-8c42-c9cb348b1a41"}]
22:58:40 INFO - 1500962320965 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
22:58:40 INFO - 1500962320967 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:58:40 INFO - 1500962320971 Marionette TRACE 394 <- [1,7,null,{"value":false}]
22:58:40 INFO - 1500962320972 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
22:58:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500962320975 Marionette TRACE 394 <- [1,8,null,{"value":null}]
22:58:40 INFO - 1500962320976 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
22:58:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
22:58:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:58:40 INFO - 1500962320978 Marionette TRACE 394 <- [1,9,null,{}]
22:58:40 INFO - 1500962320979 Marionette DEBUG Closed connection 394
22:58:40 INFO - 1500962320980 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50345
22:58:40 INFO - 1500962320981 Marionette DEBUG Closed connection 395
22:58:40 INFO - 1500962320982 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50346
22:58:40 INFO - 1500962320983 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:40 INFO - 1500962320984 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:40 INFO - 1500962320987 Marionette DEBUG Register listener.js for window 4294967297
22:58:40 INFO - 1500962320990 Marionette TRACE 396 <- [1,1,null,{"sessionId":"4c5ea4bf-8944-4964-b714-1cb7f6fc1fb5","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:40 INFO - 1500962320991 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
22:58:40 INFO - 1500962320992 Marionette TRACE 396 <- [1,2,null,{}]
22:58:40 INFO - 1500962320993 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:58:40 INFO - 1500962320993 Marionette TRACE 396 <- [1,3,null,{}]
22:58:40 INFO - 1500962320994 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
22:58:40 INFO - 1500962320995 Marionette TRACE 396 <- [1,4,null,{}]
22:58:41 INFO - 1500962320997 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
22:58:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500962320999 Marionette TRACE 396 <- [1,5,null,{"value":null}]
22:58:41 INFO - 1500962321000 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
22:58:41 INFO - 1500962321002 Marionette TRACE 396 <- [1,6,null,{}]
22:58:41 INFO - 1500962321004 Marionette DEBUG Closed connection 396
22:58:41 INFO - 1500962321004 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50347
22:58:41 INFO - 1500962321005 Marionette DEBUG Closed connection 397
22:58:41 INFO - 1500962321006 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50348
22:58:41 INFO - 1500962321007 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:58:41 INFO - 1500962321008 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:41 INFO - 1500962321011 Marionette DEBUG Register listener.js for window 4294967297
22:58:41 INFO - 1500962321014 Marionette TRACE 398 <- [1,1,null,{"sessionId":"ac9f7422-1a36-453f-ad37-d20c5210d739","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:41 INFO - 1500962321015 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
22:58:41 INFO - 1500962321017 Marionette TRACE 398 <- [1,2,null,{}]
22:58:41 INFO - 1500962321018 Marionette DEBUG Closed connection 398
22:58:41 INFO - 1500962321019 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50349
22:58:41 INFO - 1500962321020 Marionette DEBUG Closed connection 399
22:58:41 INFO - 1500962321021 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50350
22:58:41 INFO - 1500962321022 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:58:41 INFO - 1500962321023 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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}
22:58:41 INFO - 1500962321026 Marionette DEBUG Register listener.js for window 4294967297
22:58:41 INFO - 1500962321029 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9ad0f6bd-ae85-4213-ac93-ef6a6c809395","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":2936,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpvpnb18.mozrunner","moz:accessibilityChecks":false}}]
22:58:41 INFO - 1500962321030 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
22:58:41 INFO - 1500962321032 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:58:41 INFO - 1500962321034 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:58:41 INFO - 1500962321035 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
22:58:41 INFO - 1500962321044 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
22:58:41 INFO - 1500962321046 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
22:58:41 INFO - 1500962321056 Marionette TRACE 400 <- [1,2,null,{}]
22:58:41 INFO - 1500962321065 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:58:41 INFO - 1500962321068 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5fa6bb60-e8c7-4b78-aa33-64a4a5c3a17d","ELEMENT":"5fa6bb60-e8c7-4b78-aa33-64a4a5c3a17d"}}]
22:58:41 INFO - 1500962321069 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5fa6bb60-e8c7-4b78-aa33-64a4a5c3a17d"}]
22:58:41 INFO - 1500962321093 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
22:58:41 INFO - 1500962321094 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5fa6bb60-e8c7-4b78-aa33-64a4a5c3a17d"}]
22:58:41 INFO - 1500962321112 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element