1990473 - Perma pdfpaint-5 [taskcluster:error] task aborted - max run time exceeded | Timeout exceeded on http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf | "You should not call finishTest without having first initted the Profiler" | single tracking bug

Status: RESOLVED INCOMPLETE

(Testing :: Talos, defect, P5)

Description

[Treeherder Bug Filer]

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

---

 Cycle 1(14): loaded http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15942.pdf (next: http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf)
[task 2025-09-24T08:34:12.923+00:00] 08:34:12     INFO -  PID 11052 | Cycle 1(15): loaded http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15942.pdf (next: http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf)
[task 2025-09-24T08:35:14.536+00:00] 08:35:14     INFO -  PID 11052 | __WARNTimeout (1/3) exceeded on http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf__WARN
[task 2025-09-24T08:36:16.141+00:00] 08:36:16     INFO -  PID 11052 | __WARNTimeout (2/3) exceeded on http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf__WARN
[task 2025-09-24T08:37:17.734+00:00] 08:37:17     INFO -  PID 11052 | __FAILTimeout in pdfpaint__FAIL
[task 2025-09-24T08:37:17.734+00:00] 08:37:17     INFO -  PID 11052 | __FAILTimeout (3/3) exceeded on http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue1597.pdf__FAIL
[task 2025-09-24T08:37:17.734+00:00] 08:37:17     INFO -  PID 11052 | console.error: "You should not call finishTest without having first initted the Profiler"
[task 2025-09-24T08:37:17.738+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15942.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.738+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15910.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.739+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15833.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.739+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15815.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.740+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15813.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.740+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15803.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:37:17.741+00:00] 08:37:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15784.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.739+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15942.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.739+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15910.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.740+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15833.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.740+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15815.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.741+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15813.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.741+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15803.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[task 2025-09-24T08:39:17.742+00:00] 08:39:17     INFO -  PID 11052 | console.warn: WindowsJumpLists: "Failed to fetch favicon for " "http://127.0.0.1:55085/tests/pdfpaint/pdfs/issue15784.pdf" [Exception... "Failure"  nsresult: "0x80004005 (NS_ERROR_FAILURE)"  location: "<unknown>"  data: no]
[taskcluster:error] Aborting task...
[taskcluster 2025-09-24T08:56:26.817Z] Command ABORTED after 35m0.0015117s: process aborted
[taskcluster 2025-09-24T08:56:26.817Z]  Average Available System Memory: 3.56 GiB
[taskcluster 2025-09-24T08:56:26.817Z]       Average System Memory Used: 3.99 GiB
[taskcluster 2025-09-24T08:56:26.817Z]          Peak System Memory Used: 6.29 GiB
[taskcluster 2025-09-24T08:56:26.817Z]              Total System Memory: 7.56 GiB
[taskcluster 2025-09-24T08:56:26.817Z] 
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 8464 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 4104 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 4476 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 308 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 3804 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 2992 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 4092 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 2348 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 3428 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 2156 (child process of PID 9892) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 9892 (child process of PID 11052) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 11052 (child process of PID 10652) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 10652 (child process of PID 3436) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 5784 (child process of PID 4464) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 3436 (child process of PID 4464) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 4464 (child process of PID 2780) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 1672 (child process of PID 4636) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 2780 (child process of PID 4636) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] SUCCESS: The process with PID 4636 (child process of PID 9144) has been terminated.
[taskcluster 2025-09-24T08:56:26.929Z] 
[taskcluster 2025-09-24T08:56:26.929Z] === Task Finished ===
[taskcluster 2025-09-24T08:56:26.929Z] Task Duration: 35m0.1144911s
[taskcluster 2025-09-24T08:56:27.056Z] Uploading artifact public/test_info/pdfpaint_errorsummary.log from file C:\task_175868950265082\build\blobber_upload_dir\pdfpaint_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2026-09-24T06:54:04.747Z
[taskcluster 2025-09-24T08:56:27.057Z] Uploading artifact public/logs/localconfig.json from file C:\task_175868950265082\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2026-09-24T06:54:04.747Z
[taskcluster 2025-09-24T08:56:27.357Z] [mounts] Preserving cache: Moving "C:\\task_175868950265082\\.task-cache\\pip" to "C:\\cache\\ULj123v6QcSyG3EqUZ_Gwg"
[taskcluster 2025-09-24T08:56:27.357Z] [mounts] Preserving cache: Moving "C:\\task_175868950265082\\.task-cache\\uv" to "C:\\cache\\XjdUgrJoRLqSnXKpKjisEg"
[taskcluster 2025-09-24T08:56:27.433Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2026-09-24T06:54:04.747Z
[taskcluster:error] task aborted - max run time exceeded

Comment 1

[Sandor Molnar[:smolnar]]

Started from this push but backing it out, didn't fix the failures. Still happening after backout
I investigated and suspected, it was because of Bug 1988793, and did a try run with the bug being backed out, but it still fails nonetheless
Fails on win,linux and mac.

Comment 2

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

This permanent failure is caused by the toolchain-talos-pdfjs run on the push for which the failures started.

Comment 3

[Calixte Denizet (:calixte)]

It's very strange because the pdf has nothing special and it renders very quickly with nightly.
I'll have a look with the build, is target.tar.xz in the artifacts for the B task in Linux Shippable the one used by pdfpaint ? If not could you give a link if it exists ? Thank you.

Comment 4

[Intermittent Failures Robot]

51 failures were associated with this bug yesterday.

Repository breakdown:

• autoland: 40
• mozilla-beta: 4
• mozilla-central: 7

Table

	no_variant	swr
linux-x86/debug	1
linux1804-x86_64/opt	13	6
macosx1470-x86_64/opt	8	9
windows11-x86_64/opt	8	6

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1990473&startday=2025-09-24&endday=2025-09-24&tree=all

Comment 5

[Intermittent Failures Robot]

15 failures were associated with this bug yesterday.

Repository breakdown:

• mozilla-beta: 4
• autoland: 11

Table

	no_variant	swr
linux1804-x86_64/opt	2	3
macosx1470-x86_64/opt	3	3
windows11-x86_64/opt	2	2

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1990473&startday=2025-09-25&endday=2025-09-25&tree=all

Comment 6

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

The target.tar.xz is from the Linux (64-bit) Shippable build as can be seen in the log.

Comment 7

[Calixte Denizet (:calixte)]

I just tried to open the problematic pdf with the artifact used to display it and unfortunately (!!) it works with no problem.
I'm clueless... I've absolutely no idea on what could have caused this failure.
:sparky, would you have any idea ?

Comment 8

[Intermittent Failures Robot]

68 failures were associated with this bug in the last 7 days.
This is the #34 most frequent failure this week.

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: 52
• mozilla-central: 8
• mozilla-beta: 8

Table

	no_variant	swr
linux-x86/debug	1
linux1804-x86_64/opt	17	9
macosx1470-x86_64/opt	11	12
windows11-x86_64/opt	10	8

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1990473&startday=2025-09-22&endday=2025-09-28&tree=all

Comment 9

[Greg Mierzwinski [:sparky]]

It looks like the failure resolved itself? I see that the talos-pdfs toolchain task was retriggered and passed so maybe that resolved the issue.

Comment 10

[Intermittent Failures Robot]

3 failures were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Table

	swr
macosx1470-x86_64/opt	1
windows11-x86_64/opt	2

For more details, see:

https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1990473&startday=2025-09-29&endday=2025-10-05&tree=all