Open
Bug 2043784
Opened 4 months ago
Updated 3 months ago
Intermittent TV TEST-UNEXPECTED-TIMEOUT | accessible/tests/browser/pdfOutput/browser_structTree.js | application timed out after 740.0 seconds with no output
Categories
(Core :: Disability Access APIs, defect, P5)
Core
Disability Access APIs
Tracking
()
NEW
| Tracking | Status | |
|---|---|---|
| firefox-esr140 | --- | unaffected |
| firefox151 | --- | unaffected |
| firefox152 | --- | unaffected |
| firefox153 | --- | disabled |
| firefox154 | --- | affected |
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Regression)
Details
(4 keywords)
Filed by: rperta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=569684884&repo=autoland&task=ZqbaxQoLS6-DCBJ6thn6oA.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZqbaxQoLS6-DCBJ6thn6oA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/default/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZqbaxQoLS6-DCBJ6thn6oA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2026-05-30T03:18:27.835+00:00] 03:18:27 INFO - TEST-PASS | accessible/tests/browser/pdfOutput/browser_structTree.js | testIframeWithSurroundingContent_topLevel - Page 1 struct tree correct
[task 2026-05-30T03:18:27.835+00:00] 03:18:27 INFO - Buffered messages logged at 03:06:05
[task 2026-05-30T03:18:27.835+00:00] 03:18:27 INFO - Leaving test testIframeWithSurroundingContent_topLevel
[task 2026-05-30T03:18:27.835+00:00] 03:18:27 INFO - Entering test testAriaOwns_topLevel
[task 2026-05-30T03:18:27.835+00:00] 03:18:27 INFO - Buffered messages logged at 03:06:07
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - Console message: [JavaScript Warning: "Layout was forced before the page was fully loaded. If stylesheets are not yet loaded this may cause a flash of unstyled content." {file: "chrome://global/content/print.html" line: 0}]
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - TEST-PASS | accessible/tests/browser/pdfOutput/browser_structTree.js | testAriaOwns_topLevel - File does not exist before printing
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - TEST-PASS | accessible/tests/browser/pdfOutput/browser_structTree.js | testAriaOwns_topLevel - Element must be visible to click
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - Buffered messages finished
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - TEST-UNEXPECTED-TIMEOUT | accessible/tests/browser/pdfOutput/browser_structTree.js | application timed out after 740.0 seconds with no output
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - TEST-INFO took 764690ms
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - Buffered messages finished
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 WARNING - Force-terminating active process(es).
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 WARNING - profiler Attempting to start the profiler to help with diagnosing the hang.
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - profiler Sending SIGUSR1 to pid 1681 start the profiler.
[task 2026-05-30T03:18:27.836+00:00] 03:18:27 INFO - profiler Waiting 10s to capture a profile...
[task 2026-05-30T03:18:37.835+00:00] 03:18:37 INFO - profiler Sending SIGUSR2 to pid 1681 stop the profiler.
[task 2026-05-30T03:18:37.835+00:00] 03:18:37 INFO - profiler Wait 10s for Firefox to write the profile to disk.
[task 2026-05-30T03:18:47.939+00:00] 03:18:47 INFO - profiler Symbolicating profile in /opt/worker/tasks/cltbld/build/blobber_upload_dir
[task 2026-05-30T03:18:47.939+00:00] 03:18:47 INFO - profiler Looking inside symbols dir: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.crashreporter-symbols.zip)
[task 2026-05-30T03:18:47.939+00:00] 03:18:47 INFO - profiler Symbolicating profile: /opt/worker/tasks/cltbld/build/blobber_upload_dir/profile_0_1681.json
[task 2026-05-30T03:18:47.940+00:00] 03:18:47 INFO - profiler Symbolicating the performance profile... This could take a couple of minutes.
[task 2026-05-30T03:18:48.025+00:00] 03:18:48 WARNING - profiler /opt/worker/tasks/cltbld/fetches/profiler-node-tools/profiler-edit.js does not exist.
[task 2026-05-30T03:18:48.025+00:00] 03:18:48 INFO - profiler Symbolication dependencies not available, using fallback symbolication.
[task 2026-05-30T03:18:48.532+00:00] 03:18:48 INFO - Determining child pids from psutil...
[task 2026-05-30T03:18:48.535+00:00] 03:18:48 INFO - [1684, 1690, 1691, 1693, 1701, 1704, 1705, 1922, 1942]
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1684
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1690
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1691
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1693
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1701
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1702
[task 2026-05-30T03:18:48.536+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1703
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1704
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1705
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1713
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1753
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1755
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1778
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1833
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1861
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1922
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - ==> process 1681 launched child process 1942
[task 2026-05-30T03:18:48.537+00:00] 03:18:48 INFO - Found child pids: {1922, 1701, 1702, 1703, 1704, 1705, 1833, 1861, 1713, 1778, 1684, 1942, 1753, 1690, 1691, 1693, 1755}
[task 2026-05-30T03:18:48.538+00:00] 03:18:48 INFO - Failed to get child procs
[task 2026-05-30T03:18:48.538+00:00] 03:18:48 INFO - Killing process: 1922
[task 2026-05-30T03:18:48.538+00:00] 03:18:48 INFO - TEST-INFO | started process screencapture
[task 2026-05-30T03:18:54.114+00:00] 03:18:54 INFO - TEST-INFO | screencapture: exit 1
[task 2026-05-30T03:18:54.114+00:00] 03:18:54 INFO - Timed out waiting for screenshot from LaunchAgent
[task 2026-05-30T03:18:54.115+00:00] 03:18:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:19:24.134+00:00] 03:19:24 INFO - failed to kill pid 1922 after 30s
[task 2026-05-30T03:19:24.134+00:00] 03:19:24 INFO - Killing process: 1701
[task 2026-05-30T03:19:24.134+00:00] 03:19:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:19:24.134+00:00] 03:19:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - failed to kill pid 1701 after 30s
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Killing process: 1702
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Error: Failed to kill process 1702: process PID not found (pid=1702)
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Killing process: 1703
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Error: Failed to kill process 1703: process PID not found (pid=1703)
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Killing process: 1704
[task 2026-05-30T03:19:54.152+00:00] 03:19:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:19:54.153+00:00] 03:19:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:24.195+00:00] 03:20:24 INFO - failed to kill pid 1704 after 30s
[task 2026-05-30T03:20:24.195+00:00] 03:20:24 INFO - Killing process: 1705
[task 2026-05-30T03:20:24.195+00:00] 03:20:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:24.196+00:00] 03:20:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:54.321+00:00] 03:20:54 INFO - failed to kill pid 1705 after 30s
[task 2026-05-30T03:20:54.321+00:00] 03:20:54 INFO - Killing process: 1833
[task 2026-05-30T03:20:54.321+00:00] 03:20:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:54.321+00:00] 03:20:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Error: Failed to kill process 1833: process PID not found (pid=1833)
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Killing process: 1861
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Error: Failed to kill process 1861: process PID not found (pid=1861)
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Killing process: 1713
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Error: Failed to kill process 1713: process PID not found (pid=1713)
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Killing process: 1778
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:20:54.322+00:00] 03:20:54 INFO - Error: Failed to kill process 1778: process PID not found (pid=1778)
[task 2026-05-30T03:20:54.323+00:00] 03:20:54 INFO - Killing process: 1684
[task 2026-05-30T03:20:54.323+00:00] 03:20:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:20:54.323+00:00] 03:20:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:21:24.417+00:00] 03:21:24 INFO - failed to kill pid 1684 after 30s
[task 2026-05-30T03:21:24.418+00:00] 03:21:24 INFO - Killing process: 1942
[task 2026-05-30T03:21:24.418+00:00] 03:21:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:21:24.418+00:00] 03:21:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:21:54.513+00:00] 03:21:54 INFO - failed to kill pid 1942 after 30s
[task 2026-05-30T03:21:54.513+00:00] 03:21:54 INFO - Killing process: 1753
[task 2026-05-30T03:21:54.513+00:00] 03:21:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:21:54.513+00:00] 03:21:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:21:54.514+00:00] 03:21:54 INFO - Error: Failed to kill process 1753: process PID not found (pid=1753)
[task 2026-05-30T03:21:54.514+00:00] 03:21:54 INFO - Killing process: 1690
[task 2026-05-30T03:21:54.514+00:00] 03:21:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:21:54.514+00:00] 03:21:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:22:24.616+00:00] 03:22:24 INFO - failed to kill pid 1690 after 30s
[task 2026-05-30T03:22:24.616+00:00] 03:22:24 INFO - Killing process: 1691
[task 2026-05-30T03:22:24.616+00:00] 03:22:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:22:24.616+00:00] 03:22:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:22:54.635+00:00] 03:22:54 INFO - failed to kill pid 1691 after 30s
[task 2026-05-30T03:22:54.635+00:00] 03:22:54 INFO - Killing process: 1693
[task 2026-05-30T03:22:54.635+00:00] 03:22:54 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:22:54.635+00:00] 03:22:54 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - failed to kill pid 1693 after 30s
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Killing process: 1755
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Error: Failed to kill process 1755: process PID not found (pid=1755)
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Killing process: 1681
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-05-30T03:23:24.700+00:00] 03:23:24 INFO - Can't trigger Breakpad, just killing process
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 3004 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1691 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 3010 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1693 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1681 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1942 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1690 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1704 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1684 dead
[task 2026-05-30T03:23:24.732+00:00] 03:23:24 INFO - psutil found pid 1681 dead
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - TEST-INFO | Main app process: killed by SIGTERM
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - Buffered messages finished
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - runtests.py | Application ran for: 0:21:12.865942
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - zombiecheck | Reading PID log: /tmp/tmp7m51mlcwpidlog
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1684
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1690
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1691
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1693
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1701
[task 2026-05-30T03:23:24.733+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1702
[task 2026-05-30T03:23:24.734+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1703
[task 2026-05-30T03:23:24.734+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1704
[task 2026-05-30T03:23:24.734+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1705
[task 2026-05-30T03:23:24.734+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1713
[task 2026-05-30T03:23:24.734+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1753
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1755
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1778
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1833
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1861
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1922
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 1942
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 3004
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - ==> process 1681 launched child process 3010
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1922
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1684
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1942
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1690
[task 2026-05-30T03:23:24.735+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1691
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1693
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1701
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1702
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1703
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1704
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1705
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1833
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1713
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 3004
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 3010
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1861
[task 2026-05-30T03:23:24.736+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1753
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1755
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - zombiecheck | Checking for orphan process with PID: 1778
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - runtests.py | Running http tests: end. status: 1
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - Stopping web server
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - Server shut down.
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - Web server killed.
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - Stopping web socket server
[task 2026-05-30T03:23:24.737+00:00] 03:23:24 INFO - Stopping ssltunnel
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Buffered messages finished
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - TEST-INFO | checking window state
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Browser Chrome Test Summary
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Passed: 762
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Failed: 0
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Todo: 0
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Retried: 0
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Mode: e10s
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - *** End BrowserChrome Test Results ***
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - Buffered messages finished
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - SUITE-END | took 1273s
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - SUITE-START | Running 1 tests
[task 2026-05-30T03:23:24.765+00:00] 03:23:24 INFO - :::
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: Test verification summary for:
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - :::
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: accessible/tests/browser/pdfOutput/browser_structTree.js
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - :::
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: 1. Run each test 10 times in one browser. : Pass
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: 2. Run each test 5 times in a new browser each time. : Pass
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : FAIL
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - :::
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - ::: Test verification FAILED!
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - :::
[task 2026-05-30T03:23:24.766+00:00] 03:23:24 INFO - Buffered messages finished
[task 2026-05-30T03:23:24.767+00:00] 03:23:24 INFO - SUITE-END | took 0s
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - Return code: 0
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 WARNING - Got 1 unexpected statuses
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - TinderboxPrint: mochitest-mochitest-browser-a11y<br/>48/<em class="testfail">1</em>/0
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 WARNING - setting return code to 1
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 WARNING - TinderboxPrint: Per-test run of .../pdfOutput/browser_structTree.js<br/>: WARNING
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - Per-test suites: {}
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - Per-test suites: {'xpcshell': {'options': ('--xpcshell=%(abs_app_dir)s/xpcshell', '--http3server=%(abs_app_dir)s/http3server', '--manifest=tests/xpcshell/tests/xpcshell.toml'), 'tests': ()}}
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - #### Running xpcshell suites
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - Structured output parser in use for xpcshell.
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - ENV: MINIDUMP_STACKWALK is now /opt/worker/tasks/cltbld/fetches/minidump-stackwalk/minidump-stackwalk
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - ENV: MOZ_NODE_PATH is now /opt/worker/tasks/cltbld/fetches/node/bin/node
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - ENV: MOZ_UPLOAD_DIR is now /opt/worker/tasks/cltbld/build/blobber_upload_dir
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /opt/worker/tasks/cltbld/build/blobber_upload_dir
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - ENV: RUST_BACKTRACE is now full
[task 2026-05-30T03:23:24.778+00:00] 03:23:24 INFO - Per-test file(s) for 'xpcshell': ['image/test/unit/test_imgtools.js']
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - Running command: ['/opt/worker/tasks/cltbld/build/venv/bin/python', '-u', '/opt/worker/tasks/cltbld/build/tests/xpcshell/runxpcshelltests.py', '--setpref=layout.css.stylo-threads=4', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--self-test', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.crashreporter-symbols.zip', '--log-errorsummary=/opt/worker/tasks/cltbld/build/blobber_upload_dir/xpcshell-test2_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/xpcshell', '--http3server=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/http3server', '--manifest=tests/xpcshell/tests/xpcshell.toml', '--verify-max-time=900', '--verify', 'image/test/unit/test_imgtools.js'] in /opt/worker/tasks/cltbld/build
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - Copy/paste: /opt/worker/tasks/cltbld/build/venv/bin/python -u /opt/worker/tasks/cltbld/build/tests/xpcshell/runxpcshelltests.py --setpref=layout.css.stylo-threads=4 --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --setpref=layers.d3d11.enable-blacklist=false --self-test --symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.crashreporter-symbols.zip --log-errorsummary=/opt/worker/tasks/cltbld/build/blobber_upload_dir/xpcshell-test2_errorsummary.log --utility-path=tests/bin "--xpcshell=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/xpcshell" "--http3server=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/http3server" --manifest=tests/xpcshell/tests/xpcshell.toml --verify-max-time=900 --verify image/test/unit/test_imgtools.js
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - Using env: {'EXTRA_MOZHARNESS_CONFIG': '{"installer_url":"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.dmg","test_packages_url":"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.test_packages.json"}',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/autoland',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'GECKO_HEAD_REV': 'b800f13602bd1e4306fef7917ffe8d1afed2f972',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'HOME': '/Users/cltbld',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'LANG': 'en_US.UTF-8',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'LC_ALL': 'en_US.UTF-8',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MINIDUMP_SAVE_PATH': '/opt/worker/tasks/cltbld/build/blobber_upload_dir',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MINIDUMP_STACKWALK': '/opt/worker/tasks/cltbld/fetches/minidump-stackwalk/minidump-stackwalk',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_AUTOMATION': '1',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_BUILD_DATE': '20260530022329',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_FETCHES': '[{"artifact":"public/bergamot-translator.zst","extract":true,"task":"eU1Aeuv1SSSNczuYzCw2xg"},{"artifact":"public/build/fix-stacks.tar.zst","extract":true,"task":"JLUvyREZRmiH3kWGZOji1w"},{"artifact":"public/build/minidump-stackwalk.tar.zst","extract":true,"task":"J1Swu5GHSsuNlSyulp8Q6w"},{"artifact":"public/build/node.tar.zst","extract":true,"task":"QpaZvqoMQW--Z23mjsL8yg"},{"artifact":"public/ort-wasm-simd-threaded.jsep.wasm","extract":true,"task":"NKQ2tkoaSvGRD_evksR8NA"},{"artifact":"public/tiny.lex.50.50.esen.s2t.bin.zst","extract":true,"task":"Hli2f1V9Rp-R3kjmH3FgSQ"},{"artifact":"public/tiny.model.esen.intgemm.alphas.bin.zst","extract":true,"task":"LznVnrcrR2u2v6PV42ss0Q"},{"artifact":"public/tiny.vocab.esen.spm.zst","extract":true,"task":"BY3lJSVsQnOBVEB-rR4vrw"}]',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_FETCHES_DIR': '/opt/worker/tasks/cltbld/fetches',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_NODE_PATH': '/opt/worker/tasks/cltbld/fetches/node/bin/node',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_SCM_LEVEL': '3',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_SOURCE_CHANGESET': 'b800f13602bd1e4306fef7917ffe8d1afed2f972',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/autoland',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'MOZ_UPLOAD_DIR': '/opt/worker/tasks/cltbld/build/blobber_upload_dir',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'PATH': '/opt/worker/tasks/cltbld/build/venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'PERFHERDER_FETCH_CONTENT_JSON_PATH': './perf/perfherder-data-fetch-content.json',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'PIP_CACHE_DIR': '/opt/worker/tasks/cltbld/.task-cache/pip',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'PYTHON': '/usr/local/bin/python3',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'REQUIRE_GPU': '0',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'RUN_ID': '0',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'RUST_BACKTRACE': 'full',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'SCCACHE_DISABLE': '1',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'SHELL': '/bin/bash',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'TASKCLUSTER_WORKER_LOCATION': '{"cloud":"standalone","host":"mdc1"}',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'TASK_GROUP_ID': 'cu-_QpfzRmKx-YlSSVBbVw',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'TASK_ID': 'ZqbaxQoLS6-DCBJ6thn6oA',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'TASK_WORKDIR': '/opt/worker/tasks/cltbld',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'UPLOAD_DIR': '/opt/worker/tasks/cltbld/public/build',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'USER': 'cltbld',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'UV_CACHE_DIR': '/opt/worker/tasks/cltbld/.task-cache/uv',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - 'VIRTUAL_ENV': '/opt/worker/tasks/cltbld/build/venv',
[task 2026-05-30T03:23:24.779+00:00] 03:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x22B:0x0:0x0'}
[task 2026-05-30T03:23:24.782+00:00] 03:23:24 INFO - Calling ['/opt/worker/tasks/cltbld/build/venv/bin/python', '-u', '/opt/worker/tasks/cltbld/build/tests/xpcshell/runxpcshelltests.py', '--setpref=layout.css.stylo-threads=4', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--self-test', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BMzEmSPQQpu_40uXfj6gQw/artifacts/public/build/target.crashreporter-symbols.zip', '--log-errorsummary=/opt/worker/tasks/cltbld/build/blobber_upload_dir/xpcshell-test2_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/xpcshell', '--http3server=/opt/worker/tasks/cltbld/build/application/Firefox Nightly.app/Contents/MacOS/http3server', '--manifest=tests/xpcshell/tests/xpcshell.toml', '--verify-max-time=900', '--verify', 'image/test/unit/test_imgtools.js'] with output_timeout 1000
[task 2026-05-30T03:23:24.907+00:00] 03:23:24 INFO - Using harness timeout of 30s (base=30s, factor=1.0)
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - These variables are available in the mozinfo environment and can be used to skip tests conditionally:
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - appname: firefox
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - artifact: False
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - asan: False
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - automation: True
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - bin_suffix:
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - bits: 64
[task 2026-05-30T03:23:24.917+00:00] 03:23:24 INFO - buildapp: browser
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - buildtype: opt
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - buildtype_guess: opt
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - cc_type: clang
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - ccov: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - condprof: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - crashreporter: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - datareporting: True
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - dbus_enabled: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - debug: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - devedition: False
[task 2026-05-30T03:23:24.918+00:00] 03:23:24 INFO - e10s: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - early_beta_or_earlier: True
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - fission: True
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - healthreport: True
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - inc_origin_init: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - is_ubuntu: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - isolated_process: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - mingw: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - mozconfig: /builds/worker/checkouts/gecko/.mozconfig
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - msix: False
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - nightly_build: True
[task 2026-05-30T03:23:24.919+00:00] 03:23:24 INFO - normandy: True
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - official: True
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - opt: True
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - os: mac
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - pgo: False
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - platform_guess: macosx64
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - processor: aarch64
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - release_or_beta: False
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - require_signing: False
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - sessionHistoryInParent: True
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - socketprocess_networking: False
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - stylo: True
[task 2026-05-30T03:23:24.920+00:00] 03:23:24 INFO - sync: True
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - telemetry: False
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - tests_enabled: True
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - toolkit: cocoa
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - topobjdir: /builds/worker/workspace/obj-build
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - topsrcdir: /builds/worker/checkouts/gecko
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - tsan: False
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - ubsan: False
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - updater: True
[task 2026-05-30T03:23:24.921+00:00] 03:23:24 INFO - verify: True
[task 2026-05-30T03:23:24.934+00:00] 03:23:24 INFO - SUITE-START | Running 61 tests
[task 2026-05-30T03:23:24.934+00:00] 03:23:24 INFO - Running 61 self-tests in parallel with up to 25 workers...
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskRunNextTest
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskSkip
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskSkipAll
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskStackTrace
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskTestFailureInside
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskTestMultiple
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskTestRejected
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskTestRejectedUndefined
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTaskTestSingle
[task 2026-05-30T03:23:24.935+00:00] 03:23:24 INFO - TEST-START | testAddTestFailing
[task 2026-05-30T03:23:24.936+00:00] 03:23:24 INFO - TEST-START | testAddTestSimple
[task 2026-05-30T03:23:24.936+00:00] 03:23:24 INFO - TEST-START | testAddTestUncaughtRejection
[task 2026-05-30T03:23:24.936+00:00] 03:23:24 INFO - TEST-START | testAssertStack
[task 2026-05-30T03:23:24.937+00:00] 03:23:24 INFO - TEST-PASS | testAssertStack | took 0ms
Keywords: regression
Regressed by: 2042256
|
||
Comment 1•4 months ago
|
||
:lsalzman, since you are the author of the regressor, bug 2042256, could you take a look?
For more information, please visit BugBot documentation.
Flags: needinfo?(lsalzman)
|
|
||
Comment 2•4 months ago
|
||
Set release status flags based on info from the regressing bug 2042256
status-firefox151:
--- → unaffected
status-firefox152:
--- → unaffected
status-firefox153:
--- → affected
status-firefox-esr140:
--- → unaffected
|
||
Comment 3•4 months ago
|
||
According to emilio, TV implies this only runs when the test has changed. However, the test only changed cosmetically to match a sub-tree ordering issue due to refactoring in Skia. It is unlikely that the change in Skia significantly altered the timing of the test. Therefor, the timeout likely is predates the change here, but the change evidenced the timeout.
Flags: needinfo?(lsalzman)
|
||
Comment 4•4 months ago
|
||
I can't reproduce this locally with --verify, so this will need to be debugged on try. It looks to me like we're failing somewhere in PrintHelper in toolkit/components/printing/tests/head.js.
|
|
||
Comment 5•4 months ago
|
||
If I'm interpreting the screenshot correctly, we successfully clicked the Save button, but the browser got stuck forever trying to save the PDF.
|
|
||
Comment 6•4 months ago
•
|
||
Assignee: nobody → jteh
Status: NEW → ASSIGNED
|
|
||
Comment 7•4 months ago
|
||
Hmm. The first try run above does 10 runs of the PDF tests in TV mode and none of them failed. I'm not really sure what else to do here unless these start becoming more frequent.
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Updated•4 months ago
|
|
|
||
Comment 9•3 months ago
|
||
Set release status flags based on info from the regressing bug 2042256
status-firefox154:
--- → affected
You need to log in
before you can comment on or make changes to this bug.
Description
•