1861830 - Perma windows10-64-2009-qr debug mochitest-plain [tier 2] [taskcluster:error] Task aborted - max run time exceeded

Reporter

Description

•

1 year ago

treeherder

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

[task 2023-10-28T16:47:11.810Z] 16:47:11     INFO - TEST-PASS | toolkit/content/tests/widgets/test_videocontrols.html | Cursor is hidden 
[task 2023-10-28T16:47:11.874Z] 16:47:11     INFO - GECKO(3396) | [Child 4132, MediaDecoderStateMachine #1] WARNING: 25729cb4d20 Could not set cubeb stream name.: file /builds/worker/checkouts/gecko/dom/media/AudioStream.cpp:324
[task 2023-10-28T16:47:11.969Z] 16:47:11     INFO - TEST-PASS | toolkit/content/tests/widgets/test_videocontrols.html | Cursor is shown 
[task 2023-10-28T16:47:11.997Z] 16:47:11     INFO - Waiting for mozfullscreenchange but not fullscreenerror...
[task 2023-10-28T16:47:12.000Z] 16:47:11     INFO - Received event mozfullscreenchange.
[task 2023-10-28T16:47:12.001Z] 16:47:12     INFO - No longer waiting for expected event(s) mozfullscreenchange.
[task 2023-10-28T16:47:12.051Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.051Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.052Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.053Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.069Z] 16:47:12     INFO - Waiting for pause...
[taskcluster:error] Aborting task...
[task 2023-10-28T16:47:12.079Z] 16:47:12     INFO - Received event pause.
[task 2023-10-28T16:47:12.082Z] 16:47:12     INFO - No longer waiting for expected event(s) pause.
[task 2023-10-28T16:47:12.099Z] 16:47:12     INFO - add_task | Leaving ensure_fullscreen_cursor
[task 2023-10-28T16:47:12.100Z] 16:47:12     INFO - add_task | Entering ensure_fullscreen_button
[task 2023-10-28T16:47:12.167Z] 16:47:12     INFO - Waiting for mozfullscreenchange but not fullscreenerror...
[task 2023-10-28T16:47:12.201Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.205Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.206Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.206Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.223Z] 16:47:12     INFO - Received event mozfullscreenchange.
[task 2023-10-28T16:47:12.226Z] 16:47:12     INFO - No longer waiting for expected event(s) mozfullscreenchange.
[task 2023-10-28T16:47:12.294Z] 16:47:12     INFO - Clicking at 1256, 1004.
[task 2023-10-28T16:47:12.324Z] 16:47:12     INFO - Waiting for mozfullscreenchange but not fullscreenerror,play,pause...
[task 2023-10-28T16:47:12.371Z] 16:47:12     INFO - Received event mozfullscreenchange.
[task 2023-10-28T16:47:12.374Z] 16:47:12     INFO - No longer waiting for expected event(s) mozfullscreenchange.
[task 2023-10-28T16:47:12.391Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.391Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.392Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.395Z] 16:47:12     INFO - GECKO(3396) | [Child 6336, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_OK) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1150
[task 2023-10-28T16:47:12.396Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.397Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.397Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.398Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.399Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.399Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.401Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer 74E7A008F730CBE7.1AE8FA016DC053D8: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.401Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: Call to Send() failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeChannel.cpp:198
[task 2023-10-28T16:47:12.402Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer C0FAECDDFE9B5442.4B7B1B49357FCB7: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.403Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer C0FAECDDFE9B5442.4B7B1B49357FCB7: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.404Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer C0FAECDDFE9B5442.4B7B1B49357FCB7: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[task 2023-10-28T16:47:12.405Z] 16:47:12     INFO - GECKO(3396) | [Parent 3172, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer C0FAECDDFE9B5442.4B7B1B49357FCB7: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:344
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 6540 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 5228 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 4600 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 4132 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 6056 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 3472 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 1708 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 6336 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 2724 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 7812 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 2240 (child process of PID 3172) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 2076 (child process of PID 6496) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 3172 (child process of PID 3396) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 4744 (child process of PID 8660) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 6496 (child process of PID 8660) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 3308 (child process of PID 8660) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 3396 (child process of PID 8660) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 8660 (child process of PID 8996) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 5404 (child process of PID 8836) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 8996 (child process of PID 8836) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 8836 (child process of PID 8948) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 9012 (child process of PID 8892) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 8948 (child process of PID 8892) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] SUCCESS: The process with PID 8892 (child process of PID 8724) has been terminated.
[taskcluster 2023-10-28T16:47:12.445Z] 
[taskcluster 2023-10-28T16:47:12.445Z] === Task Finished ===
[taskcluster 2023-10-28T16:47:12.445Z] Task Duration: 1h0m0.3755467s
[taskcluster 2023-10-28T16:47:12.678Z] Uploading artifact public/logs/localconfig.json from file Z:\task_169850761674532\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2024-01-26T15:36:35.498Z
[taskcluster 2023-10-28T16:47:13.149Z] Uploading artifact public/test_info/manifests.list from file Z:\task_169850761674532\build\blobber_upload_dir\manifests.list with content encoding "gzip", mime type "application/octet-stream" and expiry 2024-01-26T15:36:35.498Z
[taskcluster 2023-10-28T16:47:13.558Z] Uploading artifact public/test_info/mochitest-plain_errorsummary.log from file Z:\task_169850761674532\build\blobber_upload_dir\mochitest-plain_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2024-01-26T15:36:35.498Z
[taskcluster 2023-10-28T16:47:14.137Z] Uploading artifact public/test_info/system-info.log from file Z:\task_169850761674532\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2024-01-26T15:36:35.498Z
[taskcluster 2023-10-28T16:47:14.543Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2024-01-26T15:36:35.498Z
[taskcluster:error] Task aborted - max run time exceeded

Iulian Moraru

Comment 1

•

1 year ago

Hi Joel! Can you please take a look at this?
It seems that this has started with this merge.
Thank you!

Flags: needinfo?(jmaher)

Keywords: intermittent-testcase

Summary: Perma windows10-64-2009-qr debug mochitest-plain [taskcluster:error] Task aborted - max run time exceeded | single tracking bug → Perma windows10-64-2009-qr debug mochitest-plain [taskcluster:error] Task aborted - max run time exceeded

Iulian Moraru

Updated

•

1 year ago

Summary: Perma windows10-64-2009-qr debug mochitest-plain [taskcluster:error] Task aborted - max run time exceeded → Perma windows10-64-2009-qr debug mochitest-plain [tier 2] [taskcluster:error] Task aborted - max run time exceeded

Comment hidden (Intermittent Failures Robot)

Joel Maher ( :jmaher ) (UTC -8)

Assignee

Comment 3

•

1 year ago

we were often at >53 minutes for this task prior to the suspect merge, so this is adding 5+ minutes to runtime, this could be something in the browser, a few more tests, or something in infrastructure. As the other chunks are around 40+ minutes, I think the best course of action is to go from 5 -> 8 chunks.

Flags: needinfo?(jmaher)

Joel Maher ( :jmaher ) (UTC -8)

Assignee

Comment 4

•

1 year ago

Attached file Bug 1861830 - split windows/debug mochitest-plain into 8 chunks. r=aryx! — Details

Phabricator Automation

Updated

•

1 year ago

Assignee: nobody → jmaher

Status: NEW → ASSIGNED

Pulsebot

Comment 5

•

1 year ago

Pushed by archaeopteryx@coole-files.de: https://hg.mozilla.org/integration/autoland/rev/a2e784ce29de split windows/debug mochitest-plain into 8 chunks. r=aryx DONTBUILD

Noemi Erli[:noemi_erli]

Comment 6

•

1 year ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/a2e784ce29de

Status: ASSIGNED → RESOLVED

Closed: 1 year ago

status-firefox121: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 121 Branch

Comment hidden (Intermittent Failures Robot)

Bugzilla

Perma windows10-64-2009-qr debug mochitest-plain [tier 2] [taskcluster:error] Task aborted - max run time exceeded

Categories

(Testing :: Mochitest, defect, P5)

Tracking

(firefox121 fixed)

People

(Reporter: intermittent-bug-filer, Assigned: jmaher)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Attachments

(1 file)

Description

Comment 1

Updated

Comment 2

Comment 3

Comment 4

Updated

Comment 5

Comment 6

Comment 7

Attachment

General

Description

File Name

Content Type