Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Consumer video freezes when setPreferredLayers is executed continuously #990

Closed
satoren opened this issue Jan 26, 2023 · 20 comments · Fixed by #999
Closed

Consumer video freezes when setPreferredLayers is executed continuously #990

satoren opened this issue Jan 26, 2023 · 20 comments · Fixed by #999
Labels

Comments

@satoren
Copy link
Contributor

satoren commented Jan 26, 2023

Bug Report

When using simulcast and implementing switching with setPreferredLayers depending on the size to be displayed, we found a problem with video freezing in rare cases.

I have confirmed that it can be reproduced on the demo site, please see the video of the reproduction.
Execute setPreferredLayers again and the consumer video will start playing again.

Your environment

  • Operating system: macOS 13.0.1(22A400)(arm 64)
  • Node version: N/A
  • npm version: N/A
  • gcc/clang version: Apple clang version 14.0.0 (clang-1400.0.29.202)
  • mediasoup version: mediasoup: 0.11.2 (Rust) mediasoup-sys 0.5.3
  • mediasoup-client version: 3.6.50

Issue description

Occurs on demo page too. https://v3demo.mediasoup.org/

videofreeze2.mov

It can be confirmed that a large amount of packet loss is occurring at the timing of the stop.

screenshot 2023-01-26 15 12 56

Logs reproduced locally

[2023-01-27T01:02:12Z DEBUG mediasoup::worker_manager] create_worker()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] new()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] spawning worker with arguments [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b]:  --logLevel=debug --logTag=info --logTag=ice --logTag=dtls --logTag=rtp --logTag=srtp --logTag=rtcp --logTag=sctp --logTag=rtx --logTag=bwe --logTag=score --logTag=simulcast --logTag=svc --logTag=message --rtcMinPort=18000 --rtcMaxPort=18025
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] mediasoup-worker::mediasoup_worker_run() | starting mediasoup-worker process [version:0.11.2]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] mediasoup-worker::mediasoup_worker_run() | little-endian CPU detected
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] mediasoup-worker::mediasoup_worker_run() | 64 bits architecture detected
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() | <configuration>
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() |   logLevel            : debug
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() |   logTags             : info,ice,dtls,rtp,srtp,rtcp,rtx,bwe,score,simulcast,svc,sctp,message
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() |   rtcMinPort          : 18000
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() |   rtcMaxPort          : 18025
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] Settings::PrintConfiguration() | </configuration>
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] DepLibUV::PrintVersion() | libuv version: "1.44.2"
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] DepUsrSCTP::ClassInit() | usrsctp
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::CreateSslCtx() | setting SRTP cryptoSuites for DTLS: SRTP_AEAD_AES_256_GCM:SRTP_AEAD_AES_128_GCM:SRTP_AES128_CM_SHA1_80:SRTP_AES128_CM_SHA1_32
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GenerateFingerprints() | sha-1   fingerprint: B0:68:2B:0C:D3:5D:DD:4A:76:57:27:F6:6D:55:93:7B:C0:B7:E1:05
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GenerateFingerprints() | sha-256 fingerprint: 5E:D7:B4:58:53:BF:7A:C0:AA:38:E4:ED:70:76:EB:EF:F3:59:FC:4A:FE:95:8F:B2:8B:60:11:93:C5:4B:65:12
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GenerateFingerprints() | sha-384 fingerprint: BE:2B:DB:26:79:B3:1F:F8:E0:4F:72:1B:19:5B:E7:23:89:DA:67:BD:6A:1C:1A:BC:EF:69:57:BE:CF:B0:CA:62:91:D5:1E:D5:95:58:A2:BD:0A:09:94:DC:DF:E8:98:CD
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GenerateFingerprints() | sha-224 fingerprint: 1B:92:14:68:1C:6C:BC:A5:6B:C7:B8:B6:F2:90:2E:CA:45:AD:9D:51:B0:C7:AF:BD:93:F3:F4:B8
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GenerateFingerprints() | sha-512 fingerprint: 17:A4:E5:7D:18:BD:83:E1:D1:AC:E3:57:25:A0:B6:89:EF:A1:C2:3E:A1:1F:3E:77:FB:5D:EE:F2:8E:58:11:A9:4C:F3:19:4B:23:93:2C:4B:EC:2B:90:F5:F2:1E:FD:7C:28:84:C2:38:4A:E2:91:CB:42:B3:79:33:BC:DE:EF:C1
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] worker thread running [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] create_router()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request() [method:worker.createRouter, id:0]: WorkerCreateRouterRequest { router_id: RouterId(de8f1957-6863-47df-994f-90250948acc1) }
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request succeeded [method:worker.createRouter, id:0]
[2023-01-27T01:02:12Z DEBUG mediasoup::router] new()
[2023-01-27T01:02:12Z DEBUG mediasoup::router] create_webrtc_transport()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request() [method:router.createWebRtcTransport, id:1]: RouterCreateWebrtcTransportRequest { transport_id: TransportId(1e1e8b8a-0327-4af6-9ab1-6720241ac8cb), listen: Individual { listen_ips: TransportListenIps([ListenIp { ip: 0.0.0.0, announced_ip: Some(127.0.0.1) }]), port: None }, enable_udp: true, enable_tcp: true, prefer_udp: true, prefer_tcp: false, initial_available_outgoing_bitrate: 1000000, enable_sctp: false, num_sctp_streams: NumSctpStreams { os: 1024, mis: 1024 }, max_sctp_message_size: 262144, sctp_send_buffer_size: 262144, is_data_channel: true }
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request succeeded [method:router.createWebRtcTransport, id:1]
[2023-01-27T01:02:12Z DEBUG mediasoup::router::webrtc_transport] new()
[2023-01-27T01:02:12Z DEBUG mediasoup::router::webrtc_transport] connect()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request() [method:transport.connect, id:2]: TransportConnectWebRtcRequest { dtls_parameters: DtlsParameters { role: Client, fingerprints: [Sha256 { value: "F6:39:36:7E:33:1A:B9:7B:D4:B4:56:2C:46:74:DC:3D:C8:E9:C4:59:EA:C0:85:4C:80:4C:C2:9D:E0:24:73:01" }] } }
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request succeeded [method:transport.connect, id:2]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::IceServer::HandleTuple() | transition from state 'new' to 'connected' [hasUseCandidate:false, hasNomination:false, nomination:0]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerSelectedTuple() | ICE selected tuple
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerConnected() | ICE connected
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::MayRunDtlsTransport() | running DTLS transport in local role 'server'
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnDtlsTransportConnecting() | DTLS connecting
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::Run() | running [role:server]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | DTLS handshake start
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'before SSL initialization']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | role: server, waiting:'before SSL initialization']
[2023-01-27T01:02:12Z DEBUG mediasoup::router::webrtc_transport] produce()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request() [method:transport.produce, id:3]: TransportProduceRequest { producer_id: ProducerId(4cb25f9c-818f-47d3-be9e-645d7a455225), kind: Video, rtp_parameters: RtpParameters { mid: Some("0"), codecs: [Video { mime_type: Vp8, payload_type: 96, clock_rate: 90000, parameters: RtpCodecParametersParameters({}), rtcp_feedback: [GoogRemb, TransportCc, CcmFir, Nack, NackPli] }, Video { mime_type: Rtx, payload_type: 97, clock_rate: 90000, parameters: RtpCodecParametersParameters({"apt": Number(96)}), rtcp_feedback: [] }], header_extensions: [RtpHeaderExtensionParameters { uri: Mid, id: 4, encrypt: false }, RtpHeaderExtensionParameters { uri: RtpStreamId, id: 10, encrypt: false }, RtpHeaderExtensionParameters { uri: RepairRtpStreamId, id: 11, encrypt: false }, RtpHeaderExtensionParameters { uri: AbsSendTime, id: 2, encrypt: false }, RtpHeaderExtensionParameters { uri: TransportWideCcDraft01, id: 3, encrypt: false }, RtpHeaderExtensionParameters { uri: VideoOrientation, id: 13, encrypt: false }, RtpHeaderExtensionParameters { uri: TimeOffset, id: 14, encrypt: false }], encodings: [RtpEncodingParameters { ssrc: None, rid: Some("r0"), codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: Some(4.0), max_bitrate: None }, RtpEncodingParameters { ssrc: None, rid: Some("r1"), codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: None, max_bitrate: None }, RtpEncodingParameters { ssrc: None, rid: Some("r2"), codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: None, max_bitrate: None }], rtcp: RtcpParameters { cname: Some(""), reduced_size: true, mux: None } }, rtp_mapping: RtpMapping { codecs: [RtpMappingCodec { payload_type: 96, mapped_payload_type: 101 }, RtpMappingCodec { payload_type: 97, mapped_payload_type: 102 }], encodings: [RtpMappingEncoding { ssrc: None, rid: Some("r0"), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, mapped_ssrc: 765622440 }, RtpMappingEncoding { ssrc: None, rid: Some("r1"), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, mapped_ssrc: 765622441 }, RtpMappingEncoding { ssrc: None, rid: Some("r2"), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, mapped_ssrc: 765622442 }] }, key_frame_request_delay: 0, paused: false }
[2023-01-27T01:02:12Z DEBUG mediasoup::worker::channel] request succeeded [method:transport.produce, id:3]
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRequest() | enabling TransportCongestionControlServer with transport-cc
[2023-01-27T01:02:12Z DEBUG mediasoup::router::producer] new()
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerSelectedTuple() | ICE selected tuple
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'before SSL initialization']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client hello']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server hello']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write certificate']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write key exchange']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write certificate request']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server done']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | role: server, waiting:'SSLv3/TLS write server done']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server done']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client certificate']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client key exchange']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read certificate verify']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read change cipher spec']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read finished']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write change cipher spec']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write finished']
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | DTLS handshake done
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::CheckRemoteFingerprint() | valid remote fingerprint
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GetNegotiatedSrtpCryptoSuite() | chosen SRTP crypto suite: SRTP_AEAD_AES_256_GCM
[2023-01-27T01:02:12Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnDtlsTransportConnected() | DTLS connected
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::IceServer::HandleTuple() | transition from state 'connected' to 'completed' [hasUseCandidate:true, hasNomination:false, nomination:0]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerCompleted() | ICE completed
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | no Producer found for received Sender Report [ssrc:3555531889]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | no Producer found for received Sender Report [ssrc:93634843]
[2023-01-27T01:02:13Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2423016911, payloadType:97]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | [encodingIdx:1, ssrc:3555531889, rid:r1, payloadType:96]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | DTX enabled
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | FIR supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | NACK supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | PLI supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:14919]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | [encodingIdx:0, ssrc:93634843, rid:r0, payloadType:96]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | DTX enabled
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | FIR supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | NACK supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | PLI supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:6424]
[2023-01-27T01:02:13Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:3730564440, payloadType:97]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | no Producer found for received Sender Report [ssrc:644490790]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | [encodingIdx:2, ssrc:644490790, rid:r2, payloadType:96]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | DTX enabled
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | FIR supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | NACK supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::CreateRtpStream() | PLI supported
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:24241]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:14980]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:6432]
[2023-01-27T01:02:13Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:14Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:14Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:15Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:15Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:15Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:15Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:15Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:16Z DEBUG mediasoup::router] create_webrtc_transport()
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request() [method:router.createWebRtcTransport, id:4]: RouterCreateWebrtcTransportRequest { transport_id: TransportId(2cc0b9eb-9e64-47ea-802b-2d1daf490bf9), listen: Individual { listen_ips: TransportListenIps([ListenIp { ip: 0.0.0.0, announced_ip: Some(127.0.0.1) }]), port: None }, enable_udp: true, enable_tcp: true, prefer_udp: true, prefer_tcp: false, initial_available_outgoing_bitrate: 1000000, enable_sctp: false, num_sctp_streams: NumSctpStreams { os: 1024, mis: 1024 }, max_sctp_message_size: 262144, sctp_send_buffer_size: 262144, is_data_channel: true }
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request succeeded [method:router.createWebRtcTransport, id:4]
[2023-01-27T01:02:16Z DEBUG mediasoup::router::webrtc_transport] new()
[2023-01-27T01:02:16Z DEBUG mediasoup::router::webrtc_transport] consume()
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request() [method:transport.consume, id:5]: TransportConsumeRequest { consumer_id: ConsumerId(59e57491-e171-47a6-a211-d60740a3de50), producer_id: ProducerId(4cb25f9c-818f-47d3-be9e-645d7a455225), kind: Video, rtp_parameters: RtpParameters { mid: Some("0"), codecs: [Video { mime_type: Vp8, payload_type: 101, clock_rate: 90000, parameters: RtpCodecParametersParameters({}), rtcp_feedback: [TransportCc, CcmFir, Nack, NackPli] }, Video { mime_type: Rtx, payload_type: 102, clock_rate: 90000, parameters: RtpCodecParametersParameters({"apt": Number(101)}), rtcp_feedback: [] }], header_extensions: [RtpHeaderExtensionParameters { uri: Mid, id: 1, encrypt: false }, RtpHeaderExtensionParameters { uri: AbsSendTime, id: 4, encrypt: false }, RtpHeaderExtensionParameters { uri: TransportWideCcDraft01, id: 5, encrypt: false }, RtpHeaderExtensionParameters { uri: VideoOrientation, id: 11, encrypt: false }, RtpHeaderExtensionParameters { uri: TimeOffset, id: 12, encrypt: false }], encodings: [RtpEncodingParameters { ssrc: Some(377688296), rid: None, codec_payload_type: None, rtx: Some(RtpEncodingParametersRtx { ssrc: 377688297 }), dtx: None, scalability_mode: S3T3, scale_resolution_down_by: None, max_bitrate: None }], rtcp: RtcpParameters { cname: Some(""), reduced_size: true, mux: Some(true) } }, type: Simulcast, consumable_rtp_encodings: [RtpEncodingParameters { ssrc: Some(765622440), rid: None, codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: Some(4.0), max_bitrate: None }, RtpEncodingParameters { ssrc: Some(765622441), rid: None, codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: None, max_bitrate: None }, RtpEncodingParameters { ssrc: Some(765622442), rid: None, codec_payload_type: None, rtx: None, dtx: Some(true), scalability_mode: Custom { scalability_mode: "S1T3", spatial_layers: 1, temporal_layers: 3, ksvc: false }, scale_resolution_down_by: None, max_bitrate: None }], paused: false, preferred_layers: None, ignore_dtx: false }
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::CreateRtpStream() | [ssrc:377688296, payloadType:101]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::CreateRtpStream() | FIR supported
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::CreateRtpStream() | NACK supported
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::CreateRtpStream() | PLI supported
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request succeeded [method:transport.consume, id:5]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRequest() | enabling TransportCongestionControlClient with transport-cc
[2023-01-27T01:02:16Z DEBUG mediasoup::router::consumer] new()
[2023-01-27T01:02:16Z DEBUG mediasoup::router::webrtc_transport] connect()
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request() [method:transport.connect, id:6]: TransportConnectWebRtcRequest { dtls_parameters: DtlsParameters { role: Client, fingerprints: [Sha256 { value: "07:95:B5:F9:FF:F6:A0:4B:0E:0C:50:3B:29:45:71:B1:B3:53:A0:1D:0C:B4:0D:AF:BA:DB:BA:96:9F:1A:6F:D0" }] } }
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request succeeded [method:transport.connect, id:6]
[2023-01-27T01:02:16Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:7]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: None } }
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:7]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::IceServer::HandleTuple() | transition from state 'new' to 'connected' [hasUseCandidate:false, hasNomination:false, nomination:0]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerSelectedTuple() | ICE selected tuple
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerConnected() | ICE connected
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::MayRunDtlsTransport() | running DTLS transport in local role 'server'
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnDtlsTransportConnecting() | DTLS connecting
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::Run() | running [role:server]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | DTLS handshake start
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'before SSL initialization']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | role: server, waiting:'before SSL initialization']
[2023-01-27T01:02:16Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:8]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:16Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:8]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerSelectedTuple() | ICE selected tuple
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'before SSL initialization']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client hello']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server hello']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write certificate']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write key exchange']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write certificate request']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server done']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | role: server, waiting:'SSLv3/TLS write server done']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write server done']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client certificate']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read client key exchange']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read certificate verify']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read change cipher spec']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS read finished']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write change cipher spec']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | [role:server, action:'SSLv3/TLS write finished']
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::OnSslInfo() | DTLS handshake done
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::CheckRemoteFingerprint() | valid remote fingerprint
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::DtlsTransport::GetNegotiatedSrtpCryptoSuite() | chosen SRTP crypto suite: SRTP_AEAD_AES_256_GCM
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnDtlsTransportConnected() | DTLS connected
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] webrtc::BitrateProber::SetEnabled() | Bandwidth probing enabled, set to inactive
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] webrtc::AlrExperiment::CreateFromFieldTrial() | Using ALR experiment settings: pacing factor: 1.000000, max pacer queue length:2875, ALR bandwidth usage percent: 80, ALR start budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] webrtc::AimdRateControl::AimdRateControl() | Using aimd rate control with back off factor: 0.850000 
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | using spatial layer 2 as RTP timestamp reference
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:0, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::IceServer::HandleTuple() | transition from state 'connected' to 'completed' [hasUseCandidate:true, hasNomination:false, nomination:0]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::WebRtcTransport::OnIceServerCompleted() | ICE completed
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:6525]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:15213]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::TransportCongestionControlClient::MayEmitAvailableBitrateEvent() | high BWE value increase detected, notifying the listener [now:2128539, before:1001000]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:25330]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:2, ts:871210259] from original [ssrc:765622442, seq:25330, ts:871210259]
[2023-01-27T01:02:16Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:0, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:17Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:18Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:18Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:18Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:18Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:19Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:19Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:19Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:3730564440, payloadType:97]
[2023-01-27T01:02:19Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:19Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:20Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:20Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:20Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:20Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:21Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:21Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:21Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:21Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:22Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:22Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:22Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:22Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:22Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:23Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:23Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:23Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:23Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:3730564440, payloadType:97]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:24Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:25Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:25Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:25Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:26Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:26Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:26Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:27Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:27Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:27Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:27Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:27Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:28Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:28Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:28Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:28Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2423016911, payloadType:97]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:29Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:30Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:30Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:30Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:30Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:31Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:31Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:31Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:31Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:32Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:9]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:9]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:31879]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16468]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7009]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 2970
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:6205, ts:872635589] from original [ssrc:765622440, seq:7009, ts:1342784864]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:32Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:10]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:32Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:10]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16530]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7022]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:32121]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:32Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | cannot switch stream due to too high RTP timestamp extra offset needed (20520), requesting keyframe
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16592]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7036]
[2023-01-27T01:02:32Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:6232, ts:872689589] from original [ssrc:765622440, seq:7036, ts:1342838864]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:9, previous computed score:10, new computed score:9] (calling listener)
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:32295]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 5940
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:6234, ts:872692559] from original [ssrc:765622442, seq:32295, ts:872686619]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:33Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:34Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:34Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:11]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:34Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:11]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:32736]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16697]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7066]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:34Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | cannot switch stream due to too high RTP timestamp extra offset needed (8820), requesting keyframe
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:34Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:34Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:12]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:34Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:12]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:34Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:33213]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:7152, ts:872902889] from original [ssrc:765622442, seq:33213, ts:872896949]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16802]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7093]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:0, previous computed score:10, new computed score:3] (calling listener)
[2023-01-27T01:02:35Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::TransportCongestionControlClient::MayEmitAvailableBitrateEvent() | high BWE value decrease detected, notifying the listener [now:2934595, before:5846354]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:1, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:3555531889]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:33547]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:7486, ts:872961479] from original [ssrc:765622442, seq:33547, ts:872955539]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:16892]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:35Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | giving up on proper stream switching after got a requested keyframe for which still too high RTP timestamp extra offset is needed (8820)
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 1
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:7488, ts:872955630] from original [ssrc:765622441, seq:16892, ts:1337701127]
[2023-01-27T01:02:35Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7106]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:0, previous computed score:10, new computed score:0] (calling listener)
[2023-01-27T01:02:36Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::TransportCongestionControlClient::MayEmitAvailableBitrateEvent() | high BWE value decrease detected, notifying the listener [now:1696562, before:2934595]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:36Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:37Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:37Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:37Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:37Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:0, new computed score:7] (calling listener)
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::TransportCongestionControlClient::MayEmitAvailableBitrateEvent() | high BWE value increase detected, notifying the listener [now:5895671, before:1814744]
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:7, new computed score:8] (calling listener)
[2023-01-27T01:02:38Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:39Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:8, new computed score:9] (calling listener)
[2023-01-27T01:02:40Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:40Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:40Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:41Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:41Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:41Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:41Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:41Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2652327141, payloadType:97]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:9, new computed score:10] (calling listener)
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:42Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:42Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:13]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:42Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:13]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:36680]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:17353]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7292]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 2971
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:7951, ts:873557370] from original [ssrc:765622440, seq:7292, ts:1343706554]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:7, previous computed score:10, new computed score:7] (calling listener)
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:42Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:42Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:14]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:42Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:14]
[2023-01-27T01:02:42Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:43Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:43Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:43Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:7, new computed score:9] (calling listener)
[2023-01-27T01:02:43Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:43Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStream::UpdateScore() | [added score:10, previous computed score:9, new computed score:10] (calling listener)
[2023-01-27T01:02:44Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:38224]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:8061, ts:873856889] from original [ssrc:765622442, seq:38224, ts:873856889]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:17615]
[2023-01-27T01:02:45Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7402]
[2023-01-27T01:02:46Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:46Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:46Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:46Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:3730564440, payloadType:97]
[2023-01-27T01:02:47Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:47Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:47Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:47Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:47Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:48Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:15]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:48Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:15]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:39568]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:17865]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7495]
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 3060
[2023-01-27T01:02:48Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:9435, ts:874115369] from original [ssrc:765622440, seq:7495, ts:1344264644]
[2023-01-27T01:02:49Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:49Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:16]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:49Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:16]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:17921]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7507]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:39812]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | RTP timestamp extra offset generated for stream switching: 5940
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:9462, ts:874148399] from original [ssrc:765622442, seq:39812, ts:874142459]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:49Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:50Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:17]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:17]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:40490]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18026]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7548]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:50Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | cannot switch stream due to too high RTP timestamp extra offset needed (8910), requesting keyframe
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:50Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:18]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:18]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:41031]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:10681, ts:874380419] from original [ssrc:765622442, seq:41031, ts:874374479]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18129]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7586]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:51Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2423016911, payloadType:97]
[2023-01-27T01:02:52Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:52Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:52Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:52Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:52Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:41944]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:11594, ts:874571579] from original [ssrc:765622442, seq:41944, ts:874565639]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18275]
[2023-01-27T01:02:53Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7645]
[2023-01-27T01:02:54Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:54Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:54Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:54Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:55Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:55Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:55Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:55Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:55Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18580]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7723]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:43238]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:12888, ts:874842389] from original [ssrc:765622442, seq:43238, ts:874836449]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:02:56Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:56Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2423016911, payloadType:97]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:43812]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:13462, ts:874943099] from original [ssrc:765622442, seq:43812, ts:874937159]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18634]
[2023-01-27T01:02:57Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7764]
[2023-01-27T01:02:58Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:58Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:58Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:58Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:58Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:44788]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:14438, ts:875112389] from original [ssrc:765622442, seq:44788, ts:875106449]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18794]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7828]
[2023-01-27T01:02:59Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:00Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:00Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:00Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:00Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:01Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:01Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:01Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:01Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:01Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:3730564440, payloadType:97]
[2023-01-27T01:03:01Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:46203]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:15853, ts:875382839] from original [ssrc:765622442, seq:46203, ts:875376899]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18990]
[2023-01-27T01:03:02Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7917]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:46681]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:16331, ts:875478959] from original [ssrc:765622442, seq:46681, ts:875473019]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:19086]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7953]
[2023-01-27T01:03:03Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:04Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:04Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:04Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:04Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::HandleRtcpPacket() | PLI received, requesting key frame for Consumer [sender ssrc:1, media ssrc:377688296]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:644490790]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RTCP::XR::Parse() | unknown RTCP XR block type [blockType:42]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:47420]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:17070, ts:875654639] from original [ssrc:765622442, seq:47420, ts:875648699]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:19293]
[2023-01-27T01:03:05Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:8002]
@satoren satoren added the bug label Jan 26, 2023
@ibc
Copy link
Member

ibc commented Jan 26, 2023

It's hell to address issues reported based on mediasoup-demo behavior. We have verbose logs in the worker to explain what's going on regarding simulcast, layer switching, retransmissions and everything. Without those logs we cannot do anything. If you can replicate the issue locally and indicate what is indeed wrong as per printed logs, then we may investigate it. I'm afraid I have to close this issue otherwise.

BTW simulcast in screensharing is experimental in libwebrtc so we don't know if your Chrome is properly reacting to PLIs received by mediasoup (there are also logs about that in mediasoup worker if properly enabled). In addition, BWE in mediasoup is being modernized in an ongoing PR.

@ibc ibc closed this as completed Jan 26, 2023
@satoren
Copy link
Contributor Author

satoren commented Jan 26, 2023

I got it. Thanks. The reproduction procedure is easy, so I will try to reproduce it locally tomorrow and investigate.

@satoren
Copy link
Contributor Author

satoren commented Jan 27, 2023

My guess is that it appears to occur when setPreferredLayers is run again after the following logs and before the keyframe is received.

RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received

I'll check the code when I have time.

@satoren
Copy link
Contributor Author

satoren commented Jan 27, 2023

@ibc Should I still keep this closed? Still need something more?

@ibc
Copy link
Member

ibc commented Jan 27, 2023

cannot switch stream due to too high RTP timestamp extra offset needed

This is a legitimate warning, not a bug. Why it happens in this scenario? I don't know, but we don't know yet about any clear bug here.

BTW there is an old PR that for which we never got time to review, not sure if related, probably not: #500

@satoren
Copy link
Contributor Author

satoren commented Jan 27, 2023

This is a legitimate warning, not a bug. Why it happens in this scenario? I don't know, but we don't know yet about any clear bug here.

I understand that. Even with this log, if I run setPreferredLayers after receiving the keyframe, it appears to work correctly.
However, if I run setPreferredLayers again before receiving the keyframe, it appears to freeze. Just a guess.

By the way, can't we open an issue here if we don't understand the cause of the bug?

@ibc
Copy link
Member

ibc commented Jan 27, 2023

Imagine this is a legitimate behavior (Chrome fails to encode upon receipt of too many PLIs). Is that a mediasoup bug? It would be open here forever. Let's please reopen this issue once there are some evidences that it's a bug in mediasoup.

@satoren
Copy link
Contributor Author

satoren commented Jan 27, 2023

[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:18]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:18]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]

After the last setPreferredLayers was performed at this time, several keyframes were received in RTC::Producer.

[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:41031]

When it is working properly, the following log is output in response to RTC::SimulcastConsumer receiving a keyframe, but it is not output when consumer video is freezes

 RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received

@satoren
Copy link
Contributor Author

satoren commented Jan 27, 2023

@ibc
My guess seems to be correct.

[2023-01-27T01:02:50Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:17]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 0, temporal_layer: Some(2) } }
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:17]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:0, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:40490]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18026]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7548]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sync key frame received
[2023-01-27T01:02:50Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | cannot switch stream due to too high RTP timestamp extra offset needed (8910), requesting keyframe
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:50Z DEBUG mediasoup::router::consumer] set_preferred_layers()
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request() [method:consumer.setPreferredLayers, id:18]: ConsumerSetPreferredLayersRequest { data: ConsumerLayers { spatial_layer: 2, temporal_layer: Some(2) } }
[2023-01-27T01:02:50Z DEBUG mediasoup::worker::channel] request succeeded [method:consumer.setPreferredLayers, id:18]
[2023-01-27T01:02:50Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::UpdateTargetLayers() | target layers changed [spatial:2, temporal:1, consumerId:59e57491-e171-47a6-a211-d60740a3de50]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::RtpStreamRecv::RequestKeyFrame() | sending PLI [ssrc:93634843]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:644490790, seq:41031]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::SimulcastConsumer::SendRtpPacket() | sending sync packet [ssrc:377688296, seq:10681, ts:874380419] from original [ssrc:765622442, seq:41031, ts:874374479]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:3555531889, seq:18129]
[2023-01-27T01:02:51Z DEBUG mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Producer::ReceiveRtpPacket() | key frame received [ssrc:93634843, seq:7586]
[2023-01-27T01:02:51Z WARN  mediasoup::worker] [id:48b15d2a-6817-4af0-b9ba-72a74d8ba72b] RTC::Transport::ReceiveRtpPacket() | no suitable Producer for received RTP packet [ssrc:2423016911, payloadType:97]

After executing setPreferredLayers(spatial:0 temporal:2), spatialLayerToSync is set to 0 and is waiting to receive keyframes. The currentSpatialLayer remains unchanged at 2.

And if setPreferredLayers(spatial:2 temporal:2) is executed before the keyframe is received, currentSpatialLayer and targetSpatialLayer will both remain 2, so spatialLayerToSync is not updated and remains 0.

If a keyframe with spatial:0 is received in that state, it is droped here.

And
Request keyframes in spatial 2
Ignored because it does not match spatialLayerToSync
is repeated.

@ibc
Copy link
Member

ibc commented Jan 27, 2023

Issue reopened

satoren added a commit to satoren/mediasoup that referenced this issue Jan 28, 2023
satoren added a commit to satoren/mediasoup that referenced this issue Jan 28, 2023
satoren added a commit to satoren/mediasoup that referenced this issue Jan 28, 2023
satoren added a commit to satoren/mediasoup that referenced this issue Jan 28, 2023
@jmillan
Copy link
Member

jmillan commented Jan 30, 2023

After executing setPreferredLayers(spatial:0 temporal:2), spatialLayerToSync is set to 0 and is waiting to receive keyframes. The currentSpatialLayer remains unchanged at 2.

This does not seem to be possible. spatialLayerToSync is only set for keyframe RtpPacket, so it does not apply.

@jmillan
Copy link
Member

jmillan commented Jan 30, 2023

Definitely I don't see the problem. I'm trying to draw the flow you described @satoren.

  • We are in 2,2
  • setPreferredLayes 0,0
  • New RTP packet arrives, which is a key frame for layer 0
  • This block is entered.
    • this->spatialLayerToSync is set to 0
  • But we cannot switch to this stream because high RTP extra offset (as per the logs).
    • this->spatialLayerToSync KEEPS being 0 (it should be reseted to -1 as soon as we know we can't switch)
  • setPreferredLayers 2,2
  • Now, this->currentSpatialLayer = 2, and this->targetSpatialLayer = 2.
  • No sync is needed anywhere and packets are simply forwarded because we are in the current spatial layer, which happens to be the target too.

Where's the issue @satoren?

@ibc
Copy link
Member

ibc commented Jan 30, 2023

Same feelings here. I exposed some scenarios in the PR:

#993 (comment)

@satoren
Copy link
Contributor Author

satoren commented Jan 31, 2023

@jmillan

But we cannot switch to this stream because high RTP extra offset (as per the logs).

Perhaps this is not required. The trigger is to setPreferredLayers 2,2 again before receiving a switchable keyframe, to revert to the previous layer. It certainly seems to be one of the prerequisites that this log be output.

No sync is needed anywhere and packets are simply forwarded because we are in the current spatial layer, which happens to be the target too.

SimulcastConsumer continues to request key frames at short intervals. It does not appear that packets are being forwarded. It may be more problematic that syncRequired remains true.

I will share the code so that everyone can reproduce the problem relatively easily. (It would be best if I could write a test code that reproduces 100% of the problem, but I can't.)

apply this patch to rust example

diff --git a/rust/examples-frontend/svc-simulcast/src/index.ts b/rust/examples-frontend/svc-simulcast/src/index.ts
index 761edfb57..6287df412 100644
--- a/rust/examples-frontend/svc-simulcast/src/index.ts
+++ b/rust/examples-frontend/svc-simulcast/src/index.ts
@@ -95,7 +95,6 @@ type ClientMessage =
 
 async function init()
 {
-	const isFirefox = navigator.userAgent.toLowerCase().includes('firefox')
 	const sendPreview = document.querySelector('#preview-send') as HTMLVideoElement;
 	const receivePreview = document.querySelector('#preview-receive') as HTMLVideoElement;
 	const videoCodec = document.querySelector('#video-codec') as HTMLSpanElement;
@@ -139,21 +138,10 @@ async function init()
 		setPreferredLayers(newPreferredSpatialLayer, newPreferredTemporalLayer)
 	});
 	increaseLayer.addEventListener('click', () => {
-		let newPreferredSpatialLayer: number;
-		let newPreferredTemporalLayer: number;
-
-		if (preferredTemporalLayer < 2) {
-			newPreferredSpatialLayer = preferredSpatialLayer;
-			newPreferredTemporalLayer = preferredTemporalLayer + 1;
-		} else if (preferredSpatialLayer < 2) {
-			newPreferredSpatialLayer = preferredSpatialLayer + 1;
-			newPreferredTemporalLayer = 0;
-		} else {
-			newPreferredSpatialLayer = 0;
-			newPreferredTemporalLayer = 0;
-		}
-
-		setPreferredLayers(newPreferredSpatialLayer, newPreferredTemporalLayer)
+		setPreferredLayers(0, 0)
+		setTimeout(()=>{
+			setPreferredLayers(2, 1)
+		},30)
 	});
 
 
@@ -275,10 +263,6 @@ async function init()
 					{
 						const codec = track.kind === 'video'
 							?
-								device.rtpCapabilities.codecs?.find((codec) => {
-									// Firefox supports VP9, but not SVC
-									return codec.mimeType.toLowerCase() === 'video/vp9' && !isFirefox;
-								}) ??
 								device.rtpCapabilities.codecs?.find((codec) => codec.mimeType.toLowerCase() === 'video/vp8')
 							: undefined;
 						if (track.kind === 'video')

# in rust/examples
RUST_LOG=debug  cargo run --example svc-simulcast
# in rust/examples-frontend/svc-simulcast
yarn
yarn start

Pressing the up button repeatedly will freeze the video on the receiving end. Reproduce with chrome.

I could not reproduce it in Safari because not out logs as cannot switch stream due to too high RTP timestamp extra offset needed (8910), requesting keyframe.

@jmillan
Copy link
Member

jmillan commented Jan 31, 2023

I'll try it when I get some time.

@ibc
Copy link
Member

ibc commented Jan 31, 2023

@satoren can we confirm (if possible) that the issue happens when the "cannot switch to this stream because high RTP extra offset" log is shown? That's indeed weak code and logic and probably we are not resetting some state/flags when that happens.

@ibc
Copy link
Member

ibc commented Jan 31, 2023

Ok, let's imagine this scenario:

  • currentSpatialLayer is 1.
  • targetSpatialLayer is 2.
  • Key frame of spatial layer 2 arrives.

It will enter here:

// Check whether this is the packet we are waiting for in order to update
// the current spatial layer.
if (this->currentSpatialLayer != this->targetSpatialLayer && spatialLayer == this->targetSpatialLayer)
{
	// Ignore if not a key frame.
	if (!packet->IsKeyFrame())
		return;

	shouldSwitchCurrentSpatialLayer = true;

	// Need to resync the stream.
	this->syncRequired       = true;
	this->spatialLayerToSync = spatialLayer;
}

Here have set this->syncRequired = true, this->spatialLayerToSync = 2 and local flag shouldSwitchCurrentSpatialLayer = true.

Then imagine that "cannot switch stream due to too high RTP timestamp extra offset needed" happens:

else if (tsExtraOffset > maxTsExtraOffset)
{
	MS_WARN_TAG(
	  simulcast,
	  "cannot switch stream due to too high RTP timestamp extra offset needed (%" PRIu32
	  "), requesting keyframe",
	  tsExtraOffset);

	RequestKeyFrameForTargetSpatialLayer();

	this->keyFrameForTsOffsetRequested = true;

	return;
}

Here we return but we haven't reset neither this->syncRequired nor this->spatialLayerToSync.

This means that we will drop all new packets that are no key frames:

// If we need to sync and this is not a key frame, ignore the packet.
if (this->syncRequired && !packet->IsKeyFrame())
	return;

Not sure about the implications of not resetting this->spatialLayerToSync but it doesn't look good neither.

ibc added a commit that referenced this issue Jan 31, 2023
…d due to too high RTP timestamp extra offset needed

- It may fix #990.
- Rationale here: #990 (comment).
- Basically we should reset this->syncRequired` nor `this->spatialLayerToSync` when the keyframe that is supposed to switch to another spatial layer is discarded due to too high RTP timestamp extra offset needed.
@ibc
Copy link
Member

ibc commented Jan 31, 2023

PR created: #999

@ibc
Copy link
Member

ibc commented Jan 31, 2023

@satoren I'm gonna merge PR #999 and release a new version. Will also deploy it in mediasoup-demo.

Can you please test it and confirm that it fixes this issue?

@ibc
Copy link
Member

ibc commented Jan 31, 2023

Very good news :)
#999 (comment)

@ibc ibc closed this as completed in #999 Jan 31, 2023
ibc added a commit that referenced this issue Jan 31, 2023
…d due to too high RTP timestamp extra offset needed (#999)

- Fixes #990.
- Rationale here: #990 (comment).
- Basically we should reset this->syncRequired` nor `this->spatialLayerToSync` when the keyframe that is supposed to switch to another spatial layer is discarded due to too high RTP timestamp extra offset needed.
piranna pushed a commit to dyte-in/mediasoup that referenced this issue Feb 9, 2023
…d due to too high RTP timestamp extra offset needed (versatica#999)

- Fixes versatica#990.
- Rationale here: versatica#990 (comment).
- Basically we should reset this->syncRequired` nor `this->spatialLayerToSync` when the keyframe that is supposed to switch to another spatial layer is discarded due to too high RTP timestamp extra offset needed.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment