Closed Bug 1734082 Opened 4 years ago Closed 4 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects allowed for audio-tag to same-https origin and keep-scheme redirection from https context. - Test timed out

Categories

(Core :: DOM: Security, defect, P5)

Product:
Core
Component:
DOM: Security
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [domsecurity-intermittent])

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

[task 2021-10-05T05:04:51.718Z] 05:04:51     INFO - TEST-START | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html
[task 2021-10-05T05:04:51.738Z] 05:04:51     INFO - Setting pref network.prefetch-next.aggressive (true)
[task 2021-10-05T05:06:52.457Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.462Z] 05:06:52     INFO - TEST-UNEXPECTED-TIMEOUT | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects allowed for audio-tag to same-https origin and keep-scheme redirection from https context. - Test timed out
[task 2021-10-05T05:06:52.463Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.463Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects allowed for audio-tag to same-https origin and no-redirect redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.463Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.463Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to cross-http origin and keep-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to cross-http origin and no-redirect redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to cross-http origin and swap-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to cross-https origin and swap-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to same-http origin and keep-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to same-http origin and no-redirect redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to same-http origin and swap-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - 
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-NOTRUN | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | Mixed-Content: Expects blocked for audio-tag to same-https origin and swap-scheme redirection from https context. - expected PASS
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-UNEXPECTED-TIMEOUT | /mixed-content/gen/top.http-rp/opt-in/audio-tag.https.html | expected OK
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - TEST-INFO took 120742ms
[task 2021-10-05T05:06:52.464Z] 05:06:52     INFO - PID 10518 | 1633410412460	Marionette	INFO	Stopped listening on port 45079
[task 2021-10-05T05:06:52.580Z] 05:06:52     INFO - PID 10518 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-05T05:06:52.581Z] 05:06:52     INFO - PID 10518 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-05T05:06:52.610Z] 05:06:52     INFO - PID 10518 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-05T05:06:52.613Z] 05:06:52     INFO - PID 10518 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-10-05T05:07:02.613Z] 05:07:02     INFO - PID 10518 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"MediaShutdownManager: shutdown","state":"(none)","filename":"/builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp","lineNumber":69,"stack":"MediaShutdownManager shutdown"}] Barrier: xpcom-will-shutdown
[task 2021-10-05T05:07:07.570Z] 05:07:07     INFO - PID 10518 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 445))
[task 2021-10-05T05:07:10.504Z] 05:07:10     INFO - PID 10518 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7f2d84887800","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f2d8cc81000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f2d850ed000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]}] Barrier: profile-before-change
[task 2021-10-05T05:07:53.614Z] 05:07:53     INFO - PID 10518 | FATAL ERROR: AsyncShutdown timeout in xpcom-will-shutdown Conditions: [{"name":"MediaShutdownManager: shutdown","state":"(none)","filename":"/builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp","lineNumber":69,"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 2021-10-05T05:07:53.615Z] 05:07:53     INFO - PID 10518 | [Child 10858, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp:69
[task 2021-10-05T05:07:53.615Z] 05:07:53     INFO - PID 10518 | [Child 10858, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/media/MediaShutdownManager.cpp:69
[task 2021-10-05T05:08:01.504Z] 05:08:01     INFO - PID 10518 | FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7f2d84887800","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f2d8cc81000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"stack":["resource://gre/modules/nsAsyncShutdown.jsm:addBlocker:162"]},{"name":"ContentParent: id=7f2d850ed000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3510,"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 2021-10-05T05:08:01.505Z] 05:08:01     INFO - PID 10518 | [Parent 10518, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3510
[task 2021-10-05T05:08:01.505Z] 05:08:01     INFO - PID 10518 | [Parent 10518, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3510
[task 2021-10-05T05:08:01.508Z] 05:08:01     INFO - PID 10518 | ExceptionHandler::GenerateDump cloned child 11014
[task 2021-10-05T05:08:01.508Z] 05:08:01     INFO - PID 10518 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2021-10-05T05:08:01.509Z] 05:08:01     INFO - PID 10518 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2021-10-05T05:08:03.499Z] 05:08:03     INFO - Browser exited with return code 11
[task 2021-10-05T05:08:03.500Z] 05:08:03     INFO - Closing logging queue
[task 2021-10-05T05:08:03.501Z] 05:08:03     INFO - queue closed
[task 2021-10-05T05:08:03.530Z] 05:08:03     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmprgq86q8g
[task 2021-10-05T05:08:03.568Z] 05:08:03     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10717.
[task 2021-10-05T05:08:03.569Z] 05:08:03     INFO - PID 10717 | 1633410288769	Marionette	INFO	Marionette enabled
[task 2021-10-05T05:08:03.572Z] 05:08:03     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10847.
[task 2021-10-05T05:08:03.573Z] 05:08:03     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10852.
[task 2021-10-05T05:08:03.574Z] 05:08:03     INFO - PID 10717 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmprbiaug5o/search.json.mozlz4", (void 0)))
[task 2021-10-05T05:08:03.575Z] 05:08:03     INFO - PID 10717 | 1633410294599	Marionette	INFO	Listening on port 45529
[task 2021-10-05T05:08:03.577Z] 05:08:03     INFO - Starting runner
[task 2021-10-05T05:08:04.533Z] 05:08:04     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10909.
[task 2021-10-05T05:08:04.824Z] 05:08:04     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10910.
[task 2021-10-05T05:08:04.974Z] 05:08:04     INFO - PID 10717 | [CodeCoverage] Setting handlers for process 10911.
[task 2021-10-05T05:08:05.148Z] 05:08:05     INFO - TEST-START | /mixed-content/gen/top.http-rp/opt-in/beacon.https.html

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

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