Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=428381756&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TdmelRErRtGw1_u6_qi-Cg/runs/0/artifacts/public/logs/live_backing.log
[task 2023-09-08T07:34:15.170Z] 07:34:15 INFO - TEST-PASS | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | installTrigger for link2 should have been enabled -
[task 2023-09-08T07:34:15.170Z] 07:34:15 INFO - Buffered messages finished
[task 2023-09-08T07:34:15.171Z] 07:34:15 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | Test timed out -
[task 2023-09-08T07:34:20.049Z] 07:34:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-08T07:34:20.049Z] 07:34:20 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | waiting for vsync to be disabled - timed out after 50 tries. - false == true - {"filename":"chrome://mochikit/content/browser-test.js","name":"ensureVsyncDisabled","sourceId":543,"lineNumber":603,"columnNumber":19,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":543,"lineNumber":768,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":543,"lineNumber":1437,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":543,"lineNumber":1379,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":543,"lineNumber":1120,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":570,"lineNumber":1056,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"timeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"async*nextTest@chrome://mochikit/content/browser-test.js:768:18\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"ensureVsyncDisabled@chrome://mochikit/content/browser-test.js:603:19\nasync*nextTest@chrome://mochikit/content/browser-test.js:768:18\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}}
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - Stack trace:
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:ensureVsyncDisabled:603
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:nextTest:768
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1437
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1379
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1120
[task 2023-09-08T07:34:20.050Z] 07:34:20 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-09-08T07:34:20.055Z] 07:34:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-08T07:34:20.062Z] 07:34:20 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | vsync remained enabled at the end of the test. Is there an animation still running? Consider talking to the performance team for tips to solve this. - false == true - {"filename":"chrome://mochikit/content/browser-test.js","name":"ensureVsyncDisabled","sourceId":543,"lineNumber":604,"columnNumber":19,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":543,"lineNumber":768,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":543,"lineNumber":1437,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":543,"lineNumber":1379,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":543,"lineNumber":1120,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":570,"lineNumber":1056,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"timeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"formattedStack":"async*nextTest@chrome://mochikit/content/browser-test.js:768:18\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"ensureVsyncDisabled@chrome://mochikit/content/browser-test.js:604:19\nasync*nextTest@chrome://mochikit/content/browser-test.js:768:18\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13\n","nativeSavedFrame":{}}
[task 2023-09-08T07:34:20.062Z] 07:34:20 INFO - Stack trace:
[task 2023-09-08T07:34:20.063Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:ensureVsyncDisabled:604
[task 2023-09-08T07:34:20.063Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:nextTest:768
[task 2023-09-08T07:34:20.064Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1437
[task 2023-09-08T07:34:20.064Z] 07:34:20 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1379
[task 2023-09-08T07:34:20.064Z] 07:34:20 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1120
[task 2023-09-08T07:34:20.065Z] 07:34:20 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1056
[task 2023-09-08T07:34:20.066Z] 07:34:20 INFO - GECKO(29288) | MEMORY STAT | vsize 3212MB | residentFast 391MB | heapAllocated 277MB
[task 2023-09-08T07:34:20.067Z] 07:34:20 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | took 50041ms
[task 2023-09-08T07:34:20.068Z] 07:34:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-08T07:34:20.069Z] 07:34:20 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/enabled.html -
[task 2023-09-08T07:34:20.076Z] 07:34:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-08T07:34:20.083Z] 07:34:20 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | Found a tab after previous test timed out: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/bug638292.html -
[task 2023-09-08T07:34:20.135Z] 07:34:20 INFO - checking window state
[task 2023-09-08T07:34:20.205Z] 07:34:20 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_bug645699.js
Description
•