Closed Bug 1751255 Opened 3 years ago Closed 1 year ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webvtt/api/VTTRegion/non-visible-cue-with-region.html | Box-less VTTCue attached to VTTRegion - Test timed out

Categories

(Core :: Audio/Video: Playback, defect, P5)

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2022-01-20T19:02:58.218Z] 19:02:58     INFO - TEST-START | /webvtt/api/VTTRegion/non-visible-cue-with-region.html
[task 2022-01-20T19:02:58.245Z] 19:02:58     INFO - Closing window 14ccf59f-7190-4454-8f6b-402cb7e87831
[task 2022-01-20T19:03:00.765Z] 19:03:00     INFO - PID 30161 | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-01-20T19:03:00.787Z] 19:03:00     INFO - PID 30161 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-20T19:03:18.853Z] 19:03:18     INFO - 
[task 2022-01-20T19:03:18.853Z] 19:03:18     INFO - TEST-UNEXPECTED-TIMEOUT | /webvtt/api/VTTRegion/non-visible-cue-with-region.html | Box-less VTTCue attached to VTTRegion - Test timed out
[task 2022-01-20T19:03:18.855Z] 19:03:18     INFO - TEST-UNEXPECTED-TIMEOUT | /webvtt/api/VTTRegion/non-visible-cue-with-region.html | expected OK
[task 2022-01-20T19:03:18.855Z] 19:03:18     INFO - TEST-INFO took 20638ms
[task 2022-01-20T19:03:18.858Z] 19:03:18     INFO - PID 30161 | 1642705398856	Marionette	INFO	Stopped listening on port 42978
[task 2022-01-20T19:03:19.121Z] 19:03:19     INFO - PID 30161 | JavaScript error: resource://gre/modules/URLQueryStrippingListService.jsm, line 90: TypeError: can't access property "removeEventListener", Services.cpmm.sharedData is null
[task 2022-01-20T19:03:19.122Z] 19:03:19     INFO - PID 30161 | ###!!! [Parent][PBackgroundParent] Error: RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-20T19:03:19.123Z] 19:03:19     INFO - PID 30161 | ###!!! [Parent][PCompositorManagerParent] Error: RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost
[task 2022-01-20T19:03:29.138Z] 19:03:29     INFO - PID 30161 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"MediaShutdownManager: shutdown","state":{"decoderInfo":"application/ogg, ","sInitPhase":3},"filename":"/builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp","lineNumber":71,"stack":"MediaShutdownManager shutdown"}] Barrier: xpcom-will-shutdown
[task 2022-01-20T19:03:34.115Z] 19:03:34     INFO - PID 30161 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 446))
[task 2022-01-20T19:03:36.937Z] 19:03:36     INFO - PID 30161 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7f9cb5fbce00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9cb5fbc200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9cb5fbf200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2022-01-20T19:03:40.944Z] 19:03:40     INFO - PID 30161 | JavaScript error: resource://gre/modules/CrashManager.jsm, line 898: AbortError: IOUtils: Shutting down and refusing additional I/O tasks
[task 2022-01-20T19:04:20.138Z] 19:04:20     INFO - PID 30161 | FATAL ERROR: AsyncShutdown timeout in xpcom-will-shutdown Conditions: [{"name":"MediaShutdownManager: shutdown","state":{"decoderInfo":"application/ogg, ","sInitPhase":3},"filename":"/builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp","lineNumber":71,"stack":"MediaShutdownManager shutdown"}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2022-01-20T19:04:20.139Z] 19:04:20     INFO - PID 30161 | [Child 30409, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp:71
[task 2022-01-20T19:04:27.937Z] 19:04:27     INFO - PID 30161 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7f9cb5fbce00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9cb5fbc200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f9cb5fbf200","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3509,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2022-01-20T19:04:27.938Z] 19:04:27     INFO - PID 30161 | [Parent 30161, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3509
[task 2022-01-20T19:04:27.941Z] 19:04:27     INFO - PID 30161 | ExceptionHandler::GenerateDump cloned child 30663
[task 2022-01-20T19:04:27.942Z] 19:04:27     INFO - PID 30161 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-01-20T19:04:27.942Z] 19:04:27     INFO - PID 30161 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-01-20T19:04:29.896Z] 19:04:29     INFO - Browser exited with return code 11
[task 2022-01-20T19:04:29.897Z] 19:04:29     INFO - Closing logging queue
[task 2022-01-20T19:04:29.897Z] 19:04:29     INFO - queue closed
[task 2022-01-20T19:04:29.925Z] 19:04:29     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp807cc6f0
[task 2022-01-20T19:04:29.944Z] 19:04:29     INFO - PID 30461 | [CodeCoverage] Setting handlers for process 30461.
[task 2022-01-20T19:04:29.944Z] 19:04:29     INFO - PID 30461 | 1642705375180	Marionette	INFO	Marionette enabled
[task 2022-01-20T19:04:29.944Z] 19:04:29     INFO - PID 30461 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp141z5h3u/search.json.mozlz4", (void 0)))
[task 2022-01-20T19:04:29.944Z] 19:04:29     INFO - PID 30461 | 1642705381775	Marionette	INFO	Listening on port 37955
[task 2022-01-20T19:04:29.944Z] 19:04:29     INFO - Starting runner
[task 2022-01-20T19:04:30.978Z] 19:04:30     INFO - PID 30461 | [CodeCoverage] Setting handlers for process 30588.
[task 2022-01-20T19:04:30.980Z] 19:04:30     INFO - PID 30461 | [CodeCoverage] Setting handlers for process 30594.
[task 2022-01-20T19:04:32.553Z] 19:04:32     INFO - PID 30461 | [CodeCoverage] Setting handlers for process 30765.
[task 2022-01-20T19:04:32.913Z] 19:04:32     INFO - TEST-START | /webvtt/api/VTTRegion/regionAnchorX.html

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=383153586&repo=mozilla-central&lineNumber=8175

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

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

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 2 years ago
Resolution: --- → INCOMPLETE

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

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 2 years ago → 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.