Closed Bug 1745278 Opened 3 years ago Closed 3 years ago

Intermittent Assertion failure: mRtcpState == mRtpState, at /builds/worker/checkouts/gecko/dom/media/webrtc/transportbridge/MediaPipeline.cpp:657

Categories

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

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

Tracking

()

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox95 --- unaffected
firefox96 --- wontfix
firefox97 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: assertion, intermittent-failure, regression)

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

[task 2021-12-09T21:07:42.104Z] 21:07:42     INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_peerConnection_restartIceNoBundleNoRtcpMux.html
[task 2021-12-09T21:07:42.348Z] 21:07:42     INFO - GECKO(3974) | [Parent 3974, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:361
[task 2021-12-09T21:07:42.456Z] 21:07:42     INFO - GECKO(3974) | Timecard created 1639084055.731694
<...>
[task 2021-12-09T21:07:44.858Z] 21:07:44     INFO - GECKO(3974) | Assertion failure: mRtcpState == mRtpState, at /builds/worker/checkouts/gecko/dom/media/webrtc/transportbridge/MediaPipeline.cpp:657
[task 2021-12-09T21:07:44.859Z] 21:07:44     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2021-12-09T21:07:44.879Z] 21:07:44     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-12-09T21:07:53.263Z] 21:07:53     INFO - GECKO(3974) | #01: mozilla::MediaPipeline::PacketReceived(std::string const&, mozilla::MediaPacket const&) [dom/media/webrtc/transportbridge/MediaPipeline.cpp:657]
[task 2021-12-09T21:07:53.266Z] 21:07:53     INFO - GECKO(3974) | #02: mozilla::MediaTransportHandler::OnPacketReceived(std::string const&, mozilla::MediaPacket const&) [dom/media/webrtc/jsapi/MediaTransportHandler.cpp:1189]
[task 2021-12-09T21:07:53.266Z] 21:07:53     INFO - GECKO(3974) | #03: mozilla::MediaTransportChild::RecvOnPacketReceived(std::string const&, mozilla::MediaPacket const&) [dom/media/webrtc/jsapi/MediaTransportHandlerIPC.cpp:379]
[task 2021-12-09T21:07:53.267Z] 21:07:53     INFO - GECKO(3974) | #04: mozilla::dom::PMediaTransportChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:b280768d5832c53c698a57a6af68f226fcc261c31a910500166db77a2f5198d44423ca4afd738e19bbe3b3e20bcf643fc2cafb7447aca592edc82d6988e3eaf5/ipc/ipdl/PMediaTransportChild.cpp::960]
[task 2021-12-09T21:07:53.268Z] 21:07:53     INFO - GECKO(3974) | #05: mozilla::ipc::PBackgroundChild::OnMessageReceived(IPC::Message const&) [s3:gecko-generated-sources:ad6653ed5f4162df51b41ef67d7ef7aa9431094a0ce2ee1c5873e04dbc4cf6761551af58e6bfb35113a3acb93187decd379628bb8dddc5ee358c0b682590e137/ipc/ipdl/PBackgroundChild.cpp::6090]
[task 2021-12-09T21:07:53.269Z] 21:07:53     INFO - GECKO(3974) | #06: mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) [ipc/glue/MessageChannel.cpp:2043]
[task 2021-12-09T21:07:53.269Z] 21:07:53     INFO - GECKO(3974) | #07: mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) [ipc/glue/MessageChannel.cpp:1971]
[task 2021-12-09T21:07:53.270Z] 21:07:53     INFO - GECKO(3974) | #08: mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) [ipc/glue/MessageChannel.cpp:1827]
[task 2021-12-09T21:07:53.271Z] 21:07:53     INFO - GECKO(3974) | #09: mozilla::ipc::MessageChannel::MessageTask::Run() [ipc/glue/MessageChannel.cpp:1857]
[task 2021-12-09T21:07:53.271Z] 21:07:53     INFO - GECKO(3974) | #10: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1178]
[task 2021-12-09T21:07:53.272Z] 21:07:53     INFO - GECKO(3974) | #11: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:467]
[task 2021-12-09T21:07:53.273Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2021-12-09T21:07:53.273Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2021-12-09T21:07:53.274Z] 21:07:53     INFO - GECKO(3974) | #12: mozilla::net::nsSocketTransportService::Run() [netwerk/base/nsSocketTransportService2.cpp:1191]
[task 2021-12-09T21:07:53.274Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: D/mtransport Created SRTP flow!
[task 2021-12-09T21:07:53.275Z] 21:07:53     INFO - GECKO(3974) | #13: {virtual override thunk({offset(-32)}, mozilla::net::nsSocketTransportService::Run())} [/builds/worker/workspace/build/application/firefox/libxul.so + 0x4828b35]
[task 2021-12-09T21:07:53.276Z] 21:07:53     INFO - GECKO(3974) | #14: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1178]
[task 2021-12-09T21:07:53.276Z] 21:07:53     INFO - GECKO(3974) | #15: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:467]
[task 2021-12-09T21:07:53.277Z] 21:07:53     INFO - GECKO(3974) | #16: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:301]
[task 2021-12-09T21:07:53.277Z] 21:07:53     INFO - GECKO(3974) | #17: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:331]
[task 2021-12-09T21:07:53.278Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2021-12-09T21:07:53.279Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2021-12-09T21:07:53.279Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: D/mtransport Created SRTP flow!
[task 2021-12-09T21:07:53.280Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2021-12-09T21:07:53.281Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_1(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2021-12-09T21:07:53.281Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_1(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2021-12-09T21:07:53.282Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: D/mtransport Created SRTP flow!
[task 2021-12-09T21:07:53.287Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2021-12-09T21:07:53.288Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2021-12-09T21:07:53.288Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022: Socket Thread]: D/mtransport Created SRTP flow!
[task 2021-12-09T21:07:53.290Z] 21:07:53     INFO - GECKO(3974) | #18: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:307]
[task 2021-12-09T21:07:53.290Z] 21:07:53     INFO - GECKO(3974) | #19: nsThread::ThreadFunc(void*) [xpcom/threads/nsThread.cpp:393]
[task 2021-12-09T21:07:53.291Z] 21:07:53     INFO - GECKO(3974) | #20: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2021-12-09T21:07:53.292Z] 21:07:53     INFO - GECKO(3974) | #21: ??? [/lib/x86_64-linux-gnu/libpthread.so.0 + 0x76db]
[task 2021-12-09T21:07:53.305Z] 21:07:53     INFO - GECKO(3974) | #22: clone [/lib/x86_64-linux-gnu/libc.so.6 + 0x121a3f]
[task 2021-12-09T21:07:53.305Z] 21:07:53     INFO - GECKO(3974) | #23: ??? (???:???)
[task 2021-12-09T21:07:53.306Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.306Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.307Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.307Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.307Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.311Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.312Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.312Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.313Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.313Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.314Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.314Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.315Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Ignoring message 'EVENT_MESSAGE' to unknown peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:341
[task 2021-12-09T21:07:53.315Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Rejecting introduction request from '893785BE3B0CD22D.C8FBD792C4140D8' for unknown peer 'BBB40024D161BEB6.5FA83B8AC74828F5': file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:668
[task 2021-12-09T21:07:53.315Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, IPC I/O Parent] WARNING: [1.1]: Rejecting introduction request from '13777D0E4418B329.A5916FC72F32F99E' for unknown peer 'BBB40024D161BEB6.5FA83B8AC74828F5': file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:668
[task 2021-12-09T21:07:53.316Z] 21:07:53     INFO - GECKO(3974) | [Socket 4022, IPC I/O Child] WARNING: [13777D0E4418B329.A5916FC72F32F99E]: Could not be introduced to peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:599
[task 2021-12-09T21:07:53.317Z] 21:07:53     INFO - GECKO(3974) | [RDD 4248, IPC I/O Child] WARNING: [893785BE3B0CD22D.C8FBD792C4140D8]: Could not be introduced to peer BBB40024D161BEB6.5FA83B8AC74828F5: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:599
[task 2021-12-09T21:07:53.317Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: IPC message 'PBrowser::Msg_StopIMEStateManagement' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-09T21:07:53.318Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: IPC message 'PBrowser::Msg_Destroy' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-09T21:07:53.319Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: No build ID mismatch: file /builds/worker/checkouts/gecko/dom/base/nsFrameLoader.cpp:3837
[task 2021-12-09T21:07:53.319Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: IPC message 'PContent::Msg_CommitBrowsingContextTransaction' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-09T21:07:53.320Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: IPC message 'PContent::Msg_DiscardBrowsingContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:523
[task 2021-12-09T21:07:53.321Z] 21:07:53     INFO - GECKO(3974) | [Parent 3974, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x804B0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS): file /builds/worker/checkouts/gecko/toolkit/components/antitracking/URLQueryStringStripper.cpp:129

So bug 1733930 was for this assertion in mediapipeline_unittest. It is now showing up in a mochitest, so it could also be an issue in the field. I would guess the implication of this happening in the field is not too bad.

Regressions: 1654112
Regressed by: 1654112
No longer regressions: 1654112
Has Regression Range: --- → yes

Set release status flags based on info from the regressing bug 1654112

status-firefox95: --- → unaffected
status-firefox96: --- → affected
status-firefox97: --- → affected
status-firefox-esr91: --- → unaffected

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
You need to log in before you can comment on or make changes to this bug.