search

roc-streaming / roc-toolkit

Real-time audio streaming over the network.
https://roc-streaming.org
Mozilla Public License 2.0
1.09k stars 213 forks source link

error messages with straightforward setup #528

Closed vladpetric closed 1 year ago

vladpetric commented 1 year ago

Hi,

I have a straightforward setup with a sender on an Rpi3 with Ubuntu 22.10, (built from source with speex disabled), running as follows:

~/bin/roc-send --input=alsa://hw:CARD=Receiver,DEV=0 --source=rtp+rs8m://192.168.1.231:10001 --repair=rs8m://192.168.1.231:10002 --control=rtcp://192.168.1.231:10003 -v --no-resampling --rate=44100

And a receiver running on an x86_64 box (Ubuntu 22.04, also built from source, with speex disabled), as follows:

~/bin/roc-recv -s rtp+rs8m://0.0.0.0:10001 -r rs8m://0.0.0.0:10002 --control=rtcp://0.0.0.0:10003 --output=alsa://default -v --rate=44100  --no-resampling --sess-latency=120ms   -v

Nothing out of the ordinary (AFAICT) on the sender side, but on the receiver side I get a lot of:

16:47:54.427 [455099] [dbg] roc_audio: depacketizer: got first packet: zero_samples=4944
16:47:54.427 [455099] [dbg] roc_audio: latency monitor: latency=5253 target=5292
16:47:54.442 [455099] [dbg] roc_packet: router: detected new stream: source=0 flags=0x20u
16:47:54.442 [455099] [dbg] roc_fec: fec reader: update repair block size: cur_sblen=20 cur_rblen=0 new_rblen=235
16:47:54.444 [455099] [dbg] roc_audio: watchdog: status: bbbbbbbbbbbbbbbb....
16:47:54.983 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:47:54.983 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:47:59.429 [455099] [dbg] roc_audio: latency monitor: latency=5562 target=5292
16:48:00.541 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:48:00.541 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:48:02.662 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:48:02.662 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:48:04.431 [455099] [dbg] roc_audio: latency monitor: latency=5253 target=5292
16:48:06.081 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:48:06.081 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:48:09.433 [455099] [dbg] roc_audio: latency monitor: latency=4944 target=5292
16:48:11.643 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:48:11.643 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:48:12.669 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:48:12.669 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:48:14.322 [455099] [dbg] roc_audio: depacketizer: ts=286944917 loss_ratio=0.00000
16:48:14.430 [455099] [dbg] roc_audio: latency monitor: latency=5253 target=5292

Any thoughts?

vladpetric commented 1 year ago

I do get some late packages too, but that I feel is normal. The bad version happens irrespective of that:

16:59:34.433 [455099] [dbg] roc_audio: latency monitor: latency=4635 target=5292                                                                                                                                [58/46262]
16:59:34.521 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:34.521 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:39.432 [455099] [dbg] roc_audio: latency monitor: latency=4635 target=5292
16:59:40.073 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:40.073 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:43.673 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:43.673 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:44.430 [455099] [dbg] roc_audio: latency monitor: latency=4635 target=5292
16:59:45.605 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:45.606 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:49.441 [455099] [dbg] roc_audio: latency monitor: latency=4944 target=5292
16:59:50.823 [455099] [dbg] roc_audio: watchdog: status: ..............bbbbbb
16:59:50.840 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317658899
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317659208
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317659517
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317659826
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317660135
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317660444
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317660753
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: dropping late packet: ts=317661371 pkt_ts=317661062
16:59:50.841 [455099] [dbg] roc_audio: depacketizer: fetched=1 dropped=8
16:59:50.960 [455099] [dbg] roc_audio: watchdog: status: bbD.................
16:59:51.150 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:51.150 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:51.665 [455099] [dbg] roc_pipeline: pipeline loop: tasks=4 in_place=1.00 in_frame=0.00 preempts=0 sched=0/0
16:59:53.705 [455099] [dbg] roc_rtp: rtp parser: bad version: get=0 expected=2
16:59:53.705 [455099] [dbg] roc_pipeline: receiver endpoint: can't parse packet
16:59:54.327 [455099] [dbg] roc_audio: depacketizer: ts=317815253 loss_ratio=0.00074
16:59:54.434 [455099] [dbg] roc_audio: latency monitor: latency=4635 target=5292
vladpetric commented 1 year ago

Apologies, it seems that something else was firing packets on the same port(s). I changed the ports to something more random and the problem disappeared.

gavv commented 1 year ago

Thanks for sharing solution :)