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]
19:38:16 INFO - 1501036696894 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}]
19:38:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501036696901 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:38:16 INFO - 1501036696903 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:38:16 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 681ms
19:38:16 INFO - 1501036696907 Marionette TRACE 352 <- [1,6,null,{}]
19:38:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:38:16 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:16 INFO - 1501036696912 Marionette DEBUG Closed connection 352
19:38:16 INFO - 1501036696913 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49802
19:38:16 INFO - 1501036696914 Marionette DEBUG Closed connection 353
19:38:16 INFO - 1501036696916 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49803
19:38:16 INFO - 1501036696918 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:16 INFO - 1501036696920 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:16 INFO - 1501036696941 Marionette DEBUG Register listener.js for window 4294967297
19:38:16 INFO - 1501036696954 Marionette TRACE 354 <- [1,1,null,{"sessionId":"039f79aa-3e8d-4d2f-81c7-c25874b5d922","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:16 INFO - 1501036696957 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:38:16 INFO - 1501036696959 Marionette TRACE 354 <- [1,2,null,{}]
19:38:16 INFO - 1501036696961 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:16 INFO - 1501036696962 Marionette TRACE 354 <- [1,3,null,{}]
19:38:16 INFO - 1501036696964 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:16 INFO - 1501036696965 Marionette TRACE 354 <- [1,4,null,{}]
19:38:16 INFO - 1501036696968 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}]
19:38:16 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501036696976 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:38:16 INFO - 1501036696978 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:38:16 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:16 INFO - 1501036696982 Marionette TRACE 354 <- [1,6,null,{}]
19:38:16 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:16 INFO - 1501036696986 Marionette DEBUG Closed connection 354
19:38:16 INFO - 1501036696988 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49804
19:38:16 INFO - 1501036696989 Marionette DEBUG Closed connection 355
19:38:16 INFO - 1501036696991 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49805
19:38:16 INFO - 1501036696993 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:16 INFO - 1501036696996 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:17 INFO - 1501036697027 Marionette DEBUG Register listener.js for window 4294967297
19:38:17 INFO - 1501036697217 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d1cbeac9-5ff4-4817-a06f-930005b511db","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:17 INFO - 1501036697221 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - 1501036697226 Marionette TRACE 356 <- [1,2,null,{}]
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - 1501036697231 Marionette DEBUG Closed connection 356
19:38:17 INFO - 1501036697233 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49806
19:38:17 INFO - 1501036697234 Marionette DEBUG Closed connection 357
19:38:17 INFO - 1501036697236 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49807
19:38:17 INFO - 1501036697239 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:17 INFO - 1501036697242 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:17 INFO - 1501036697264 Marionette DEBUG Register listener.js for window 4294967297
19:38:17 INFO - 1501036697279 Marionette TRACE 358 <- [1,1,null,{"sessionId":"47fbdfe5-f5f2-48dd-b6e4-db38d698e096","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:17 INFO - 1501036697283 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49347/clicks.html"}]
19:38:17 INFO - 1501036697288 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/hidden.html"
19:38:17 INFO - 1501036697304 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/hidden.html"
19:38:17 INFO - 1501036697304 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/hidden.html"
19:38:17 INFO - ++DOMWINDOW == 19 (000000FC3FA7E800) [pid = 3452] [serial = 25] [outer = 000000FC3BB1E000]
19:38:17 INFO - 1501036697328 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/clicks.html"
19:38:17 INFO - 1501036697339 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/clicks.html"
19:38:17 INFO - 1501036697345 Marionette TRACE 358 <- [1,2,null,{}]
19:38:17 INFO - 1501036697360 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:38:17 INFO - 1501036697366 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"074835a8-1ca7-44a0-a500-d6b8317c1a55","ELEMENT":"074835a8-1ca7-44a0-a500-d6b8317c1a55"}}]
19:38:17 INFO - 1501036697372 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"074835a8-1ca7-44a0-a500-d6b8317c1a55"}]
19:38:17 INFO - 1501036697610 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/clicks.html"
19:38:17 INFO - 1501036697630 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/clicks.html"
19:38:17 INFO - 1501036697631 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/clicks.html"
19:38:17 INFO - ++DOMWINDOW == 20 (000000FC3F75B800) [pid = 3452] [serial = 26] [outer = 000000FC3BB1E000]
19:38:17 INFO - 1501036697659 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/test.html"
19:38:17 INFO - 1501036697663 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/test.html"
19:38:17 INFO - 1501036697674 Marionette TRACE 358 <- [1,4,null,{}]
19:38:17 INFO - 1501036697690 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:38:17 INFO - 1501036697699 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"581c63f0-557b-4a62-9c2c-e9dc1866bbe1","ELEMENT":"581c63f0-557b-4a62-9c2c-e9dc1866bbe1"}}]
19:38:17 INFO - 1501036697702 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:38:17 INFO - 1501036697703 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:38:17 INFO - 1501036697706 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}]
19:38:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501036697713 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:38:17 INFO - 1501036697715 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 812ms
19:38:17 INFO - 1501036697719 Marionette TRACE 358 <- [1,8,null,{}]
19:38:17 INFO - TEST-START | test_click.py TestClick.test_container_element
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - 1501036697724 Marionette DEBUG Closed connection 358
19:38:17 INFO - 1501036697725 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49810
19:38:17 INFO - 1501036697727 Marionette DEBUG Closed connection 359
19:38:17 INFO - 1501036697728 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49811
19:38:17 INFO - 1501036697730 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:17 INFO - 1501036697733 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:17 INFO - 1501036697755 Marionette DEBUG Register listener.js for window 4294967297
19:38:17 INFO - 1501036697766 Marionette TRACE 360 <- [1,1,null,{"sessionId":"98f80940-6124-4bfe-809f-00256f33e1f0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:17 INFO - 1501036697769 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:38:17 INFO - 1501036697771 Marionette TRACE 360 <- [1,2,null,{}]
19:38:17 INFO - --DOMWINDOW == 19 (000000FC3FA7C800) [pid = 3452] [serial = 8] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:17 INFO - --DOMWINDOW == 18 (000000FC43B42000) [pid = 3452] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:17 INFO - --DOMWINDOW == 17 (000000FC43BA6800) [pid = 3452] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:17 INFO - --DOMWINDOW == 16 (000000FC440AB000) [pid = 3452] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:17 INFO - 1501036697773 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:17 INFO - 1501036697774 Marionette TRACE 360 <- [1,3,null,{}]
19:38:17 INFO - 1501036697776 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:17 INFO - 1501036697778 Marionette TRACE 360 <- [1,4,null,{}]
19:38:17 INFO - 1501036697780 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}]
19:38:17 INFO - TEST-START: test_click.py TestClick.test_container_element1501036697787 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:38:17 INFO - 1501036697789 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - 1501036697793 Marionette TRACE 360 <- [1,6,null,{}]
19:38:17 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:17 INFO - 1501036697797 Marionette DEBUG Closed connection 360
19:38:17 INFO - 1501036697799 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49812
19:38:17 INFO - 1501036697801 Marionette DEBUG Closed connection 361
19:38:17 INFO - 1501036697802 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49813
19:38:17 INFO - 1501036697804 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:17 INFO - 1501036697806 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:18 INFO - 1501036698209 Marionette DEBUG Register listener.js for window 4294967297
19:38:18 INFO - 1501036698220 Marionette TRACE 362 <- [1,1,null,{"sessionId":"40353e82-a4a0-497f-bc12-f92ef4a93e92","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:18 INFO - 1501036698223 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:38:18 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:18 INFO - 1501036698228 Marionette TRACE 362 <- [1,2,null,{}]
19:38:18 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:18 INFO - 1501036698232 Marionette DEBUG Closed connection 362
19:38:18 INFO - 1501036698233 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49814
19:38:18 INFO - 1501036698239 Marionette DEBUG Closed connection 363
19:38:18 INFO - 1501036698241 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49815
19:38:18 INFO - 1501036698243 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:18 INFO - 1501036698246 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:18 INFO - 1501036698278 Marionette DEBUG Register listener.js for window 4294967297
19:38:18 INFO - 1501036698290 Marionette TRACE 364 <- [1,1,null,{"sessionId":"155500d4-900f-41b0-b3c6-3e4f6f1bdcc7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:18 INFO - 1501036698293 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"}]
19:38:18 INFO - 1501036698305 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/test.html"
19:38:18 INFO - 1501036698310 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/test.html"
19:38:18 INFO - 1501036698310 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/test.html"
19:38:18 INFO - ++DOMWINDOW == 17 (000000FC445A8000) [pid = 3452] [serial = 27] [outer = 000000FC3BB1E000]
19:38:18 INFO - 1501036698343 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"
19:38:18 INFO - 1501036698347 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"
19:38:18 INFO - 1501036698361 Marionette TRACE 364 <- [1,2,null,{}]
19:38:18 INFO - 1501036698377 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:38:18 INFO - 1501036698392 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a68a3f7e-af39-4258-9744-05f5882542eb","ELEMENT":"a68a3f7e-af39-4258-9744-05f5882542eb"}}]
19:38:18 INFO - 1501036698394 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"a68a3f7e-af39-4258-9744-05f5882542eb"}]
19:38:18 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
19:38:18 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
19:38:18 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
19:38:18 INFO - 1501036698914 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:38:19 INFO - 1501036699028 Marionette TRACE 364 <- [1,4,null,{}]
19:38:19 INFO - 1501036699035 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"a68a3f7e-af39-4258-9744-05f5882542eb","name":"selected"}]
19:38:19 INFO - 1501036699065 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:38:19 INFO - 1501036699067 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}]
19:38:19 INFO - TEST-END: test_click.py TestClick.test_container_element1501036699075 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:38:19 INFO - 1501036699077 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1360ms
19:38:19 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:38:19 INFO - 1501036699081 Marionette TRACE 364 <- [1,7,null,{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699085 Marionette DEBUG Closed connection 364
19:38:19 INFO - 1501036699087 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49816
19:38:19 INFO - 1501036699088 Marionette DEBUG Closed connection 365
19:38:19 INFO - 1501036699090 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49817
19:38:19 INFO - 1501036699092 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:19 INFO - 1501036699094 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:19 INFO - 1501036699116 Marionette DEBUG Register listener.js for window 4294967297
19:38:19 INFO - 1501036699127 Marionette TRACE 366 <- [1,1,null,{"sessionId":"942405cb-eda7-4256-bf6b-66232ee43ee5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:19 INFO - 1501036699130 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:38:19 INFO - 1501036699132 Marionette TRACE 366 <- [1,2,null,{}]
19:38:19 INFO - 1501036699133 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:19 INFO - 1501036699135 Marionette TRACE 366 <- [1,3,null,{}]
19:38:19 INFO - 1501036699136 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:19 INFO - 1501036699138 Marionette TRACE 366 <- [1,4,null,{}]
19:38:19 INFO - 1501036699140 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}]
19:38:19 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501036699153 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:38:19 INFO - 1501036699155 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699159 Marionette TRACE 366 <- [1,6,null,{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699163 Marionette DEBUG Closed connection 366
19:38:19 INFO - 1501036699164 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49818
19:38:19 INFO - 1501036699166 Marionette DEBUG Closed connection 367
19:38:19 INFO - 1501036699167 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49819
19:38:19 INFO - 1501036699169 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:19 INFO - 1501036699172 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:19 INFO - 1501036699194 Marionette DEBUG Register listener.js for window 4294967297
19:38:19 INFO - 1501036699205 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f2217c99-2fe4-4935-a54a-0ccfbbd66ee5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:19 INFO - 1501036699207 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699212 Marionette TRACE 368 <- [1,2,null,{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699216 Marionette DEBUG Closed connection 368
19:38:19 INFO - 1501036699217 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49820
19:38:19 INFO - 1501036699219 Marionette DEBUG Closed connection 369
19:38:19 INFO - 1501036699220 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49821
19:38:19 INFO - 1501036699222 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:19 INFO - 1501036699224 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:19 INFO - 1501036699247 Marionette DEBUG Register listener.js for window 4294967297
19:38:19 INFO - 1501036699258 Marionette TRACE 370 <- [1,1,null,{"sessionId":"069db922-af05-427a-a7ff-6b768cc4679c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:19 INFO - 1501036699261 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"}]
19:38:19 INFO - 1501036699276 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"
19:38:19 INFO - 1501036699281 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"
19:38:19 INFO - 1501036699282 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"
19:38:19 INFO - ++DOMWINDOW == 18 (000000FC44620800) [pid = 3452] [serial = 28] [outer = 000000FC3BB1E000]
19:38:19 INFO - 1501036699307 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"
19:38:19 INFO - 1501036699311 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"
19:38:19 INFO - 1501036699318 Marionette TRACE 370 <- [1,2,null,{}]
19:38:19 INFO - 1501036699339 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:38:19 INFO - 1501036699345 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d365a8f6-2ddf-4f5f-a96d-a66c98eef83f","ELEMENT":"d365a8f6-2ddf-4f5f-a96d-a66c98eef83f"}}]
19:38:19 INFO - 1501036699347 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d365a8f6-2ddf-4f5f-a96d-a66c98eef83f"}]
19:38:19 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
19:38:19 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
19:38:19 INFO - [Child 3452] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
19:38:19 INFO - 1501036699789 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:38:19 INFO - 1501036699791 Marionette TRACE 370 <- [1,4,null,{}]
19:38:19 INFO - 1501036699793 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d365a8f6-2ddf-4f5f-a96d-a66c98eef83f","name":"selected"}]
19:38:19 INFO - 1501036699822 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:38:19 INFO - 1501036699825 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}]
19:38:19 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501036699832 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:38:19 INFO - 1501036699834 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 757ms
19:38:19 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:38:19 INFO - 1501036699838 Marionette TRACE 370 <- [1,7,null,{}]
19:38:19 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:19 INFO - 1501036699842 Marionette DEBUG Closed connection 370
19:38:19 INFO - 1501036699844 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49822
19:38:19 INFO - 1501036699845 Marionette DEBUG Closed connection 371
19:38:19 INFO - 1501036699847 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49823
19:38:19 INFO - 1501036699849 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:19 INFO - 1501036699851 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:20 INFO - 1501036700137 Marionette DEBUG Register listener.js for window 4294967297
19:38:20 INFO - 1501036700149 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e66a5c49-2bb2-49e3-8a72-084dfe30e14d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:20 INFO - 1501036700151 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:38:20 INFO - 1501036700153 Marionette TRACE 372 <- [1,2,null,{}]
19:38:20 INFO - 1501036700155 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:20 INFO - 1501036700156 Marionette TRACE 372 <- [1,3,null,{}]
19:38:20 INFO - 1501036700158 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:20 INFO - 1501036700159 Marionette TRACE 372 <- [1,4,null,{}]
19:38:20 INFO - 1501036700162 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}]
19:38:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501036700206 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:38:20 INFO - 1501036700209 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700213 Marionette TRACE 372 <- [1,6,null,{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700217 Marionette DEBUG Closed connection 372
19:38:20 INFO - 1501036700219 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49824
19:38:20 INFO - 1501036700220 Marionette DEBUG Closed connection 373
19:38:20 INFO - 1501036700222 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49825
19:38:20 INFO - 1501036700224 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:20 INFO - 1501036700227 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:20 INFO - 1501036700261 Marionette DEBUG Register listener.js for window 4294967297
19:38:20 INFO - 1501036700272 Marionette TRACE 374 <- [1,1,null,{"sessionId":"59b4b61d-ba84-4306-9ae1-3e4bfd0427b3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:20 INFO - 1501036700274 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700279 Marionette TRACE 374 <- [1,2,null,{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700283 Marionette DEBUG Closed connection 374
19:38:20 INFO - 1501036700284 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49826
19:38:20 INFO - 1501036700286 Marionette DEBUG Closed connection 375
19:38:20 INFO - 1501036700287 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49827
19:38:20 INFO - 1501036700290 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:20 INFO - 1501036700292 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:20 INFO - 1501036700327 Marionette DEBUG Register listener.js for window 4294967297
19:38:20 INFO - 1501036700338 Marionette TRACE 376 <- [1,1,null,{"sessionId":"17392999-b4b0-400d-9719-802ec8c73ab1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:20 INFO - 1501036700341 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"}]
19:38:20 INFO - 1501036700354 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"
19:38:20 INFO - 1501036700359 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"
19:38:20 INFO - 1501036700359 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"
19:38:20 INFO - ++DOMWINDOW == 19 (000000FC47F4C800) [pid = 3452] [serial = 29] [outer = 000000FC3BB1E000]
19:38:20 INFO - 1501036700385 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"
19:38:20 INFO - 1501036700390 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"
19:38:20 INFO - 1501036700402 Marionette TRACE 376 <- [1,2,null,{}]
19:38:20 INFO - 1501036700417 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:38:20 INFO - 1501036700423 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83a7d900-8a26-4eb6-9127-becf55b3d8a5","ELEMENT":"83a7d900-8a26-4eb6-9127-becf55b3d8a5"}}]
19:38:20 INFO - 1501036700425 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"83a7d900-8a26-4eb6-9127-becf55b3d8a5"}]
19:38:20 INFO - 1501036700877 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:38:20 INFO - 1501036700878 Marionette TRACE 376 <- [1,4,null,{}]
19:38:20 INFO - 1501036700882 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}]
19:38:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501036700889 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:38:20 INFO - 1501036700891 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1057ms
19:38:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:38:20 INFO - 1501036700896 Marionette TRACE 376 <- [1,6,null,{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700900 Marionette DEBUG Closed connection 376
19:38:20 INFO - 1501036700902 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49828
19:38:20 INFO - 1501036700903 Marionette DEBUG Closed connection 377
19:38:20 INFO - 1501036700904 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49829
19:38:20 INFO - 1501036700907 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:20 INFO - 1501036700909 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:20 INFO - 1501036700931 Marionette DEBUG Register listener.js for window 4294967297
19:38:20 INFO - 1501036700942 Marionette TRACE 378 <- [1,1,null,{"sessionId":"13d72b30-645c-401a-8de0-41aa140b643d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:20 INFO - 1501036700945 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:38:20 INFO - 1501036700946 Marionette TRACE 378 <- [1,2,null,{}]
19:38:20 INFO - 1501036700948 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:20 INFO - 1501036700950 Marionette TRACE 378 <- [1,3,null,{}]
19:38:20 INFO - 1501036700951 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:20 INFO - 1501036700953 Marionette TRACE 378 <- [1,4,null,{}]
19:38:20 INFO - 1501036700955 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}]
19:38:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501036700962 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:38:20 INFO - 1501036700964 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700968 Marionette TRACE 378 <- [1,6,null,{}]
19:38:20 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:20 INFO - 1501036700972 Marionette DEBUG Closed connection 378
19:38:20 INFO - 1501036700973 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49830
19:38:20 INFO - 1501036700975 Marionette DEBUG Closed connection 379
19:38:20 INFO - 1501036700976 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49831
19:38:20 INFO - 1501036700979 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:20 INFO - 1501036700981 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:21 INFO - 1501036701004 Marionette DEBUG Register listener.js for window 4294967297
19:38:21 INFO - 1501036701014 Marionette TRACE 380 <- [1,1,null,{"sessionId":"18e4f5cc-4e21-4d81-b144-0394dc98f7c7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:21 INFO - 1501036701017 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701022 Marionette TRACE 380 <- [1,2,null,{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701026 Marionette DEBUG Closed connection 380
19:38:21 INFO - 1501036701027 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49832
19:38:21 INFO - 1501036701029 Marionette DEBUG Closed connection 381
19:38:21 INFO - 1501036701030 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49833
19:38:21 INFO - 1501036701032 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:21 INFO - 1501036701035 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:21 INFO - 1501036701058 Marionette DEBUG Register listener.js for window 4294967297
19:38:21 INFO - 1501036701068 Marionette TRACE 382 <- [1,1,null,{"sessionId":"fe679fbc-6383-4aed-8c9e-c377eda9efeb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:21 INFO - 1501036701071 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"}]
19:38:21 INFO - 1501036701075 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"
19:38:21 INFO - 1501036701080 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"
19:38:21 INFO - 1501036701080 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"
19:38:21 INFO - ++DOMWINDOW == 20 (000000FC44D53000) [pid = 3452] [serial = 30] [outer = 000000FC3BB1E000]
19:38:21 INFO - 1501036701106 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"
19:38:21 INFO - 1501036701110 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"
19:38:21 INFO - 1501036701119 Marionette TRACE 382 <- [1,2,null,{}]
19:38:21 INFO - 1501036701134 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:38:21 INFO - 1501036701141 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80c875db-c5b8-4cb3-bddf-ca9e5842f5bd","ELEMENT":"80c875db-c5b8-4cb3-bddf-ca9e5842f5bd"}}]
19:38:21 INFO - 1501036701142 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"80c875db-c5b8-4cb3-bddf-ca9e5842f5bd"}]
19:38:21 INFO - 1501036701579 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:38:21 INFO - 1501036701580 Marionette TRACE 382 <- [1,4,null,{}]
19:38:21 INFO - 1501036701584 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}]
19:38:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501036701591 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:38:21 INFO - 1501036701593 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:38:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 700ms
19:38:21 INFO - TEST-START | test_click.py TestClick.test_input_file
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701597 Marionette TRACE 382 <- [1,6,null,{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701601 Marionette DEBUG Closed connection 382
19:38:21 INFO - 1501036701602 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49834
19:38:21 INFO - 1501036701604 Marionette DEBUG Closed connection 383
19:38:21 INFO - 1501036701605 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49835
19:38:21 INFO - 1501036701608 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:21 INFO - 1501036701610 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:21 INFO - 1501036701633 Marionette DEBUG Register listener.js for window 4294967297
19:38:21 INFO - 1501036701657 Marionette TRACE 384 <- [1,1,null,{"sessionId":"0d79ad8d-6cf1-4480-9f3d-081065880fe6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:21 INFO - 1501036701660 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:38:21 INFO - 1501036701662 Marionette TRACE 384 <- [1,2,null,{}]
19:38:21 INFO - 1501036701664 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:21 INFO - 1501036701665 Marionette TRACE 384 <- [1,3,null,{}]
19:38:21 INFO - 1501036701667 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:21 INFO - 1501036701668 Marionette TRACE 384 <- [1,4,null,{}]
19:38:21 INFO - 1501036701671 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}]
19:38:21 INFO - TEST-START: test_click.py TestClick.test_input_file1501036701678 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:38:21 INFO - 1501036701680 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701684 Marionette TRACE 384 <- [1,6,null,{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701690 Marionette DEBUG Closed connection 384
19:38:21 INFO - 1501036701691 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49836
19:38:21 INFO - 1501036701693 Marionette DEBUG Closed connection 385
19:38:21 INFO - 1501036701695 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49837
19:38:21 INFO - 1501036701697 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:21 INFO - 1501036701699 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:21 INFO - 1501036701723 Marionette DEBUG Register listener.js for window 4294967297
19:38:21 INFO - 1501036701734 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c293f1fb-f803-4d2b-b599-22904e3c457a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:21 INFO - 1501036701737 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701741 Marionette TRACE 386 <- [1,2,null,{}]
19:38:21 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:21 INFO - 1501036701745 Marionette DEBUG Closed connection 386
19:38:21 INFO - 1501036701746 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49838
19:38:21 INFO - 1501036701748 Marionette DEBUG Closed connection 387
19:38:21 INFO - 1501036701749 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49839
19:38:21 INFO - 1501036701752 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:21 INFO - 1501036701754 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:21 INFO - 1501036701777 Marionette DEBUG Register listener.js for window 4294967297
19:38:21 INFO - 1501036701788 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1db0cc57-4512-4705-8b6c-db6ad9fd2b39","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:21 INFO - 1501036701791 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:38:21 INFO - 1501036701796 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"
19:38:21 INFO - 1501036701800 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"
19:38:21 INFO - 1501036701802 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"
19:38:21 INFO - ++DOMWINDOW == 21 (000000FC46545800) [pid = 3452] [serial = 31] [outer = 000000FC3BB1E000]
19:38:21 INFO - 1501036701829 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:38:21 INFO - 1501036701834 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:38:21 INFO - 1501036701840 Marionette TRACE 388 <- [1,2,null,{}]
19:38:21 INFO - 1501036701848 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:38:21 INFO - 1501036701865 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"365d14b3-0dac-4846-8f24-7c444b4f5d09","ELEMENT":"365d14b3-0dac-4846-8f24-7c444b4f5d09"}}]
19:38:21 INFO - 1501036701867 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"365d14b3-0dac-4846-8f24-7c444b4f5d09"}]
19:38:22 INFO - 1501036702032 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]
19:38:22 INFO - 1501036702035 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}]
19:38:22 INFO - TEST-END: test_click.py TestClick.test_input_file1501036702047 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:38:22 INFO - 1501036702049 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702053 Marionette TRACE 388 <- [1,6,null,{}]
19:38:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 456ms
19:38:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702059 Marionette DEBUG Closed connection 388
19:38:22 INFO - 1501036702061 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49840
19:38:22 INFO - 1501036702063 Marionette DEBUG Closed connection 389
19:38:22 INFO - 1501036702065 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49841
19:38:22 INFO - 1501036702068 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:22 INFO - 1501036702070 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:22 INFO - 1501036702095 Marionette DEBUG Register listener.js for window 4294967297
19:38:22 INFO - 1501036702107 Marionette TRACE 390 <- [1,1,null,{"sessionId":"92d500ff-d78d-43d0-b26a-5428b2d6e88c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:22 INFO - 1501036702110 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:38:22 INFO - 1501036702112 Marionette TRACE 390 <- [1,2,null,{}]
19:38:22 INFO - 1501036702114 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:22 INFO - 1501036702115 Marionette TRACE 390 <- [1,3,null,{}]
19:38:22 INFO - 1501036702117 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:22 INFO - 1501036702119 Marionette TRACE 390 <- [1,4,null,{}]
19:38:22 INFO - 1501036702122 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}]
19:38:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501036702133 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:38:22 INFO - 1501036702135 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702139 Marionette TRACE 390 <- [1,6,null,{}]
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702144 Marionette DEBUG Closed connection 390
19:38:22 INFO - 1501036702146 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49842
19:38:22 INFO - 1501036702148 Marionette DEBUG Closed connection 391
19:38:22 INFO - 1501036702149 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49843
19:38:22 INFO - 1501036702151 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:22 INFO - 1501036702154 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:22 INFO - 1501036702207 Marionette DEBUG Register listener.js for window 4294967297
19:38:22 INFO - 1501036702218 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3807ef2b-c450-45ca-83dd-c6cd155b3840","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:22 INFO - 1501036702221 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702225 Marionette TRACE 392 <- [1,2,null,{}]
19:38:22 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:22 INFO - 1501036702229 Marionette DEBUG Closed connection 392
19:38:22 INFO - 1501036702230 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49844
19:38:22 INFO - 1501036702232 Marionette DEBUG Closed connection 393
19:38:22 INFO - 1501036702234 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49845
19:38:22 INFO - 1501036702237 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:22 INFO - 1501036702240 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:22 INFO - 1501036702278 Marionette DEBUG Register listener.js for window 4294967297
19:38:22 INFO - 1501036702291 Marionette TRACE 394 <- [1,1,null,{"sessionId":"aaf474ac-59bf-459d-9685-ffa0e5705558","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:22 INFO - 1501036702294 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"}]
19:38:22 INFO - 1501036702299 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:38:22 INFO - 1501036702305 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:38:22 INFO - 1501036702305 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:38:22 INFO - ++DOMWINDOW == 22 (000000FC3F764800) [pid = 3452] [serial = 32] [outer = 000000FC3BB1E000]
19:38:22 INFO - 1501036702329 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"
19:38:22 INFO - 1501036702336 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"
19:38:22 INFO - 1501036702348 Marionette TRACE 394 <- [1,2,null,{}]
19:38:22 INFO - 1501036702363 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:38:22 INFO - 1501036702370 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6320654d-aca3-4522-8dcf-ba69812efab4","ELEMENT":"6320654d-aca3-4522-8dcf-ba69812efab4"}}]
19:38:22 INFO - 1501036702372 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:38:22 INFO - 1501036702378 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2f5ce9c-e6f3-4471-aca3-1f3e2c0adc6e","ELEMENT":"c2f5ce9c-e6f3-4471-aca3-1f3e2c0adc6e"}}]
19:38:22 INFO - 1501036702380 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"6320654d-aca3-4522-8dcf-ba69812efab4"}]
19:38:22 INFO - --DOMWINDOW == 21 (000000FC453DC800) [pid = 3452] [serial = 16] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:22 INFO - --DOMWINDOW == 20 (000000FC453D1000) [pid = 3452] [serial = 15] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:22 INFO - --DOMWINDOW == 19 (000000FC47F6A000) [pid = 3452] [serial = 21] [outer = 0000000000000000] [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]
19:38:22 INFO - --DOMWINDOW == 18 (000000FC445A8800) [pid = 3452] [serial = 20] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%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.clicks%20%3D%200%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.clicks%2B%2B%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]
19:38:22 INFO - --DOMWINDOW == 17 (000000FC4459A000) [pid = 3452] [serial = 19] [outer = 0000000000000000] [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%20position%3A%20absolute%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%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%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]
19:38:22 INFO - --DOMWINDOW == 16 (000000FC47EC9000) [pid = 3452] [serial = 18] [outer = 0000000000000000] [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%20position%3A%20absolute%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%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%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]
19:38:22 INFO - --DOMWINDOW == 15 (000000FC4653B000) [pid = 3452] [serial = 17] [outer = 0000000000000000] [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%20position%3A%20absolute%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%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%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]
19:38:22 INFO - --DOMWINDOW == 14 (000000FC45A9C000) [pid = 3452] [serial = 12] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:22 INFO - --DOMWINDOW == 13 (000000FC3FBCC000) [pid = 3452] [serial = 14] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:22 INFO - --DOMWINDOW == 12 (000000FC3D676000) [pid = 3452] [serial = 13] [outer = 0000000000000000] [url = http://127.0.0.1:49347/test_accessibility.html]
19:38:22 INFO - 1501036702826 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:38:22 INFO - 1501036702828 Marionette TRACE 394 <- [1,5,null,{}]
19:38:22 INFO - 1501036702830 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c2f5ce9c-e6f3-4471-aca3-1f3e2c0adc6e"}]
19:38:22 INFO - 1501036702969 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]
19:38:22 INFO - 1501036702972 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:38:22 INFO - 1501036702986 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:38:22 INFO - 1501036702989 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}]
19:38:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1296ms
19:38:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:38:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501036703344 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:38:23 INFO - 1501036703346 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703350 Marionette TRACE 394 <- [1,9,null,{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703355 Marionette DEBUG Closed connection 394
19:38:23 INFO - 1501036703356 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49846
19:38:23 INFO - 1501036703360 Marionette DEBUG Closed connection 395
19:38:23 INFO - 1501036703361 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49847
19:38:23 INFO - 1501036703363 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:23 INFO - 1501036703365 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:23 INFO - 1501036703414 Marionette DEBUG Register listener.js for window 4294967297
19:38:23 INFO - 1501036703425 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f29b9217-1579-42f0-918d-2dd659328d38","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:23 INFO - 1501036703428 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:38:23 INFO - 1501036703430 Marionette TRACE 396 <- [1,2,null,{}]
19:38:23 INFO - 1501036703431 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:38:23 INFO - 1501036703433 Marionette TRACE 396 <- [1,3,null,{}]
19:38:23 INFO - 1501036703435 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:38:23 INFO - 1501036703436 Marionette TRACE 396 <- [1,4,null,{}]
19:38:23 INFO - 1501036703439 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}]
19:38:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501036703481 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:38:23 INFO - 1501036703483 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703488 Marionette TRACE 396 <- [1,6,null,{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703492 Marionette DEBUG Closed connection 396
19:38:23 INFO - 1501036703493 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49848
19:38:23 INFO - 1501036703495 Marionette DEBUG Closed connection 397
19:38:23 INFO - 1501036703497 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49849
19:38:23 INFO - 1501036703499 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:38:23 INFO - 1501036703502 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:23 INFO - 1501036703537 Marionette DEBUG Register listener.js for window 4294967297
19:38:23 INFO - 1501036703547 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d78ca063-3697-46b8-b86c-aeb11c5fc312","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:23 INFO - 1501036703550 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703554 Marionette TRACE 398 <- [1,2,null,{}]
19:38:23 INFO - [Parent 2988] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:38:23 INFO - 1501036703558 Marionette DEBUG Closed connection 398
19:38:23 INFO - 1501036703560 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49850
19:38:23 INFO - 1501036703561 Marionette DEBUG Closed connection 399
19:38:23 INFO - 1501036703563 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49851
19:38:23 INFO - 1501036703565 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:38:23 INFO - 1501036703567 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}
19:38:23 INFO - 1501036703599 Marionette DEBUG Register listener.js for window 4294967297
19:38:23 INFO - 1501036703609 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8daf84a5-f6d9-4df0-9bf3-559aed0d8058","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2988,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-248.001\\AppData\\Local\\Temp\\tmpy2yrue.mozrunner","moz:accessibilityChecks":false}}]
19:38:23 INFO - 1501036703612 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"}]
19:38:23 INFO - 1501036703631 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"
19:38:23 INFO - 1501036703635 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"
19:38:23 INFO - 1501036703636 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"
19:38:23 INFO - ++DOMWINDOW == 13 (000000FC44B32800) [pid = 3452] [serial = 33] [outer = 000000FC3BB1E000]
19:38:23 INFO - 1501036703662 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"
19:38:23 INFO - 1501036703666 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"
19:38:23 INFO - 1501036703676 Marionette TRACE 400 <- [1,2,null,{}]
19:38:23 INFO - 1501036703691 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:38:23 INFO - 1501036703697 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f4df336-1fb3-4ada-a12a-1394ce2ad9d7","ELEMENT":"1f4df336-1fb3-4ada-a12a-1394ce2ad9d7"}}]
19:38:23 INFO - 1501036703699 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1f4df336-1fb3-4ada-a12a-1394ce2ad9d7"}]
19:38:23 INFO - 1501036703833 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:38:23 INFO - 1501036703835 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1f4df336-1fb3-4ada-a12a-1394ce2ad9d7"}]
19:38:23 INFO - 1501036703975 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element