1861451 - Permafailing browser/components/places/tests/browser/browser_default_bookmark_location.js | Test timed out -

Status: RESOLVED FIXED

(Firefox :: Bookmarks & History, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=433897142&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IHTZWkSaSBSqEX8C7POedw/runs/0/artifacts/public/logs/live_backing.log

---

task 2023-10-26T14:37:29.073Z] 14:37:29     INFO - TEST-PASS | browser/components/places/tests/browser/browser_default_bookmark_location.js | checking if popup is closed - 
[task 2023-10-26T14:37:29.073Z] 14:37:29     INFO - Buffered messages finished
[task 2023-10-26T14:37:29.074Z] 14:37:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_default_bookmark_location.js | Test timed out - 
[task 2023-10-26T14:37:29.074Z] 14:37:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-10-26T14:37:29.075Z] 14:37:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_default_bookmark_location.js | Uncaught exception received from previously timed out test bound test_context_menu_link - subdialog-loaded observer not removed before the end of test
[task 2023-10-26T14:37:29.075Z] 14:37:29     INFO - Entering test bound test_change_location_panel
[task 2023-10-26T14:37:29.076Z] 14:37:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-10-26T14:37:29.077Z] 14:37:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_default_bookmark_location.js | Uncaught exception received from previously timed out test bound test_change_location_panel - at chrome://mochitests/content/browser/browser/components/places/tests/browser/head.js:481 - TypeError: can't access property "document", win is null
[task 2023-10-26T14:37:29.077Z] 14:37:29     INFO - Stack trace:
[task 2023-10-26T14:37:29.078Z] 14:37:29     INFO - clickBookmarkStar@chrome://mochitests/content/browser/browser/components/places/tests/browser/head.js:481:5
[task 2023-10-26T14:37:29.078Z] 14:37:29     INFO - test_change_location_panel@chrome://mochitests/content/browser/browser/components/places/tests/browser/browser_default_bookmark_location.js:150:9
[task 2023-10-26T14:37:29.079Z] 14:37:29     INFO - handleTask@chrome://mochikit/content/browser-test.js:1134:26
[task 2023-10-26T14:37:29.079Z] 14:37:29     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1206:18
[task 2023-10-26T14:37:29.079Z] 14:37:29     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1348:14
[task 2023-10-26T14:37:29.079Z] 14:37:29     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1123:14
[task 2023-10-26T14:37:29.079Z] 14:37:29     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2023-10-26T14:37:29.080Z] 14:37:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-10-26T14:37:29.082Z] 14:37:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_default_bookmark_location.js | A promise chain failed to handle a rejection: popupshown listener on #contentAreaContextMenu not removed before the end of test - stack: openDialog@chrome://mochitests/content/browser/browser/components/places/tests/browser/browser_default_bookmark_location.js:127:7
[task 2023-10-26T14:37:29.083Z] 14:37:29     INFO - async*run@chrome://mochikit/content/browser-test.js:1717:9
[task 2023-10-26T14:37:29.083Z] 14:37:29     INFO - Rejection date: Thu Oct 26 2023 14:37:28 GMT+0000 (Coordinated Universal Time) - false == true - {"filename":"resource://testing-common/PromiseTestUtils.sys.mjs","name":"assertNoUncaughtRejections","sourceId":587,"lineNumber":266,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":551,"lineNumber":769,"columnNumber":29,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":551,"lineNumber":1440,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":551,"lineNumber":1382,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":551,"lineNumber":1123,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":578,"lineNumber":1058,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"nextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertNoUncaughtRejections@resource://testing-common/PromiseTestUtils.sys.mjs:266:14\nnextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}}
[task 2023-10-26T14:37:29.084Z] 14:37:29     INFO - Stack trace:
[task 2023-10-26T14:37:29.084Z] 14:37:29     INFO - resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-10-26T14:37:29.085Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:nextTest:769
[task 2023-10-26T14:37:29.085Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1440
[task 2023-10-26T14:37:29.086Z] 14:37:29     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1382
[task 2023-10-26T14:37:29.086Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1123
[task 2023-10-26T14:37:29.086Z] 14:37:29     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2023-10-26T14:37:29.087Z] 14:37:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-10-26T14:37:29.090Z] 14:37:29     INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_default_bookmark_location.js | A promise chain failed to handle a rejection: subdialog-loaded observer not removed before the end of test - stack: ensureDOMPromiseRejectionsProcessed@resource://testing-common/PromiseTestUtils.sys.mjs:115:17
[task 2023-10-26T14:37:29.090Z] 14:37:29     INFO - nextTest@chrome://mochikit/content/browser-test.js:768:29
[task 2023-10-26T14:37:29.090Z] 14:37:29     INFO - async*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18
[task 2023-10-26T14:37:29.091Z] 14:37:29     INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45
[task 2023-10-26T14:37:29.091Z] 14:37:29     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1123:14
[task 2023-10-26T14:37:29.092Z] 14:37:29     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2023-10-26T14:37:29.092Z] 14:37:29     INFO - Rejection date: Thu Oct 26 2023 14:37:28 GMT+0000 (Coordinated Universal Time) - false == true - {"filename":"resource://testing-common/PromiseTestUtils.sys.mjs","name":"assertNoUncaughtRejections","sourceId":587,"lineNumber":266,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":551,"lineNumber":769,"columnNumber":29,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":551,"lineNumber":1440,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":551,"lineNumber":1382,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":551,"lineNumber":1123,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":578,"lineNumber":1058,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"nextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertNoUncaughtRejections@resource://testing-common/PromiseTestUtils.sys.mjs:266:14\nnextTest@chrome://mochikit/content/browser-test.js:769:29\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1440:18\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1382:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1123:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}}
[task 2023-10-26T14:37:29.093Z] 14:37:29     INFO - Stack trace:
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:nextTest:769
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1440
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1382
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1123
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2023-10-26T14:37:29.094Z] 14:37:29     INFO - GECKO(26412) | JavaScript error: chrome://mochitests/content/browser/browser/components/places/tests/browser/browser_default_bookmark_location.js, line 127: uncaught exception: popupshown listener on #contentAreaContextMenu not removed before the end of test
[task 2023-10-26T14:37:29.096Z] 14:37:29     INFO - GECKO(26412) | JavaScript error: resource://testing-common/PromiseTestUtils.sys.mjs, line 115: uncaught exception: subdialog-loaded observer not removed before the end of test
[task 2023-10-26T14:37:29.097Z] 14:37:29     INFO - Console message: [JavaScript Error: "uncaught exception: popupshown listener on #contentAreaContextMenu not removed before the end of test" {file: "chrome://mochitests/content/browser/browser/components/places/tests/browser/browser_default_bookmark_location.js" line: 127}]
[task 2023-10-26T14:37:29.097Z] 14:37:29     INFO - openDialog@chrome://mochitests/content/browser/browser/components/places/tests/browser/browser_default_bookmark_location.js:127:7
[task 2023-10-26T14:37:29.097Z] 14:37:29     INFO - async*run@chrome://mochikit/content/browser-test.js:1717:9
[task 2023-10-26T14:37:29.098Z] 14:37:29     INFO - 
[task 2023-10-26T14:37:29.098Z] 14:37:29     INFO - Console message: [JavaScript Error: "uncaught exception: subdialog-loaded observer not removed before the end of test" {file: "resource://testing-common/PromiseTestUtils.sys.mjs" line: 115}]
[task 2023-10-26T14:37:29.296Z] 14:37:29     INFO - GECKO(26412) | MEMORY STAT | vsize 4128MB | residentFast 619MB | heapAllocated 475MB
[task 2023-10-26T14:37:29.297Z] 14:37:29     INFO - TEST-OK | browser/components/places/tests/browser/browser_default_bookmark_location.js | took 50249ms

Comment 1

[Intermittent Failures Robot]

22 failures in 677 pushes (0.032 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• mozilla-central: 8

Platform and build breakdown:

• linux1804-64-qr: 5
  • opt: 5
• linux1804-64-shippable-qr: 12
  • opt: 12
• windows11-64-2009-qr: 2
  • opt: 2
• windows11-64-2009-shippable-qr: 1
  • opt: 1
• windows11-32-2009-qr: 1
  • opt: 1
• macosx1100-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1861451&startday=2023-10-26&endday=2023-10-26&tree=all

Comment 2

[Natalia Csoregi [:nataliaCs]]

This is actually permafailing: https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&searchStr=linux%2C18.04%2Cx64%2Cwebrender%2Copt%2Cmochitests%2Cwith%2Cnetworking%2Con%2Csocket%2Cprocess%2Ctest-linux1804-64-qr%2Fopt-mochitest-browser-chrome-spi-nw%2Cbc5&revision=630864299a197bf416ac18d405063b7ec40c3644

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1856109

Comment 4

[Natalia Csoregi [:nataliaCs]]

Fixed by backout: https://hg.mozilla.org/integration/autoland/rev/40f8ad7cb46ee58e761f16c12d5b1b1eaa55e7c1

Comment 5

[Intermittent Failures Robot]

35 failures in 3657 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 21
• mozilla-central: 14

Platform and build breakdown:

• linux1804-64-qr: 6
  • opt: 6
• linux1804-64-shippable-qr: 17
  • opt: 17
• windows11-64-2009-qr: 4
  • opt: 4
• windows11-64-2009-shippable-qr: 4
  • opt: 4
• windows11-32-2009-qr: 1
  • opt: 1
• macosx1100-64-shippable-qr: 1
  • opt: 1
• windows11-32-2009-shippable-qr: 1
  • opt: 1
• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1861451&startday=2023-10-23&endday=2023-10-29&tree=all