srs icon indicating copy to clipboard operation
srs copied to clipboard

Published 20 hours ago • verified publisher icon ossrs

WebRTC: 安卓webrtc推流报错 rtcp unprotect : not ready

Open xiamo6666 opened this issue 3 years ago • 3 comments

srs日志显示 [2022-02-18 17:35:51.689][Warn][18484][1f7l49o5][11] handle udp pkt, count=56825/56825, err: code=5015 : size=16, data=[80 cf 00 03 03 30 14 a8] : rtcp unprotect : not ready thread [18484][1f7l49o5]: cycle() [src/app/srs_app_listener.cpp:630][errno=11] thread [18484][9q7tl37m]: on_rtcp() [src/app/srs_app_rtc_conn.cpp:2130][errno=11] thread [18484][9q7tl37m]: unprotect_rtcp() [src/app/srs_app_rtc_dtls.cpp:1161][errno=11] [2022-02-18 17:35:53.275][Trace][18484][ca73m03a] Hybrid cpu=2.00%,12MB, cid=1,0, timer=62,10,48, clock=0,43,4,0,0,0,0,0,0 [2022-02-18 17:35:53.275][Trace][18484][ca73m03a] RTC: Server conns=1, rpkts=(7,rtp:7,stun:1,rtcp:1), spkts=(1,rtp:0,stun:1,rtcp:0), snk=(82,a:41,v:41,h:0), fid=(id:1,fid:7,ffid:0,addr:1,faddr:7) [2022-02-18 17:35:58.276][Trace][18484][ca73m03a] Hybrid cpu=2.00%,12MB, cid=154,60, timer=61,7,33, clock=0,37,10,1,1,0,0,0,0, free=1 [2022-02-18 17:35:58.276][Trace][18484][ca73m03a] RTC: Server conns=1, rpkts=(7,rtp:7,stun:1,rtcp:1), spkts=(1,rtp:0,stun:1,rtcp:0), snk=(82,a:41,v:41,h:0), fid=(id:1,fid:7,ffid:0,addr:1,faddr:7) [2022-02-18 17:36:01.457][Trace][18484][1f7l49o5] <- RTC RECV #10, udp 800, pps 57/79, schedule 800 [2022-02-18 17:36:01.702][Warn][18484][1f7l49o5][11] handle udp pkt, count=57634/57634, err: code=5015 : size=62, data=[80 6f 78 5a 18 17 66 f0] : marker=0, pt=111, seq=30810, ts=404186864, ssrc=3289106422, pad=0, payload=50B : not ready thread [18484][1f7l49o5]: cycle() [src/app/srs_app_listener.cpp:630][errno=11] thread [18484][9q7tl37m]: on_rtp() [src/app/srs_app_rtc_conn.cpp:1380][errno=11] thread [18484][9q7tl37m]: unprotect_rtp() [src/app/srs_app_rtc_dtls.cpp:1144][errno=11] [2022-02-18 17:36:03.277][Trace][18484][ca73m03a] Hybrid cpu=2.00%,12MB, cid=154,60, timer=61,7,33, clock=0,37,10,1,1,0,0,0,0, free=1 [2022-02-18 17:36:03.277][Trace][18484][ca73m03a] RTC: Server conns=1, rpkts=(77,rtp:76,stun:1,rtcp:1), spkts=(1,rtp:0,stun:1,rtcp:0), snk=(96,a:48,v:48,h:0), fid=(id:0,fid:77,ffid:0,addr:1,faddr:77)

xiamo6666 avatar Feb 18 '22 09:02 xiamo6666

I have also encountered this problem

TRANS_BY_GPT3

mcck avatar Jul 15 '22 08:07 mcck

have this issue revsolved?

kvneight avatar Jul 21 '22 10:07 kvneight

Here is srs log when I produced this issue. Looks like DTLS handshake failed, have no idea the problem is srs or android webrtc module.

[2022-07-21 20:04:35.979][Trace][9273][894592te] Hybrid cpu=0.00%,19MB, cid=1,0, timer=62,0,0, clock=0,49,0,0,0,0,0,0,0 [2022-07-21 20:04:36.413][Trace][9273][93o0im63] HTTP #0 192.168.0.10:49326 POST http://192.168.0.10:1985/rtc/v1/publish/, content-length=3843 [2022-07-21 20:04:36.413][Trace][9273][93o0im63] RTC publish webrtc://192.168.0.10:8084/live/livestream, api=https://192.168.0.10:8084/rtc/v1/publish/, tid=1658405075, clientip=123.235.109.234, app=live, stream=livestream, offer=3483B, eip=, codec= [2022-07-21 20:04:36.413][Trace][9273][93o0im63] ignore attribute=, value= [2022-07-21 20:04:36.413][Trace][9273][93o0im63] RTC publisher nack=1, nnc=1, pt-drop=0, twcc=1/3 [2022-07-21 20:04:36.414][Trace][9273][93o0im63] RTC: Init tracks {track: videtrack, is_active: 0=>1},{track: audiotrack, is_active: 0=>1}, ok [2022-07-21 20:04:36.414][Trace][9273][93o0im63] Best matched ip=10.25.77.241, ifname=eth0 [2022-07-21 20:04:36.414][Trace][9273][93o0im63] Best matched ip=172.17.0.1, ifname=docker0 [2022-07-21 20:04:36.414][Trace][9273][93o0im63] RTC: Use candidates 10.25.77.241, 192.168.0.10, 172.17.0.1 [2022-07-21 20:04:36.415][Trace][9273][93o0im63] RTC init session, user=0b137217:FuCX, url=/live/livestream, encrypt=1/1, DTLS(role=passive, version=auto), timeout=30000ms, nack=1 [2022-07-21 20:04:36.415][Trace][9273][93o0im63] RTC username=0b137217:FuCX, offer=3483B, answer=1767B [2022-07-21 20:04:36.415][Trace][9273][93o0im63] RTC remote offer: v=0\r\no=- 6191983178288356175 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=extmap-allow-mixed\r\na=msid-semantic: WMS localAudioStream localVideoStream\r\nm=audio 9 RTP/AVPF 111 103 9 102 0 8 105 13 110 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:FuCX\r\na=ice-pwd:JD3HRtPwRho+4Zr5qY/f3DHw\r\na=ice-options:trickle renomination\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:102 ILBC/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:3720879058 cname:C6peN0TUueu/7xzl\r\na=ssrc:3720879058 msid:localAudioStream audiotrack\r\na=ssrc:3720879058 mslabel:localAudioStream\r\na=ssrc:3720879058 label:audiotrack\r\nm=video 9 RTP/AVPF 96 97 98 99 35 36 100 101 127 125 104 124 106\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:FuCX\r\na=ice-pwd:JD3HRtPwRho+4Zr5qY/f3DHw\r\na=ice-options:trickle renomination\r\na=mid:video\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:5 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=sendrecv\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:35 AV1X/90000\r\na=rtcp-fb:35 goog-remb\r\na=rtcp-fb:35 transport-cc\r\na=rtcp-fb:35 ccm fir\r\na=rtcp-fb:35 nack\r\na=rtcp-fb:35 nack pli\r\na=rtpmap:36 rtx/90000\r\na=fmtp:36 apt=35\r\na=rtpmap:100 H264/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640c1f\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:127 H264/90000\r\na=rtcp-fb:127 goog-remb\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 ccm fir\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:125 rtx/90000\r\na=fmtp:125 apt=127\r\na=rtpmap:104 red/90000\r\na=rtpmap:124 rtx/90000\r\na=fmtp:124 apt=104\r\na=rtpmap:106 ulpfec/90000\r\na=ssrc-group:FID 3537545665 3455281884\r\na=ssrc:3537545665 cname:C6peN0TUueu/7xzl\r\na=ssrc:3537545665 msid:localVideoStream videtrack\r\na=ssrc:3537545665 mslabel:localVideoStream\r\na=ssrc:3537545665 label:videtrack\r\na=ssrc:3455281884 cname:C6peN0TUueu/7xzl\r\na=ssrc:3455281884 msid:localVideoStream videtrack\r\na=ssrc:3455281884 mslabel:localVideoStream\r\na=ssrc:3455281884 label:videtrack\r\n [2022-07-21 20:04:36.415][Trace][9273][93o0im63] RTC local answer: v=0\r\no=SRS/4.0.251(Leo) 94011594879856 2 IN IP4 0.0.0.0\r\ns=SRSPublishSession\r\nt=0 0\r\na=ice-lite\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS live/livestream\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111\r\nc=IN IP4 0.0.0.0\r\na=ice-ufrag:0b137217\r\na=ice-pwd:5178j6h24wch2987yo283g3m3225484t\r\na=fingerprint:sha-256 AA:A3:2C:19:D9:9C:79:DB:5E:06:EC:7C:28:A1:AF:EF:50:AA:D9:A7:62:CE:99:9D:B7:02:48:1D:F2:79:66:3B\r\na=setup:passive\r\na=mid:audio\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=candidate:0 1 udp 2130706431 10.25.77.241 6000 typ host generation 0\r\na=candidate:1 1 udp 2130706431 192.168.0.10 6000 typ host generation 0\r\na=candidate:2 1 udp 2130706431 172.17.0.1 6000 typ host generation 0\r\nm=video 9 UDP/TLS/RTP/SAVPF 127 104\r\nc=IN IP4 0.0.0.0\r\na=ice-ufrag:0b137217\r\na=ice-pwd:5178j6h24wch2987yo283g3m3225484t\r\na=fingerprint:sha-256 AA:A3:2C:19:D9:9C:79:DB:5E:06:EC:7C:28:A1:AF:EF:50:AA:D9:A7:62:CE:99:9D:B7:02:48:1D:F2:79:66:3B\r\na=setup:passive\r\na=mid:video\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:127 H264/90000\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:104 red/90000\r\na=candidate:0 1 udp 2130706431 10.25.77.241 6000 typ host generation 0\r\na=candidate:1 1 udp 2130706431 192.168.0.10 6000 typ host generation 0\r\na=candidate:2 1 udp 2130706431 172.17.0.1 6000 typ host generation 0\r\n [2022-07-21 20:04:36.417][Trace][9273][93o0im63] TCP: before dispose resource(HttpConn)(0x5580c77f74b0), conns=1, zombies=0, ign=0, inz=0, ind=0 [2022-07-21 20:04:36.417][Warn][9273][93o0im63][104] client disconnect peer. ret=1007 [2022-07-21 20:04:36.417][Trace][9273][7m8560t1] TCP: clear zombies=1 resources, conns=1, removing=0, unsubs=0 [2022-07-21 20:04:36.417][Trace][9273][93o0im63] TCP: disposing #0 resource(HttpConn)(0x5580c77f74b0), conns=1, disposing=1, zombies=0 [2022-07-21 20:04:37.044][Trace][9273][93o0im63] RTC: session address init 107.174.246.44:30653 [2022-07-21 20:04:37.044][Trace][9273][93o0im63] ### RTC: session STUN done, waiting DTLS handshake. [2022-07-21 20:04:37.044][Trace][9273][7ob3t276] <- RTC RECV #11, udp 786, pps 9/6, schedule 786 [2022-07-21 20:04:37.177][Trace][9273][93o0im63] RTC: session address change 107.174.246.44:30653 -> 107.174.246.44:47362, cached=0, nn_change=1/1, nn_address=1 [2022-07-21 20:04:37.479][Trace][9273][93o0im63] RTC: session address change 107.174.246.44:47362 -> 107.174.246.44:30653, cached=1, nn_change=2/1, nn_address=2 [2022-07-21 20:04:37.774][Warn][9273][7ob3t276][11] handle udp pkt, count=46271/46271, err: code=5015 : size=16, data=[80 cf 00 03 d2 da a9 c1] : rtcp unprotect : not ready thread [9273][7ob3t276]: cycle() [src/app/srs_app_listener.cpp:630][errno=11] thread [9273][93o0im63]: on_rtcp() [src/app/srs_app_rtc_conn.cpp:2130][errno=11] thread [9273][93o0im63]: unprotect_rtcp() [src/app/srs_app_rtc_dtls.cpp:1161][errno=11] [2022-07-21 20:04:40.980][Trace][9273][894592te] Hybrid cpu=1.00%,19MB, cid=1,0, timer=62,0,0, clock=0,49,0,0,0,0,0,0,0 [2022-07-21 20:04:40.980][Trace][9273][894592te] RTC: Server conns=1, rpkts=(3,rtp:3,stun:1,rtcp:1), spkts=(1,rtp:0,stun:1,rtcp:1), rtcp=(pli:0,twcc:1,rr:1), snk=(4,a:2,v:2,h:0), fid=(id:1,fid:3,ffid:0,addr:1,faddr:3) [2022-07-21 20:04:45.980][Trace][9273][894592te] Hybrid cpu=1.00%,19MB, cid=1,0, timer=62,0,0, clock=0,49,0,0,0,0,0,0,0 [2022-07-21 20:04:45.980][Trace][9273][894592te] RTC: Server conns=1, rpkts=(3,rtp:3,stun:1,rtcp:1), spkts=(1,rtp:0,stun:1,rtcp:1), rtcp=(pli:0,twcc:1,rr:1), snk=(4,a:2,v:2,h:0), fid=(id:1,fid:3,ffid:0,addr:1,faddr:3)

kvneight avatar Jul 22 '22 04:07 kvneight