1855872 - Intermittent toolkit/components/printing/tests/browser_print_duplex.js | single tracking bug

Status: RESOLVED INCOMPLETE

(Toolkit :: Printing, defect, P5)

Description

[Treeherder Bug Filer]

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

---

TEST-PASS | toolkit/components/printing/tests/browser_print_duplex.js | duplex matches - 
[task 2023-09-29T00:27:43.086Z] 00:27:43     INFO - Buffered messages finished
[task 2023-09-29T00:27:43.086Z] 00:27:43     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_print_duplex.js | Test timed out - 
[task 2023-09-29T00:27:43.086Z] 00:27:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_print_duplex.js | A promise chain failed to handle a rejection: print-settings listener not removed before the end of test - stack: waitForEvent/</<@resource://testing-common/BrowserTestUtils.sys.mjs:1325:15
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - ensureDOMPromiseRejectionsProcessed@resource://testing-common/PromiseTestUtils.sys.mjs:115:17
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - nextTest@chrome://mochikit/content/browser-test.js:765:29
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - timeoutFn@chrome://mochikit/content/browser-test.js:1437:18
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - setTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - timeoutFn@chrome://mochikit/content/browser-test.js:1418:52
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - setTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1379:45
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1120:14
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - Rejection date: Fri Sep 29 2023 00:27:42 GMT+0000 (Greenwich Mean Time) - false == true - {"filename":"resource://testing-common/PromiseTestUtils.sys.mjs","name":"assertNoUncaughtRejections","sourceId":591,"lineNumber":266,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest","sourceId":556,"lineNumber":766,"columnNumber":29,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":556,"lineNumber":1437,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest_setTimeoutShim","sourceId":582,"lineNumber":922,"columnNumber":41,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"timeoutFn","sourceId":556,"lineNumber":1418,"columnNumber":52,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":556,"lineNumber":1379,"columnNumber":45,"sourceLine":"","asyncCause":"setTimeout handler","asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":556,"lineNumber":1120,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":582,"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:1120: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:1379:45\nnextTest/<@chrome://mochikit/content/browser-test.js:1120:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"timeoutFn@chrome://mochikit/content/browser-test.js:1418:52\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:1058:13\n","nativeSavedFrame":{}},"formattedStack":"setTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1418:52\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:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"timeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1418:52\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:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest@chrome://mochikit/content/browser-test.js:766:29\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1418:52\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:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertNoUncaughtRejections@resource://testing-common/PromiseTestUtils.sys.mjs:266:14\nnextTest@chrome://mochikit/content/browser-test.js:766:29\ntimeoutFn@chrome://mochikit/content/browser-test.js:1437:18\nsetTimeout handler*SimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:922:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1418:52\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:1058:13\n","nativeSavedFrame":{}}
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - Stack trace:
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - chrome://mochikit/content/browser-test.js:nextTest:766
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1437
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:922
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1418
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1379
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1120
[task 2023-09-29T00:27:43.093Z] 00:27:43     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2023-09-29T00:27:43.094Z] 00:27:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-29T00:27:43.094Z] 00:27:43     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_print_duplex.js | Uncaught exception received from previously timed out test bound testToggleDuplexWithLandscapeOrientation - popupshown listener on #ContentSelectDropdownPopup not removed before the end of test
[task 2023-09-29T00:27:43.095Z] 00:27:43     INFO - Entering test bound testSwitchOrientationWithDuplexEnabled
[task 2023-09-29T00:27:43.095Z] 00:27:43     INFO - withTestPage: http://example.com/browser/toolkit/components/printing/tests/simplifyArticleSample.html
[task 2023-09-29T00:27:43.095Z] 00:27:43     INFO - GECKO(3454) | JavaScript error: resource://testing-common/BrowserTestUtils.sys.mjs, line 1325: uncaught exception: print-settings listener not removed before the end of test
[task 2023-09-29T00:27:43.096Z] 00:27:43     INFO - Console message: [JavaScript Error: "uncaught exception: print-settings listener not removed before the end of test" {file: "resource://testing-common/BrowserTestUtils.sys.mjs" line: 1325}]
[task 2023-09-29T00:27:43.096Z] 00:27:43     INFO - GECKO(3454) | MEMORY STAT | vsize 8110MB | residentFast 404MB | heapAllocated 133MB
[task 2023-09-29T00:27:43.096Z] 00:27:43     INFO - TEST-OK | toolkit/components/printing/tests/browser_print_duplex.js | took 92960ms
[task 2023-09-29T00:27:43.097Z] 00:27:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-09-29T00:27:43.097Z] 00:27:43     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/test
INFO - checking window state
[task 2023-09-29T00:27:43.097Z] 00:27:43     INFO - GECKO(3454) | must wait for focus
[task 2023-09-29T00:33:53.163Z] 00:33:53     INFO - TEST-UNEXPECTED-TIMEOUT | toolkit/components/printing/tests/browser_print_duplex.js (finished) | application timed out after 370 seconds with no output
[task 2023-09-29T00:33:53.163Z] 00:33:53     INFO - TEST-INFO 
[task 2023-09-29T00:33:53.163Z] 00:33:53     INFO - Buffered messages finished
[task 2023-09-29T00:33:53.163Z] 00:33:53  WARNING - Force-terminating active process(es).
[task 2023-09-29T00:33:53.163Z] 00:33:53     INFO - Determining child pids from psutil...
[task 2023-09-29T00:33:53.165Z] 00:33:53     INFO - [3455, 3456, 3457, 3458, 3459, 3461, 3521, 3533, 3538, 3582]
[task 2023-09-29T00:33:53.165Z] 00:33:53     INFO - ==> process 3454 launched child process 3455
<...>

Comment 1

[Intermittent Failures Robot]

1 failures in 3726 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1

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

Comment 2

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=443942969&repo=try

Comment 4

[Intermittent Failures Robot]

1 failures in 3599 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1855872&startday=2024-01-15&endday=2024-01-21&tree=all

Comment 5

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Comment 6

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=463198968&repo=mozilla-beta

Comment 7

[Intermittent Failures Robot]

1 failures in 4577 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-devedition-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1855872&startday=2024-06-17&endday=2024-06-23&tree=all

Comment 8

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.