could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:49:54 INFO - 1501058994230 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
01:49:54 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501058994237 Marionette TRACE 352 <- [1,5,null,{"value":null}]
01:49:54 INFO - 1501058994239 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994243 Marionette TRACE 352 <- [1,6,null,{}]
01:49:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1552ms
01:49:54 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994249 Marionette DEBUG Closed connection 352
01:49:54 INFO - 1501058994251 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49788
01:49:54 INFO - 1501058994252 Marionette DEBUG Closed connection 353
01:49:54 INFO - 1501058994254 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49789
01:49:54 INFO - 1501058994256 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:54 INFO - 1501058994258 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:54 INFO - 1501058994279 Marionette DEBUG Register listener.js for window 12
01:49:54 INFO - 1501058994290 Marionette TRACE 354 <- [1,1,null,{"sessionId":"df3d94ef-2d66-4b9c-a61a-544e695db667","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:54 INFO - 1501058994340 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
01:49:54 INFO - 1501058994341 Marionette TRACE 354 <- [1,2,null,{}]
01:49:54 INFO - 1501058994358 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:49:54 INFO - 1501058994360 Marionette TRACE 354 <- [1,3,null,{}]
01:49:54 INFO - 1501058994362 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
01:49:54 INFO - 1501058994363 Marionette TRACE 354 <- [1,4,null,{}]
01:49:54 INFO - 1501058994367 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
01:49:54 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501058994375 Marionette TRACE 354 <- [1,5,null,{"value":null}]
01:49:54 INFO - 1501058994378 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994383 Marionette TRACE 354 <- [1,6,null,{}]
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994405 Marionette DEBUG Closed connection 354
01:49:54 INFO - 1501058994407 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49790
01:49:54 INFO - 1501058994410 Marionette DEBUG Closed connection 355
01:49:54 INFO - 1501058994412 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49791
01:49:54 INFO - 1501058994414 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:54 INFO - 1501058994416 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:54 INFO - 1501058994435 Marionette DEBUG Register listener.js for window 12
01:49:54 INFO - 1501058994447 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6850398a-fea1-4124-8d6f-f17d1831856e","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:54 INFO - 1501058994453 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994457 Marionette TRACE 356 <- [1,2,null,{}]
01:49:54 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:54 INFO - 1501058994485 Marionette DEBUG Closed connection 356
01:49:54 INFO - 1501058994486 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49792
01:49:54 INFO - 1501058994488 Marionette DEBUG Closed connection 357
01:49:54 INFO - 1501058994489 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49793
01:49:54 INFO - 1501058994491 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:49:54 INFO - 1501058994494 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:54 INFO - 1501058994513 Marionette DEBUG Register listener.js for window 12
01:49:54 INFO - 1501058994524 Marionette TRACE 358 <- [1,1,null,{"sessionId":"13bb1f93-3148-4db5-b030-f01899565d41","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:54 INFO - 1501058994536 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49338/clicks.html"}]
01:49:54 INFO - 1501058994540 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/hidden.html"
01:49:54 INFO - 1501058994557 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/hidden.html"
01:49:54 INFO - 1501058994558 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/hidden.html"
01:49:54 INFO - ++DOMWINDOW == 28 (0000009548863000) [pid = 3476] [serial = 40] [outer = 000000953E389000]
01:49:54 INFO - 1501058994642 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/clicks.html"
01:49:54 INFO - 1501058994654 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/clicks.html"
01:49:54 INFO - 1501058994676 Marionette TRACE 358 <- [1,2,null,{}]
01:49:54 INFO - 1501058994683 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:49:54 INFO - 1501058994694 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fbd26854-6e07-459d-bea7-c12ded86aaf5","ELEMENT":"fbd26854-6e07-459d-bea7-c12ded86aaf5"}}]
01:49:54 INFO - 1501058994696 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"fbd26854-6e07-459d-bea7-c12ded86aaf5"}]
01:49:54 INFO - 1501058994924 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/clicks.html"
01:49:54 INFO - [Parent 3476] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
01:49:54 INFO - 1501058994942 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/clicks.html"
01:49:54 INFO - 1501058994944 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/clicks.html"
01:49:54 INFO - ++DOMWINDOW == 29 (000000953B5C2000) [pid = 3476] [serial = 41] [outer = 000000953E389000]
01:49:55 INFO - 1501058995034 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/test.html"
01:49:55 INFO - 1501058995038 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/test.html"
01:49:55 INFO - 1501058995078 Marionette TRACE 358 <- [1,4,null,{}]
01:49:55 INFO - 1501058995083 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:49:55 INFO - 1501058995096 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e217f422-d7e3-4c59-955d-2613fd12577e","ELEMENT":"e217f422-d7e3-4c59-955d-2613fd12577e"}}]
01:49:55 INFO - 1501058995107 Marionette TRACE 358 -> [0,6,"getTitle",{}]
01:49:55 INFO - 1501058995111 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
01:49:55 INFO - 1501058995117 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
01:49:55 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501058995131 Marionette TRACE 358 <- [1,7,null,{"value":null}]
01:49:55 INFO - 1501058995134 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995145 Marionette TRACE 358 <- [1,8,null,{}]
01:49:55 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 902ms
01:49:55 INFO - TEST-START | test_click.py TestClick.test_container_element
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995155 Marionette DEBUG Closed connection 358
01:49:55 INFO - 1501058995159 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49796
01:49:55 INFO - 1501058995176 Marionette DEBUG Closed connection 359
01:49:55 INFO - 1501058995179 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49797
01:49:55 INFO - 1501058995204 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:55 INFO - 1501058995214 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:55 INFO - 1501058995251 Marionette DEBUG Register listener.js for window 12
01:49:55 INFO - 1501058995273 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4dc4bdd5-67d3-44f2-b822-847bc9039a00","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:55 INFO - 1501058995290 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
01:49:55 INFO - 1501058995292 Marionette TRACE 360 <- [1,2,null,{}]
01:49:55 INFO - 1501058995304 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:49:55 INFO - 1501058995307 Marionette TRACE 360 <- [1,3,null,{}]
01:49:55 INFO - 1501058995311 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
01:49:55 INFO - 1501058995313 Marionette TRACE 360 <- [1,4,null,{}]
01:49:55 INFO - 1501058995318 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
01:49:55 INFO - TEST-START: test_click.py TestClick.test_container_element1501058995331 Marionette TRACE 360 <- [1,5,null,{"value":null}]
01:49:55 INFO - 1501058995383 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995391 Marionette TRACE 360 <- [1,6,null,{}]
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995417 Marionette DEBUG Closed connection 360
01:49:55 INFO - 1501058995420 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49798
01:49:55 INFO - 1501058995425 Marionette DEBUG Closed connection 361
01:49:55 INFO - 1501058995427 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49799
01:49:55 INFO - 1501058995485 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:55 INFO - 1501058995490 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:55 INFO - 1501058995526 Marionette DEBUG Register listener.js for window 12
01:49:55 INFO - 1501058995543 Marionette TRACE 362 <- [1,1,null,{"sessionId":"81f2801d-9576-4816-8371-4121ae331221","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:55 INFO - 1501058995558 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995565 Marionette TRACE 362 <- [1,2,null,{}]
01:49:55 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:55 INFO - 1501058995572 Marionette DEBUG Closed connection 362
01:49:55 INFO - 1501058995574 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49800
01:49:55 INFO - 1501058995587 Marionette DEBUG Closed connection 363
01:49:55 INFO - 1501058995590 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49801
01:49:55 INFO - 1501058995592 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:49:55 INFO - 1501058995596 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:55 INFO - 1501058995632 Marionette DEBUG Register listener.js for window 12
01:49:55 INFO - 1501058995650 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1b56ead2-4af9-40e2-a1c0-bf960c283034","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:55 INFO - 1501058995680 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:49:55 INFO - 1501058995686 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/test.html"
01:49:55 INFO - 1501058995706 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/test.html"
01:49:55 INFO - 1501058995707 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/test.html"
01:49:55 INFO - ++DOMWINDOW == 30 (000000953514F800) [pid = 3476] [serial = 42] [outer = 000000953E389000]
01:49:55 INFO - 1501058995782 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:55 INFO - 1501058995789 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:55 INFO - 1501058995810 Marionette TRACE 364 <- [1,2,null,{}]
01:49:55 INFO - 1501058995817 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:49:55 INFO - 1501058995829 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8448137c-5809-4b12-8ab9-52be4498ce7c","ELEMENT":"8448137c-5809-4b12-8ab9-52be4498ce7c"}}]
01:49:55 INFO - 1501058995831 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8448137c-5809-4b12-8ab9-52be4498ce7c"}]
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
01:49:56 INFO - 1501058996389 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:49:56 INFO - 1501058996392 Marionette TRACE 364 <- [1,4,null,{}]
01:49:56 INFO - 1501058996395 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8448137c-5809-4b12-8ab9-52be4498ce7c","name":"selected"}]
01:49:56 INFO - 1501058996440 Marionette TRACE 364 <- [1,5,null,{"value":true}]
01:49:56 INFO - 1501058996448 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
01:49:56 INFO - TEST-END: test_click.py TestClick.test_container_element1501058996459 Marionette TRACE 364 <- [1,6,null,{"value":null}]
01:49:56 INFO - 1501058996511 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:56 INFO - 1501058996516 Marionette TRACE 364 <- [1,7,null,{}]
01:49:56 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1372ms
01:49:56 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:56 INFO - 1501058996542 Marionette DEBUG Closed connection 364
01:49:56 INFO - 1501058996547 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49802
01:49:56 INFO - 1501058996551 Marionette DEBUG Closed connection 365
01:49:56 INFO - 1501058996554 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49803
01:49:56 INFO - 1501058996565 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:56 INFO - 1501058996569 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:56 INFO - 1501058996608 Marionette DEBUG Register listener.js for window 12
01:49:56 INFO - 1501058996625 Marionette TRACE 366 <- [1,1,null,{"sessionId":"348b2db3-b464-4322-b858-c406ad7d5cae","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:56 INFO - 1501058996637 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
01:49:56 INFO - 1501058996639 Marionette TRACE 366 <- [1,2,null,{}]
01:49:56 INFO - 1501058996828 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:49:56 INFO - 1501058996834 Marionette TRACE 366 <- [1,3,null,{}]
01:49:56 INFO - 1501058996839 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
01:49:56 INFO - 1501058996844 Marionette TRACE 366 <- [1,4,null,{}]
01:49:56 INFO - 1501058996923 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
01:49:56 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501058996935 Marionette TRACE 366 <- [1,5,null,{"value":null}]
01:49:56 INFO - 1501058996944 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
01:49:56 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:56 INFO - 1501058996953 Marionette TRACE 366 <- [1,6,null,{}]
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:57 INFO - 1501058997011 Marionette DEBUG Closed connection 366
01:49:57 INFO - 1501058997014 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49804
01:49:57 INFO - 1501058997040 Marionette DEBUG Closed connection 367
01:49:57 INFO - 1501058997044 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49805
01:49:57 INFO - 1501058997049 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:57 INFO - 1501058997058 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:57 INFO - 1501058997080 Marionette DEBUG Register listener.js for window 12
01:49:57 INFO - 1501058997101 Marionette TRACE 368 <- [1,1,null,{"sessionId":"7ecf90b7-1e3e-4780-a106-6869ce3f2dbf","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:57 INFO - 1501058997115 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:57 INFO - 1501058997122 Marionette TRACE 368 <- [1,2,null,{}]
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:57 INFO - 1501058997151 Marionette DEBUG Closed connection 368
01:49:57 INFO - 1501058997154 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49806
01:49:57 INFO - 1501058997158 Marionette DEBUG Closed connection 369
01:49:57 INFO - 1501058997160 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49807
01:49:57 INFO - 1501058997171 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:49:57 INFO - 1501058997176 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:57 INFO - 1501058997197 Marionette DEBUG Register listener.js for window 12
01:49:57 INFO - 1501058997214 Marionette TRACE 370 <- [1,1,null,{"sessionId":"048d019b-097e-45e1-8184-5bb866a1eba0","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:57 INFO - 1501058997224 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:49:57 INFO - 1501058997230 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:57 INFO - 1501058997239 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:57 INFO - 1501058997240 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:57 INFO - ++DOMWINDOW == 31 (000000953268E800) [pid = 3476] [serial = 43] [outer = 000000953E389000]
01:49:57 INFO - 1501058997301 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:57 INFO - 1501058997307 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:57 INFO - 1501058997329 Marionette TRACE 370 <- [1,2,null,{}]
01:49:57 INFO - 1501058997340 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:49:57 INFO - 1501058997350 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a12a74fb-8f16-47c3-9d2b-19f71c3f879d","ELEMENT":"a12a74fb-8f16-47c3-9d2b-19f71c3f879d"}}]
01:49:57 INFO - 1501058997352 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a12a74fb-8f16-47c3-9d2b-19f71c3f879d"}]
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
01:49:57 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
01:49:57 INFO - --DOMWINDOW == 30 (0000009546208000) [pid = 3476] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 29 (0000009542E73000) [pid = 3476] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 28 (00000095331D3800) [pid = 3476] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 27 (000000953985D000) [pid = 3476] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 26 (0000009539864000) [pid = 3476] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 25 (000000953986E800) [pid = 3476] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:57 INFO - --DOMWINDOW == 24 (000000953D76C000) [pid = 3476] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:49:58 INFO - 1501058998463 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:49:58 INFO - 1501058998485 Marionette TRACE 370 <- [1,4,null,{}]
01:49:58 INFO - 1501058998534 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a12a74fb-8f16-47c3-9d2b-19f71c3f879d","name":"selected"}]
01:49:58 INFO - 1501058998565 Marionette TRACE 370 <- [1,5,null,{"value":true}]
01:49:58 INFO - 1501058998568 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
01:49:58 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501058998580 Marionette TRACE 370 <- [1,6,null,{"value":null}]
01:49:58 INFO - 1501058998628 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
01:49:58 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:58 INFO - 1501058998634 Marionette TRACE 370 <- [1,7,null,{}]
01:49:58 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 2116ms
01:49:58 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:49:58 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:58 INFO - 1501058998640 Marionette DEBUG Closed connection 370
01:49:58 INFO - 1501058998642 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49808
01:49:58 INFO - 1501058998645 Marionette DEBUG Closed connection 371
01:49:58 INFO - 1501058998647 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49809
01:49:58 INFO - 1501058998649 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:58 INFO - 1501058998652 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:58 INFO - 1501058998673 Marionette DEBUG Register listener.js for window 12
01:49:58 INFO - 1501058998689 Marionette TRACE 372 <- [1,1,null,{"sessionId":"bca84aec-b9f9-4637-be70-1bfaebcde67d","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:58 INFO - 1501058998750 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
01:49:58 INFO - 1501058998752 Marionette TRACE 372 <- [1,2,null,{}]
01:49:58 INFO - 1501058998805 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:49:58 INFO - 1501058998807 Marionette TRACE 372 <- [1,3,null,{}]
01:49:58 INFO - 1501058998860 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
01:49:58 INFO - 1501058998861 Marionette TRACE 372 <- [1,4,null,{}]
01:49:58 INFO - 1501058998913 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
01:49:58 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501058998922 Marionette TRACE 372 <- [1,5,null,{"value":null}]
01:49:58 INFO - 1501058998945 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
01:49:58 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:58 INFO - 1501058998949 Marionette TRACE 372 <- [1,6,null,{}]
01:49:58 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:58 INFO - 1501058998956 Marionette DEBUG Closed connection 372
01:49:58 INFO - 1501058998958 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49810
01:49:58 INFO - 1501058998979 Marionette DEBUG Closed connection 373
01:49:58 INFO - 1501058998981 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49811
01:49:58 INFO - 1501058998984 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:58 INFO - 1501058998987 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:59 INFO - 1501058999009 Marionette DEBUG Register listener.js for window 12
01:49:59 INFO - 1501058999023 Marionette TRACE 374 <- [1,1,null,{"sessionId":"cb077998-c54c-47b7-bf51-9d4a1acacc9c","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:59 INFO - 1501058999033 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999038 Marionette TRACE 374 <- [1,2,null,{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999059 Marionette DEBUG Closed connection 374
01:49:59 INFO - 1501058999061 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49812
01:49:59 INFO - 1501058999066 Marionette DEBUG Closed connection 375
01:49:59 INFO - 1501058999067 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49813
01:49:59 INFO - 1501058999070 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:49:59 INFO - 1501058999075 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:59 INFO - 1501058999096 Marionette DEBUG Register listener.js for window 12
01:49:59 INFO - 1501058999111 Marionette TRACE 376 <- [1,1,null,{"sessionId":"91d6b37a-9a25-45c4-a2d6-07dadd91dd3e","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:59 INFO - 1501058999121 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
01:49:59 INFO - 1501058999126 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:59 INFO - 1501058999135 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:59 INFO - 1501058999136 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:49:59 INFO - ++DOMWINDOW == 25 (0000009538C26000) [pid = 3476] [serial = 44] [outer = 000000953E389000]
01:49:59 INFO - 1501058999199 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:49:59 INFO - 1501058999204 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:49:59 INFO - 1501058999225 Marionette TRACE 376 <- [1,2,null,{}]
01:49:59 INFO - 1501058999233 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:49:59 INFO - 1501058999241 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eab4ccdc-5634-4f61-8e79-9b8df86c3c96","ELEMENT":"eab4ccdc-5634-4f61-8e79-9b8df86c3c96"}}]
01:49:59 INFO - 1501058999243 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"eab4ccdc-5634-4f61-8e79-9b8df86c3c96"}]
01:49:59 INFO - 1501058999690 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:49:59 INFO - 1501058999692 Marionette TRACE 376 <- [1,4,null,{}]
01:49:59 INFO - 1501058999695 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
01:49:59 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501058999708 Marionette TRACE 376 <- [1,5,null,{"value":null}]
01:49:59 INFO - 1501058999710 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999715 Marionette TRACE 376 <- [1,6,null,{}]
01:49:59 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1080ms
01:49:59 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999723 Marionette DEBUG Closed connection 376
01:49:59 INFO - 1501058999728 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49814
01:49:59 INFO - 1501058999730 Marionette DEBUG Closed connection 377
01:49:59 INFO - 1501058999732 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49815
01:49:59 INFO - 1501058999734 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:59 INFO - 1501058999736 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:59 INFO - 1501058999757 Marionette DEBUG Register listener.js for window 12
01:49:59 INFO - 1501058999797 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5d999615-fe7f-4cbe-bd62-f06ff12b449f","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:59 INFO - 1501058999804 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
01:49:59 INFO - 1501058999805 Marionette TRACE 378 <- [1,2,null,{}]
01:49:59 INFO - 1501058999809 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:49:59 INFO - 1501058999811 Marionette TRACE 378 <- [1,3,null,{}]
01:49:59 INFO - 1501058999813 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
01:49:59 INFO - 1501058999814 Marionette TRACE 378 <- [1,4,null,{}]
01:49:59 INFO - 1501058999817 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
01:49:59 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501058999825 Marionette TRACE 378 <- [1,5,null,{"value":null}]
01:49:59 INFO - 1501058999827 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999832 Marionette TRACE 378 <- [1,6,null,{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999838 Marionette DEBUG Closed connection 378
01:49:59 INFO - 1501058999839 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49816
01:49:59 INFO - 1501058999841 Marionette DEBUG Closed connection 379
01:49:59 INFO - 1501058999843 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49817
01:49:59 INFO - 1501058999845 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:49:59 INFO - 1501058999847 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:59 INFO - 1501058999869 Marionette DEBUG Register listener.js for window 12
01:49:59 INFO - 1501058999881 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1f230dd4-aa2a-433a-b525-8991024744e0","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:59 INFO - 1501058999888 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999892 Marionette TRACE 380 <- [1,2,null,{}]
01:49:59 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:49:59 INFO - 1501058999898 Marionette DEBUG Closed connection 380
01:49:59 INFO - 1501058999899 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49818
01:49:59 INFO - 1501058999901 Marionette DEBUG Closed connection 381
01:49:59 INFO - 1501058999903 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49819
01:49:59 INFO - 1501058999905 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:49:59 INFO - 1501058999909 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:49:59 INFO - 1501058999930 Marionette DEBUG Register listener.js for window 12
01:49:59 INFO - 1501058999956 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7e92d676-6f47-46d7-9efa-6f311474561b","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:49:59 INFO - 1501058999964 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
01:49:59 INFO - 1501058999969 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:49:59 INFO - 1501058999975 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:49:59 INFO - 1501058999976 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
01:49:59 INFO - ++DOMWINDOW == 26 (000000953985A000) [pid = 3476] [serial = 45] [outer = 000000953E389000]
01:50:00 INFO - 1501059000035 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:50:00 INFO - 1501059000041 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:50:00 INFO - 1501059000059 Marionette TRACE 382 <- [1,2,null,{}]
01:50:00 INFO - 1501059000062 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:50:00 INFO - 1501059000071 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3550618b-7002-487b-a769-1af63a39f13e","ELEMENT":"3550618b-7002-487b-a769-1af63a39f13e"}}]
01:50:00 INFO - 1501059000074 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"3550618b-7002-487b-a769-1af63a39f13e"}]
01:50:00 INFO - 1501059000589 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:50:00 INFO - 1501059000590 Marionette TRACE 382 <- [1,4,null,{}]
01:50:00 INFO - 1501059000593 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
01:50:00 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501059000602 Marionette TRACE 382 <- [1,5,null,{"value":null}]
01:50:00 INFO - 1501059000604 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
01:50:00 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:00 INFO - 1501059000609 Marionette TRACE 382 <- [1,6,null,{}]
01:50:00 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 894ms
01:50:00 INFO - TEST-START | test_click.py TestClick.test_input_file
01:50:00 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:00 INFO - 1501059000634 Marionette DEBUG Closed connection 382
01:50:00 INFO - 1501059000636 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49820
01:50:00 INFO - 1501059000640 Marionette DEBUG Closed connection 383
01:50:00 INFO - 1501059000641 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49821
01:50:00 INFO - 1501059000645 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:00 INFO - 1501059000648 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:00 INFO - 1501059000669 Marionette DEBUG Register listener.js for window 12
01:50:00 INFO - 1501059000681 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5b26c54e-deec-41bd-9671-85dae70abb13","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:00 INFO - 1501059000692 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
01:50:00 INFO - 1501059000694 Marionette TRACE 384 <- [1,2,null,{}]
01:50:01 INFO - 1501059001280 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:50:01 INFO - 1501059001282 Marionette TRACE 384 <- [1,3,null,{}]
01:50:01 INFO - 1501059001317 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
01:50:01 INFO - 1501059001319 Marionette TRACE 384 <- [1,4,null,{}]
01:50:01 INFO - 1501059001322 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
01:50:01 INFO - TEST-START: test_click.py TestClick.test_input_file1501059001331 Marionette TRACE 384 <- [1,5,null,{"value":null}]
01:50:01 INFO - 1501059001333 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - 1501059001337 Marionette TRACE 384 <- [1,6,null,{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - 1501059001343 Marionette DEBUG Closed connection 384
01:50:01 INFO - 1501059001345 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49822
01:50:01 INFO - 1501059001346 Marionette DEBUG Closed connection 385
01:50:01 INFO - 1501059001348 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49823
01:50:01 INFO - 1501059001350 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:01 INFO - 1501059001353 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:01 INFO - 1501059001375 Marionette DEBUG Register listener.js for window 12
01:50:01 INFO - 1501059001422 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f65ac95d-5f4f-46ca-8c85-edc6a873215e","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:01 INFO - 1501059001452 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - 1501059001456 Marionette TRACE 386 <- [1,2,null,{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - 1501059001484 Marionette DEBUG Closed connection 386
01:50:01 INFO - 1501059001486 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49824
01:50:01 INFO - 1501059001490 Marionette DEBUG Closed connection 387
01:50:01 INFO - 1501059001491 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49825
01:50:01 INFO - 1501059001494 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:50:01 INFO - 1501059001498 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:01 INFO - 1501059001525 Marionette DEBUG Register listener.js for window 12
01:50:01 INFO - 1501059001550 Marionette TRACE 388 <- [1,1,null,{"sessionId":"19345e2e-3c63-4b27-874a-db10618eb855","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:01 INFO - 1501059001561 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:50:01 INFO - 1501059001565 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:50:01 INFO - 1501059001575 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:50:01 INFO - 1501059001577 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
01:50:01 INFO - ++DOMWINDOW == 27 (0000009539865800) [pid = 3476] [serial = 46] [outer = 000000953E389000]
01:50:01 INFO - 1501059001636 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:50:01 INFO - 1501059001642 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:50:01 INFO - 1501059001660 Marionette TRACE 388 <- [1,2,null,{}]
01:50:01 INFO - 1501059001666 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:50:01 INFO - 1501059001675 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a5ed980-2281-4836-a504-2662b0f89c7e","ELEMENT":"8a5ed980-2281-4836-a504-2662b0f89c7e"}}]
01:50:01 INFO - 1501059001677 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8a5ed980-2281-4836-a504-2662b0f89c7e"}]
01:50:01 INFO - 1501059001837 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:50:01 INFO - 1501059001841 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
01:50:01 INFO - TEST-END: test_click.py TestClick.test_input_file1501059001849 Marionette TRACE 388 <- [1,5,null,{"value":null}]
01:50:01 INFO - 1501059001902 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1305ms
01:50:01 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:50:01 INFO - 1501059001912 Marionette TRACE 388 <- [1,6,null,{}]
01:50:01 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:01 INFO - 1501059001941 Marionette DEBUG Closed connection 388
01:50:01 INFO - 1501059001945 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49826
01:50:02 INFO - 1501059001998 Marionette DEBUG Closed connection 389
01:50:02 INFO - 1501059002001 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49827
01:50:02 INFO - 1501059002009 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:02 INFO - 1501059002019 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:02 INFO - 1501059002059 Marionette DEBUG Register listener.js for window 12
01:50:02 INFO - 1501059002081 Marionette TRACE 390 <- [1,1,null,{"sessionId":"4b1dfc50-ba21-4c98-a8c8-cb145a010023","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:02 INFO - 1501059002101 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
01:50:02 INFO - 1501059002106 Marionette TRACE 390 <- [1,2,null,{}]
01:50:02 INFO - 1501059002160 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:50:02 INFO - 1501059002164 Marionette TRACE 390 <- [1,3,null,{}]
01:50:02 INFO - 1501059002169 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
01:50:02 INFO - 1501059002173 Marionette TRACE 390 <- [1,4,null,{}]
01:50:02 INFO - 1501059002226 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
01:50:02 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501059002240 Marionette TRACE 390 <- [1,5,null,{"value":null}]
01:50:02 INFO - 1501059002247 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
01:50:02 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:02 INFO - 1501059002256 Marionette TRACE 390 <- [1,6,null,{}]
01:50:02 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:02 INFO - 1501059002275 Marionette DEBUG Closed connection 390
01:50:02 INFO - 1501059002279 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49828
01:50:02 INFO - 1501059002281 Marionette DEBUG Closed connection 391
01:50:02 INFO - 1501059002283 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49829
01:50:02 INFO - 1501059002289 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:02 INFO - 1501059002293 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:02 INFO - 1501059002332 Marionette DEBUG Register listener.js for window 12
01:50:02 INFO - 1501059002349 Marionette TRACE 392 <- [1,1,null,{"sessionId":"94ca7f2f-bb03-4d11-b811-4fa7e8858574","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:02 INFO - 1501059002380 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
01:50:02 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:02 INFO - 1501059002387 Marionette TRACE 392 <- [1,2,null,{}]
01:50:02 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:02 INFO - 1501059002421 Marionette DEBUG Closed connection 392
01:50:02 INFO - 1501059002424 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49830
01:50:02 INFO - 1501059002429 Marionette DEBUG Closed connection 393
01:50:02 INFO - 1501059002431 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49831
01:50:02 INFO - 1501059002456 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:50:02 INFO - 1501059002461 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:02 INFO - 1501059002500 Marionette DEBUG Register listener.js for window 12
01:50:02 INFO - 1501059002517 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c0993082-8a4f-486e-80ca-7d580ff2a7f6","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:02 INFO - 1501059002534 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
01:50:02 INFO - 1501059002542 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:50:02 INFO - 1501059002552 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:50:02 INFO - 1501059002553 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:50:02 INFO - ++DOMWINDOW == 28 (000000953471D000) [pid = 3476] [serial = 47] [outer = 000000953E389000]
01:50:02 INFO - 1501059002846 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:50:02 INFO - 1501059002855 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:50:02 INFO - 1501059002874 Marionette TRACE 394 <- [1,2,null,{}]
01:50:03 INFO - 1501059003105 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:50:03 INFO - 1501059003117 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff965193-36a4-44fa-b14a-9bed249cef96","ELEMENT":"ff965193-36a4-44fa-b14a-9bed249cef96"}}]
01:50:03 INFO - 1501059003121 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:50:03 INFO - 1501059003132 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b95eceec-6a5c-4246-a89f-ca114be4e044","ELEMENT":"b95eceec-6a5c-4246-a89f-ca114be4e044"}}]
01:50:03 INFO - 1501059003140 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ff965193-36a4-44fa-b14a-9bed249cef96"}]
01:50:03 INFO - 1501059003545 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:50:03 INFO - 1501059003548 Marionette TRACE 394 <- [1,5,null,{}]
01:50:03 INFO - 1501059003553 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b95eceec-6a5c-4246-a89f-ca114be4e044"}]
01:50:03 INFO - 1501059003642 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
01:50:03 INFO - 1501059003649 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:50:03 INFO - 1501059003662 Marionette TRACE 394 <- [1,7,null,{"value":false}]
01:50:03 INFO - 1501059003673 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
01:50:03 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501059003684 Marionette TRACE 394 <- [1,8,null,{"value":null}]
01:50:03 INFO - 1501059003687 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
01:50:03 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:03 INFO - 1501059003694 Marionette TRACE 394 <- [1,9,null,{}]
01:50:03 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1781ms
01:50:03 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:50:03 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:03 INFO - 1501059003702 Marionette DEBUG Closed connection 394
01:50:03 INFO - 1501059003704 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49832
01:50:03 INFO - --DOMWINDOW == 27 (000000953383C800) [pid = 3476] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:50:03 INFO - --DOMWINDOW == 26 (0000009537169000) [pid = 3476] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:50:03 INFO - --DOMWINDOW == 25 (0000009546888800) [pid = 3476] [serial = 36] [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]
01:50:03 INFO - --DOMWINDOW == 24 (0000009538C24800) [pid = 3476] [serial = 32] [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]
01:50:03 INFO - --DOMWINDOW == 23 (00000095436BA800) [pid = 3476] [serial = 33] [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]
01:50:03 INFO - --DOMWINDOW == 22 (0000009546213800) [pid = 3476] [serial = 34] [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]
01:50:03 INFO - --DOMWINDOW == 21 (0000009546C2B000) [pid = 3476] [serial = 35] [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]
01:50:03 INFO - --DOMWINDOW == 20 (000000954885F800) [pid = 3476] [serial = 37] [outer = 0000000000000000] [url = http://127.0.0.1:49338/clicks.html]
01:50:03 INFO - --DOMWINDOW == 19 (0000009548A6E000) [pid = 3476] [serial = 38] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test.html]
01:50:03 INFO - --DOMWINDOW == 18 (0000009546B4F800) [pid = 3476] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
01:50:03 INFO - 1501059003750 Marionette DEBUG Closed connection 395
01:50:03 INFO - 1501059003753 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49833
01:50:03 INFO - 1501059003756 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:03 INFO - 1501059003764 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:03 INFO - 1501059003787 Marionette DEBUG Register listener.js for window 12
01:50:03 INFO - 1501059003805 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8a23f7b4-f20b-4e4d-be6d-4c1f8f4a364a","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:03 INFO - 1501059003818 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
01:50:03 INFO - 1501059003820 Marionette TRACE 396 <- [1,2,null,{}]
01:50:03 INFO - 1501059003822 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:50:03 INFO - 1501059003825 Marionette TRACE 396 <- [1,3,null,{}]
01:50:03 INFO - 1501059003832 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
01:50:03 INFO - 1501059003836 Marionette TRACE 396 <- [1,4,null,{}]
01:50:03 INFO - 1501059003839 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
01:50:03 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501059003867 Marionette TRACE 396 <- [1,5,null,{"value":null}]
01:50:03 INFO - 1501059003873 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
01:50:03 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:03 INFO - 1501059003878 Marionette TRACE 396 <- [1,6,null,{}]
01:50:03 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:03 INFO - 1501059003886 Marionette DEBUG Closed connection 396
01:50:03 INFO - 1501059003889 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49834
01:50:03 INFO - 1501059003891 Marionette DEBUG Closed connection 397
01:50:03 INFO - 1501059003893 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49835
01:50:03 INFO - 1501059003895 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:50:03 INFO - 1501059003898 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:03 INFO - 1501059003920 Marionette DEBUG Register listener.js for window 12
01:50:03 INFO - 1501059003938 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cdfdc0ab-ec99-442c-b096-35a14e463229","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:03 INFO - 1501059003971 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
01:50:03 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:03 INFO - 1501059003976 Marionette TRACE 398 <- [1,2,null,{}]
01:50:04 INFO - [Parent 3476] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
01:50:04 INFO - 1501059004098 Marionette DEBUG Closed connection 398
01:50:04 INFO - 1501059004100 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49836
01:50:04 INFO - 1501059004119 Marionette DEBUG Closed connection 399
01:50:04 INFO - 1501059004121 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49837
01:50:04 INFO - 1501059004124 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:50:04 INFO - 1501059004128 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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}
01:50:04 INFO - 1501059004150 Marionette DEBUG Register listener.js for window 12
01:50:04 INFO - 1501059004164 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f6ef32f0-cc1f-4656-801b-98b7e9ec53dd","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":3476,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-282.001\\AppData\\Local\\Temp\\tmpoor1zi.mozrunner","moz:accessibilityChecks":false}}]
01:50:04 INFO - 1501059004175 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
01:50:04 INFO - 1501059004181 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:50:04 INFO - 1501059004190 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:50:04 INFO - 1501059004191 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
01:50:04 INFO - ++DOMWINDOW == 19 (0000009539865000) [pid = 3476] [serial = 48] [outer = 000000953E389000]
01:50:04 INFO - 1501059004255 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
01:50:04 INFO - 1501059004260 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
01:50:04 INFO - 1501059004279 Marionette TRACE 400 <- [1,2,null,{}]
01:50:04 INFO - 1501059004283 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:50:04 INFO - 1501059004293 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce6c88d7-2c15-4c56-8b64-c6a9667a37af","ELEMENT":"ce6c88d7-2c15-4c56-8b64-c6a9667a37af"}}]
01:50:04 INFO - 1501059004295 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ce6c88d7-2c15-4c56-8b64-c6a9667a37af"}]
01:50:04 INFO - 1501059004417 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
01:50:04 INFO - 1501059004429 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ce6c88d7-2c15-4c56-8b64-c6a9667a37af"}]
01:50:04 INFO - 1501059004518 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element