Skip to content

HTTPS clone URL

Subversion checkout URL

You can clone with HTTPS or Subversion.

Download ZIP

Loading…

No video with some channels #41

Closed
n37 opened this Issue · 73 comments

6 participants

n37 Alexander Pipelka Darren Black James Mika Daniel Scheller Rainer Hochecker
n37

I came across some channels which can't be played with the xvdr plugin.

vdr: [11025] XVDR: --------------------------------------
vdr: [11025] XVDR: Channel streaming request: 564 - ZZZZZ
vdr: [11025] XVDR: Found available device 1
vdr: [11025] setstatus 0
vdr: [11025] XVDR: Creating new live Receiver
vdr: [11025] XVDR: Starting live receiver
vdr: [11025] XVDR: cStreamdevPatFilter("ZZZZZ")
vdr: [11026] XVDR: LiveQueue started
vdr: [11022] buffer stats: 349868 (8%) used
vdr: [11022] receiver on device 1 thread ended (pid=10977, tid=11022)
vdr: [11025] XVDR: VDR active, sending stream start message
vdr: [11027] cLiveStreamer stream processor thread started (pid=10977, tid=11027)
vdr: [11025] XVDR: Successfully switched to channel 564 - ZZZZZ
vdr: [11025] XVDR: Started streaming of channel ZZZZZ (timeout 3 seconds, priority 50)
vdr: [11028] receiver on device 1 thread started (pid=10977, tid=11028)
vdr: [10984] XVDR: Client with ID 5 seems to be disconnected, removing from client list
vdr: [10984] XVDR: ~cXVDRClient
vdr: [10984] XVDR: done
vdr: [11029] TS buffer on device 1 thread started (pid=10977, tid=11029)
vdr: [10982] setstatus 0
vdr: [10982] setstatus 1
vdr: [10982] Filter Pid:0,Tid:0 added.
vdr: [10989] XVDR: Received chan=1, ser=2133, op=7, edl=0
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner adding PID 160 (ISO/IEC 14496-10 Video (MPEG-4 part 10/AVC, aka H.264))
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner adding PID 80 (ISO/IEC 13818-3 Audio) (eng)
vdr: [10982] XVDR: NOT adding PID 208 (type 0xc0) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 208 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 309 (type 0xc0) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 309 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 253 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 253 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 307 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 307 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 392 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 392 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 616 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 616 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 888 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 888 (UNKNOWN) UNKNOWN
vdr: [10982] XVDR: NOT adding PID 356 (type 0xc1) RegDesc not found -> UNKNOWN
vdr: [10982] XVDR: cStreamdevPatFilter PMT scanner: NOT adding PID 356 (UNKNOWN) UNKNOWN
vdr: [10982] PMT scan idle
vdr: [10982] EEPG: Ended all processing
vdr: [11027] XVDR: --------------------------------------
vdr: [11027] XVDR: NEW AUDIO INFORMATION:
vdr: [11027] XVDR: Channels: 2
vdr: [11027] XVDR: Samplerate: 48000 Hz
vdr: [11027] XVDR: Bitrate: 192000 bps
vdr: [11027] XVDR: --------------------------------------
vdr: [10989] XVDR: Received chan=1, ser=2134, op=7, edl=0
vdr: [10989] XVDR: Received chan=1, ser=2135, op=82, edl=0
vdr: [10989] XVDR: Received chan=1, ser=2136, op=7, edl=0
vdr: [10989] XVDR: Received chan=1, ser=2137, op=7, edl=0
.........................

The last messages keep repeating and streaming doesn't ever start. The same channel is playable with streamdev. Also recordings made on the same channel can be played in xbmc.

n37

A quick follow up. I also checked with vdr-xine and there was no problem so it must be something to do with xvdr.

n37

I don't know if this might be somehow relevant but I tried playing these problematic channles with streamdev and xine. This is just xine, not the xine vdr plugin. xine http://127.0.0.1:3000/. So here is what xine spewed out with these channels:

******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=3 prefs=1 l1=1 brefs=0 type=0 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=1 prefs=1 l1=1 brefs=0 type=1 (8 fps)
******** Missing refframes, dropping. nrf=4 lo=8 prefs=4 l1=1 brefs=0 type=0 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=4 prefs=2 l1=1 brefs=0 type=0 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=2 prefs=2 l1=2 brefs=0 type=1 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=4 prefs=0 l1=2 brefs=0 type=1 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=2 prefs=0 l1=2 brefs=0 type=1 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=4 prefs=0 l1=2 brefs=0 type=1 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=2 prefs=0 l1=2 brefs=0 type=1 (16 fps)
******** Missing refframes, dropping. nrf=4 lo=2 prefs=0 l1=2 brefs=0 type=1 (16 fps)

I wonder if the changing fps at the end of these lines might provide some clue as to why streaming never starts wiith xvdr.

Alexander Pipelka
Owner

Picture information isn't detected on this channel. Streaming will not start before the picture information is known.
I see that the plugin was compiled with DEBUG=1 but there aren't any H.264 debug messages.
Something really strange is happening within this stream.

Is it possible to record ~30 seconds of this stream ?
I would like to analyze this.

BTW, do you have channel updates enabled ?

Darren Black

I have the same issue. This only began recently for me.

I'll record some of the stream for you. Is part of a recording from VDR good enough, or does it need to be more 'raw'?

Darren Black

The same issue is apparent on the same channels using VNSI.

n37
n37 commented

I sent a sample long time ago. Unfortunately the dev does not have the time to fix this. Maybe you stand a better chance with the vnsi dev.

Alexander Pipelka
Owner

Ok. That's not quite right.
I'm currently completely rewriting the demuxers, ...

I bit more patience please. ;-)

Darren Black

Out of interest, how will I know when the issue should be fixed?

This isn't meant as a request for a release or date, rather a genuine question, since I'm not sure when the appropriate changes will have been committed :-)

James Mika

Same problem here, tested with both xvdr and vnsi, xvdr no picture at all on the problematic channels, vnsi I get picture but vdpau is not working. If I playback the same channels with the streamdev plugin they work just fine.

That's with vdr 1.7.27 and vdr-plugin-xvdr 0.9.5.git20120414

James Mika

In the meantime I updated to frodo beta 2, compiled newest vr-plugin-xvdr from git and use the pvr repo for the xbmc xvdr plugin, still the same problems. I see you're talking about updated demuxers in another issue with audio drop outs. Would you still need video snippets or logs for this issue here or you know the culprit already? I'm happy to provide both if needed.

n37

Hi Alex,

The latest changes to the plugin have broken again playback on these channels. Iirc, some time ago you implemented a temporary fix for these and they worked fine. Now they are broken again. Here is what the logs show:
vdr: [2990] XVDR: --------------------------------------
vdr: [2990] XVDR: NEW AUDIO INFORMATION:
vdr: [2990] XVDR: Channels: 2
vdr: [2990] XVDR: Samplerate: 48000 Hz
vdr: [2990] XVDR: Bitrate: 192000 bps
vdr: [2990] XVDR: --------------------------------------
vdr: [2990] XVDR-Error: pic_order_cnt_type = 33
vdr: [2990] XVDR-Error: pic_order_cnt_type = 33
vdr: [2990] XVDR-Error: pic_order_cnt_type = 33

n37

There is another issue related to the demuxer changes. If the connection to vdr server drops for some reason, when it is restored, video playback continues (though it is somewhat jerky) while there is no audio.

vdr: [3248] XVDR: streaming of channel started
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: NEW AUDIO INFORMATION:
vdr: [3248] XVDR: Channels: 1
vdr: [3248] XVDR: Samplerate: 48000 Hz
vdr: [3248] XVDR: Bitrate: 160000 bps
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: NEW AUDIO INFORMATION:
vdr: [3248] XVDR: Channels: 1
vdr: [3248] XVDR: Samplerate: 48000 Hz
vdr: [3248] XVDR: Bitrate: 160000 bps
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: NEW AUDIO INFORMATION:
vdr: [3248] XVDR: Channels: 6
vdr: [3248] XVDR: Samplerate: 48000 Hz
vdr: [3248] XVDR: Bitrate: 384000 bps
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: NEW AUDIO INFORMATION:
vdr: [3248] XVDR: Channels: 6
vdr: [3248] XVDR: Samplerate: 48000 Hz
vdr: [3248] XVDR: Bitrate: 384000 bps
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: NEW PICTURE INFORMATION:
vdr: [3248] XVDR: Picture Width: 1920
vdr: [3248] XVDR: Picture Height: 1080
vdr: [3248] XVDR: Display Aspect Ratio: 1.78
vdr: [3248] XVDR: --------------------------------------
vdr: [3248] XVDR: sync found at offset 16416
vdr: [3248] XVDR: sync found at offset 17064
vdr: [3248] XVDR: sync found at offset 112
vdr: [3248] XVDR: sync found at offset 400
vdr: [3248] XVDR: sync found at offset 568
vdr: [3248] XVDR: sync found at offset 432
vdr: [3248] XVDR: sync found at offset 72
vdr: [3248] XVDR: sync found at offset 432
vdr: [3248] XVDR: sync found at offset 736
vdr: [3248] XVDR: sync found at offset 200
vdr: [3248] XVDR: sync found at offset 16
...

Here is another log. This time it started with no audio form the beginning and eventually playback stopped altogether.

vdr: [3426] XVDR: streaming of channel started
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW AUDIO INFORMATION:
vdr: [3426] XVDR: Channels: 1
vdr: [3426] XVDR: Samplerate: 48000 Hz
vdr: [3426] XVDR: Bitrate: 160000 bps
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW AUDIO INFORMATION:
vdr: [3426] XVDR: Channels: 6
vdr: [3426] XVDR: Samplerate: 48000 Hz
vdr: [3426] XVDR: Bitrate: 384000 bps
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW AUDIO INFORMATION:
vdr: [3426] XVDR: Channels: 6
vdr: [3426] XVDR: Samplerate: 48000 Hz
vdr: [3426] XVDR: Bitrate: 384000 bps
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW AUDIO INFORMATION:
vdr: [3426] XVDR: Channels: 1
vdr: [3426] XVDR: Samplerate: 48000 Hz
vdr: [3426] XVDR: Bitrate: 160000 bps
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW PICTURE INFORMATION:
vdr: [3426] XVDR: Picture Width: 1920
vdr: [3426] XVDR: Picture Height: 1080
vdr: [3426] XVDR: Display Aspect Ratio: 1.78
vdr: [3426] XVDR: --------------------------------------
vdr: [2791] XVDR: Checking for channel updates ...
vdr: [2791] XVDR: Client 2: 970 channels, no change
vdr: [2791] XVDR: Done.
vdr: [3426] XVDR: sync found at offset 14627
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: NEW AUDIO INFORMATION:
vdr: [3426] XVDR: Channels: 2
vdr: [3426] XVDR: Samplerate: 10 Hz
vdr: [3426] XVDR: Bitrate: 7 bps
vdr: [3426] XVDR: --------------------------------------
vdr: [3426] XVDR: sync found at offset 25289

Alexander Pipelka
Owner

Hi,

About the H264 issue:
I didn't even know that the fix was working, ...
I'll add it again until I find some time to dig into this.

About the connection lost thing:
It isn't intended that the stream continues to play after connection loss. I should simply stop.
Maybe the interruption was too short (the connection went up again before the "connection lost" warning appeared).
In this case a have to take a look at the connection lost detection.

n37

Sorry for not reporting it working. I thought that you were aware that it was but left the issue open because the fix was a hack.
About the audio issue. This time I tried the demuxer branch. The bigger buffer seems to alleviate the situation as the stream is able more frequently recover after the "sync found at" messages. However there was no "Connection lost" message or any other indication when this happened:

Dec 18 10:45:40 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:40 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:45:40 localhost vdr: [4368] XVDR: Channels: 1
Dec 18 10:45:40 localhost vdr: [4368] XVDR: Samplerate: 48000 Hz
Dec 18 10:45:40 localhost vdr: [4368] XVDR: Bitrate: 160000 bps
Dec 18 10:45:40 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Channels: 6
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Samplerate: 48000 Hz
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Bitrate: 384000 bps
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Channels: 6
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Samplerate: 48000 Hz
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Bitrate: 384000 bps
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Channels: 1
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Samplerate: 48000 Hz
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Bitrate: 160000 bps
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: NEW PICTURE INFORMATION:
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Picture Width: 1920
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Picture Height: 1080
Dec 18 10:45:41 localhost vdr: [4368] XVDR: Display Aspect Ratio: 1.78
Dec 18 10:45:41 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:45:41 localhost vdr: [4368] XVDR: streaming of channel started
Dec 18 10:45:50 localhost vdr: [4260] XVDR: Checking for channel updates ...
Dec 18 10:45:50 localhost vdr: [4260] XVDR: Client 0: 970 channels, no change
Dec 18 10:45:50 localhost vdr: [4260] XVDR: Done.
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 2128
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 1168
Dec 18 10:46:09 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:46:09 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Channels: 2
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Samplerate: 24000 Hz
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Bitrate: 40000 bps
Dec 18 10:46:09 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:09 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:46:09 localhost vdr: [4368] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Channels: 2
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Samplerate: 24000 Hz
Dec 18 10:46:09 localhost vdr: [4368] XVDR: Bitrate: 40000 bps
Dec 18 10:46:09 localhost vdr: [4368] XVDR: --------------------------------------
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:09 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:10 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:10 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:10 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:10 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:11 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:11 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:11 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:11 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:12 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:12 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:12 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:12 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:12 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:13 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:13 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:13 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:13 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:13 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:14 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:14 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:14 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:14 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:15 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:15 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:15 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:15 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:16 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:16 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:16 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:16 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:16 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:17 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:17 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:17 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:17 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:17 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:18 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:18 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:18 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:18 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:19 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:19 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:19 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:19 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:20 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:20 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:20 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:20 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:21 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:21 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:21 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:21 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:22 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:22 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:22 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:22 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:22 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:23 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:23 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:23 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:23 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:24 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:24 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:24 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:24 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:24 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:25 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:25 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:25 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:25 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:26 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:26 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:26 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:26 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:26 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:27 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:27 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:27 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:27 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:27 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:28 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:28 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:28 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:28 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:29 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:29 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:29 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:29 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:30 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:30 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:30 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:30 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:31 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:31 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:31 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:31 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:32 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:32 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:32 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:32 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:32 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:33 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:33 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:33 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:33 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:33 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:34 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:34 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:34 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:34 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:34 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:35 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:35 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:35 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:35 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:35 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:36 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:36 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:36 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:36 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:37 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:37 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:37 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:37 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:37 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:38 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:38 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:38 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:38 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:38 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:39 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:39 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:39 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:40 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:40 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:40 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:40 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:41 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:41 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:41 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:41 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:42 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:42 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:42 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:42 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:42 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:43 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:43 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:43 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:43 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:44 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:44 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:44 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:44 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:44 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:45 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:45 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:45 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:45 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:46:45 localhost vdr: [4368] XVDR: sync found at offset 1991
Dec 18 10:47:01 localhost vdr: [4369] buffer usage: 70% (tid=4364)
Dec 18 10:47:09 localhost vdr: [4369] buffer usage: 80% (tid=4364)
Dec 18 10:47:13 localhost vdr: [4368] XVDR: timeout. signal lost!
Dec 18 10:47:17 localhost vdr: [4369] buffer usage: 90% (tid=4364)
Dec 18 10:47:22 localhost vdr: [4369] buffer usage: 100% (tid=4364)
Dec 18 10:47:22 localhost vdr: [4369] ERROR: 1 ring buffer overflow (9 bytes dropped)
Dec 18 10:47:28 localhost vdr: [4369] ERROR: 16492 ring buffer overflows (3100496 bytes dropped)
Dec 18 10:47:34 localhost vdr: [4369] ERROR: 18512 ring buffer overflows (3480256 bytes dropped)
Dec 18 10:47:40 localhost vdr: [4369] ERROR: 19855 ring buffer overflows (3732740 bytes dropped)

Also after stopping and starting playback again the plugin crashed vdr:

Dec 18 10:52:45 localhost vdr: [4452] XVDR: streaming of channel started
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Channels: 1
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Samplerate: 48000 Hz
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Bitrate: 160000 bps
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Channels: 1
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Samplerate: 48000 Hz
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Bitrate: 160000 bps
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Channels: 6
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Samplerate: 48000 Hz
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Bitrate: 384000 bps
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:45 localhost vdr: [4452] XVDR: NEW AUDIO INFORMATION:
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Channels: 6
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Samplerate: 48000 Hz
Dec 18 10:52:45 localhost vdr: [4452] XVDR: Bitrate: 384000 bps
Dec 18 10:52:45 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:46 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:46 localhost vdr: [4452] XVDR: NEW PICTURE INFORMATION:
Dec 18 10:52:46 localhost vdr: [4452] XVDR: Picture Width: 1920
Dec 18 10:52:46 localhost vdr: [4452] XVDR: Picture Height: 1080
Dec 18 10:52:46 localhost vdr: [4452] XVDR: Display Aspect Ratio: 1.78
Dec 18 10:52:46 localhost vdr: [4452] XVDR: --------------------------------------
Dec 18 10:52:47 localhost vdr: [4257] EEPG: PMT scan idle
Dec 18 10:52:47 localhost vdr: [4257] EEPG: Ended all processing
Dec 18 10:52:57 localhost vdr: [4260] XVDR: Checking for channel updates ...
Dec 18 10:52:57 localhost vdr: [4260] XVDR: Client 0: 970 channels, no change
Dec 18 10:52:57 localhost vdr: [4260] XVDR: Done.
Dec 18 10:53:25 localhost vdr: [4452] XVDR: sync found at offset 51627
Dec 18 10:53:27 localhost runvdr: restarting VDR
Dec 18 10:53:30 localhost vdr: [4479] VDR version 1.7.31 started
...

And finally there is the situation when a " Signal lost" message was produced:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: streaming of channel started
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: NEW AUDIO INFORMATION:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Channels: 6
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Samplerate: 48000 Hz
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Bitrate: 384000 bps
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: NEW AUDIO INFORMATION:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Channels: 6
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Samplerate: 48000 Hz
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Bitrate: 384000 bps
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: NEW AUDIO INFORMATION:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Channels: 1
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Samplerate: 48000 Hz
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Bitrate: 160000 bps
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: NEW AUDIO INFORMATION:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Channels: 1
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Samplerate: 48000 Hz
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Bitrate: 160000 bps
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:07 localhost vdr: [4576] XVDR: NEW PICTURE INFORMATION:
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Picture Width: 1920
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Picture Height: 1080
Dec 18 11:00:07 localhost vdr: [4576] XVDR: Display Aspect Ratio: 1.78
Dec 18 11:00:07 localhost vdr: [4576] XVDR: --------------------------------------
Dec 18 11:00:09 localhost vdr: [4485] EEPG: PMT scan idle
Dec 18 11:00:09 localhost vdr: [4485] EEPG: Ended all processing
Dec 18 11:00:19 localhost vdr: [4488] XVDR: Checking for channel updates ...
Dec 18 11:00:19 localhost vdr: [4488] XVDR: Client 0: 970 channels, no change
Dec 18 11:00:19 localhost vdr: [4488] XVDR: Done.
Dec 18 11:00:34 localhost vdr: [4576] XVDR: timeout. signal lost!
Dec 18 11:01:12 localhost vdr: [4577] buffer usage: 70% (tid=4573)
Dec 18 11:01:12 localhost vdr: [4577] buffer usage: 60% (tid=4573)
Dec 18 11:01:12 localhost vdr: [4577] buffer usage: 70% (tid=4573)
Dec 18 11:01:17 localhost vdr: [4577] buffer usage: 80% (tid=4573)
Dec 18 11:01:24 localhost vdr: [4577] buffer usage: 90% (tid=4573)
Dec 18 11:01:31 localhost vdr: [4577] buffer usage: 100% (tid=4573)
Dec 18 11:01:31 localhost vdr: [4577] ERROR: 1 ring buffer overflow (9 bytes dropped)
Dec 18 11:01:37 localhost vdr: [4577] ERROR: 20303 ring buffer overflows (3816964 bytes dropped)
Dec 18 11:01:43 localhost vdr: [4577] ERROR: 15006 ring buffer overflows (2821128 bytes dropped)
Dec 18 11:01:49 localhost vdr: [4577] ERROR: 16257 ring buffer overflows (3056316 bytes dropped)
Dec 18 11:01:55 localhost vdr: [4577] ERROR: 19005 ring buffer overflows (3572940 bytes dropped)
Dec 18 11:02:01 localhost vdr: [4577] ERROR: 14302 ring buffer overflows (2688776 bytes dropped)
Dec 18 11:02:07 localhost vdr: [4577] ERROR: 12462 ring buffer overflows (2342856 bytes dropped)
Dec 18 11:02:13 localhost vdr: [4577] ERROR: 10244 ring buffer overflows (1925872 bytes dropped)
Dec 18 11:02:19 localhost vdr: [4577] ERROR: 10670 ring buffer overflows (2005960 bytes dropped)

Alexander Pipelka
Owner

Ok.

I able to reproduce the buffer overflows. Happens after a couple of hours.

More interesting for me is currently the

XVDR: sync found at offset 1991

behaviour.

Please pull the demuxer branch and try to reproduce this error.
The log should give a bit more details.

Thanks
Alex

n37

Here is one example:

Dec 19 10:14:18 localhost vdr: [1875] XVDR: sync found at offset 896 (streamtype: 1 / 4216 bytes in buffer)
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:18 localhost vdr: [1875] XVDR: NEW AUDIO INFORMATION:
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Channels: 6
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Samplerate: 48000 Hz
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Bitrate: 384000 bps
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:18 localhost vdr: [1875] XVDR: NEW PICTURE INFORMATION:
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Picture Width: 1920
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Picture Height: 1080
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Display Aspect Ratio: 1.78
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:18 localhost vdr: [1875] XVDR: streaming of channel started
Dec 19 10:14:18 localhost vdr: [1875] XVDR: sync found at offset 336 (streamtype: 0 / 2192 bytes in buffer)
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:18 localhost vdr: [1875] XVDR: NEW AUDIO INFORMATION:
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Channels: 2
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Samplerate: 48000 Hz
Dec 19 10:14:18 localhost vdr: [1875] XVDR: Bitrate: 192000 bps
Dec 19 10:14:18 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:22 localhost vdr: [1875] XVDR: sync found at offset 2984 (streamtype: 0 / 3144 bytes in buffer)
Dec 19 10:14:22 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:22 localhost vdr: [1875] XVDR: NEW AUDIO INFORMATION:
Dec 19 10:14:22 localhost vdr: [1875] XVDR: Channels: 2
Dec 19 10:14:22 localhost vdr: [1875] XVDR: Samplerate: 22050 Hz
Dec 19 10:14:22 localhost vdr: [1875] XVDR: Bitrate: 56000 bps
Dec 19 10:14:22 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:23 localhost vdr: [1875] XVDR: sync found at offset 22908 (streamtype: 0 / 22979 bytes in buffer)
Dec 19 10:14:23 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:23 localhost vdr: [1875] XVDR: NEW AUDIO INFORMATION:
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Channels: 2
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Samplerate: 44100 Hz
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Bitrate: 192000 bps
Dec 19 10:14:23 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:23 localhost vdr: [1875] XVDR: sync found at offset 2229 (streamtype: 0 / 2389 bytes in buffer)
Dec 19 10:14:23 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:23 localhost vdr: [1875] XVDR: NEW AUDIO INFORMATION:
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Channels: 2
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Samplerate: 48000 Hz
Dec 19 10:14:23 localhost vdr: [1875] XVDR: Bitrate: 192000 bps
Dec 19 10:14:23 localhost vdr: [1875] XVDR: --------------------------------------
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 70% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 60% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 70% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 60% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 70% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 60% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 70% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 60% (tid=1872)
Dec 19 10:14:23 localhost vdr: [1876] buffer usage: 70% (tid=1872)
Dec 19 10:14:24 localhost vdr: [1876] buffer usage: 80% (tid=1872)
Dec 19 10:14:24 localhost vdr: [1876] buffer usage: 90% (tid=1872)
Dec 19 10:14:25 localhost vdr: [1876] buffer usage: 100% (tid=1872)
Dec 19 10:14:25 localhost vdr: [1876] ERROR: 1 ring buffer overflow (89 bytes dropped)
Dec 19 10:14:26 localhost vdr: [1875] XVDR: sync found at offset 40 (streamtype: 0 / 2128 bytes in buffer)
Dec 19 10:14:26 localhost vdr: [1875] XVDR: sync found at offset 992 (streamtype: 0 / 2064 bytes in buffer)
Dec 19 10:14:26 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2112 bytes in buffer)
Dec 19 10:14:26 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2064 bytes in buffer)
Dec 19 10:14:26 localhost vdr: [1875] XVDR: sync found at offset 72 (streamtype: 0 / 2160 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2064 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 48 (streamtype: 0 / 2200 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 600 (streamtype: 0 / 2128 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 48 (streamtype: 0 / 2224 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2152 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 440 (streamtype: 0 / 2104 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 600 (streamtype: 0 / 2192 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2120 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2048 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 272 (streamtype: 0 / 2184 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1449] XVDR: Checking for channel updates ...
Dec 19 10:14:27 localhost vdr: [1449] XVDR: Client 0: 970 channels, no change
Dec 19 10:14:27 localhost vdr: [1449] XVDR: Done.
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 232 (streamtype: 0 / 2056 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 600 (streamtype: 0 / 2168 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2096 bytes in buffer)
Dec 19 10:14:27 localhost vdr: [1875] XVDR: sync found at offset 416 (streamtype: 0 / 2048 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2136 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2088 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 616 (streamtype: 0 / 2224 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2136 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2088 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2176 bytes in buffer)
Dec 19 10:14:28 localhost vdr: [1875] XVDR: sync found at offset 40 (streamtype: 0 / 2128 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 600 (streamtype: 0 / 2064 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2176 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 24 (streamtype: 0 / 2128 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 40 (streamtype: 0 / 2056 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2152 bytes in buffer)
Dec 19 10:14:29 localhost vdr: [1875] XVDR: sync found at offset 208 (streamtype: 0 / 2064 bytes in buffer)

And here is another:

Dec 19 09:54:32 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:32 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Samplerate: 48000 Hz
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Bitrate: 160000 bps
Dec 19 09:54:32 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:32 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:32 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Samplerate: 48000 Hz
Dec 19 09:54:32 localhost vdr: [1590] XVDR: Bitrate: 160000 bps
Dec 19 09:54:32 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:32 localhost vdr: [1446] EEPG: PMT scan idle
Dec 19 09:54:32 localhost vdr: [1446] EEPG: Ended all processing
Dec 19 09:54:33 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:33 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:33 localhost vdr: [1590] XVDR: NEW PICTURE INFORMATION:
Dec 19 09:54:33 localhost vdr: [1590] XVDR: Picture Width: 1920
Dec 19 09:54:33 localhost vdr: [1590] XVDR: Picture Height: 1080
Dec 19 09:54:33 localhost vdr: [1590] XVDR: Display Aspect Ratio: 1.78
Dec 19 09:54:33 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:33 localhost vdr: [1590] XVDR: streaming of channel started
Dec 19 09:54:34 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:35 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:35 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:37 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:37 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:38 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:39 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:40 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:41 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:42 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:42 localhost vdr: [1449] XVDR: Checking for channel updates ...
Dec 19 09:54:42 localhost vdr: [1449] XVDR: Client 0: 970 channels, no change
Dec 19 09:54:42 localhost vdr: [1449] XVDR: Done.
Dec 19 09:54:43 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:44 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:45 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:46 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:47 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:48 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:49 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:50 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:51 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:52 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:53 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:53 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:54:54 localhost vdr: [1590] XVDR: sync found at offset 2231 (streamtype: 0 / 2360 bytes in buffer)
Dec 19 09:54:54 localhost vdr: [1590] XVDR: sync found at offset 3742 (streamtype: 0 / 3832 bytes in buffer)
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Bitrate: 96000 bps
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Samplerate: 32000 Hz
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Bitrate: 384000 bps
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: sync found at offset 4754 (streamtype: 0 / 4831 bytes in buffer)
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Bitrate: 64000 bps
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: sync found at offset 5035 (streamtype: 0 / 5164 bytes in buffer)
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:54 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Samplerate: 32000 Hz
Dec 19 09:54:54 localhost vdr: [1590] XVDR: Bitrate: 128000 bps
Dec 19 09:54:54 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 5990 (streamtype: 0 / 6160 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 1
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 44100 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 288000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 2778 (streamtype: 0 / 2802 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 17367 (streamtype: 0 / 17457 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 32000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 48000 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 48000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 1495 (streamtype: 0 / 2145 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 2847 (streamtype: 0 / 2888 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 44100 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 48000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 44100 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 32000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 3512 (streamtype: 0 / 3613 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 64000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: sync found at offset 1768 (streamtype: 0 / 2173 bytes in buffer)
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:55 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:55 localhost vdr: [1590] XVDR: Bitrate: 112000 bps
Dec 19 09:54:55 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: sync found at offset 10800 (streamtype: 0 / 10952 bytes in buffer)
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Samplerate: 16000 Hz
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Bitrate: 112000 bps
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: sync found at offset 164 (streamtype: 0 / 2079 bytes in buffer)
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Channels: 1
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Samplerate: 24000 Hz
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Bitrate: 256000 bps
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: sync found at offset 5709 (streamtype: 0 / 5811 bytes in buffer)
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Channels: 1
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Samplerate: 48000 Hz
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Bitrate: 64000 bps
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: sync found at offset 2389 (streamtype: 0 / 2478 bytes in buffer)
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Channels: 1
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Samplerate: 44100 Hz
Dec 19 09:54:56 localhost vdr: [1590] XVDR: Bitrate: 128000 bps
Dec 19 09:54:56 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:56 localhost vdr: [1590] XVDR: sync found at offset 4662 (streamtype: 0 / 4815 bytes in buffer)
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:57 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Samplerate: 16000 Hz
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Bitrate: 8000 bps
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:57 localhost vdr: [1590] XVDR: sync found at offset 25166 (streamtype: 0 / 25193 bytes in buffer)
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:57 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Samplerate: 44100 Hz
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Bitrate: 224000 bps
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:57 localhost vdr: [1590] XVDR: sync found at offset 8090 (streamtype: 0 / 8161 bytes in buffer)
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:54:57 localhost vdr: [1590] XVDR: NEW AUDIO INFORMATION:
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Channels: 2
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Samplerate: 22050 Hz
Dec 19 09:54:57 localhost vdr: [1590] XVDR: Bitrate: 96000 bps
Dec 19 09:54:57 localhost vdr: [1590] XVDR: --------------------------------------
Dec 19 09:55:04 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:06 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:06 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:07 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:08 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:09 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:10 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:11 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:12 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:13 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:14 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:15 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:16 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100
Dec 19 09:55:17 localhost vdr: [1590] XVDR: H264 work-around enabled. profile_idc = 100

Alexander Pipelka
Owner

Oh, my god.
Your streams are parser blasters ;-)

I think i have to add more sanity checks for mpeg2 audio streams.

Thanks

Alexander Pipelka
Owner

Ok. Please pull again the demuxer branch.
Hopefully, there is some improvement.

James Mika

That quick fix doesn't seem to be helping on my problematic channels, compiled the demuxer branch today.

Debug log: http://pastebin.com/STfniGeP

Video snippet: http://localhostr.com/5SDIWkoQbGHT

edit: scratch that, I recompiled your latest commit to the demuxer branch and now it is working! Thanks a lot.

n37

Situation seems to be improved. However here is the occasional glitch:
http://pastebin.com/92w6Bv9y
http://pastebin.com/QDJXrJrp

Alexander Pipelka
Owner

@n37
Hmm,...
Not really improved.
BTW, I suppose the AC3 track is playing fine MP2 audio not.
Can you please record ~30 - 60 seconds of that channel (+ channels.conf entry).

@sharepass11
Great.

Does

XVDR: H264 work-around enabled. profile_idc = XXX

appear in your log files ?

James Mika

Would that be with DEBUG=1? I compiled it without the debug option now and that message isn't showing up. But where before I would get no picture information on the channel in the syslog, xvdr properly parses it now. I see you also mention this work-around to be for 1080i streams whilst I had this problem on 720p channels (Maybe also on 1080i but I mainly tested the first 2 non-working channels in my list which happen to be 720p).

As mentioned I deleted the debug version already, but if you still need a debug log of the working demuxer branch to compare it to the non working one I can redo the whole thing. For now I copied what was happening earlier in the day and what's happening now without debug: http://pastebin.com/edUze24F

Alexander Pipelka
Owner

@sharepass11
If the message doesn't show up (doesn't matter if debug or not) everything is fine ;-)
I just want to be sure that "demuxer" solves the problem, "master" does not.
Then I need to pull the fix into "master".

James Mika

I can confirm that on my end demuxer works while master does not on the channels I couldn't get picture on. I never had the glitch problem so thats something n37 needs to work out with you.

Alexander Pipelka
Owner

@sharepass11
I merged the fix (well, I think it's the right one) into master.
Finally, please check if master works for you now.

James Mika

recompiled master, working like a charm. Consider my issue fixed. :)

n37

Hi Alex,
Did you get my e-mails? I have found other channels with mpeg2 video which have no picture. If you need more samples I'd be happy to help.

Alexander Pipelka
Owner

Hi,

Yes. I already downloaded the samples.
Thanks.

I'm currently thinking about an easy testing procedure. Currently it's quite time consuming.
I would love to have a sample db.

So, if you're able to provide more problematic streams, ...
Yes. Please.

Alexander Pipelka
Owner

@all
Could you all please test the updated "demuxer" branch if it fixes or breaks H264 parsing ?
There is a good chance that the fix in there will solve all the H264 problems.

Thanks.

Alexander Pipelka
Owner

Please also check for occurences of "H264 work-around enabled" messages in the log.

Daniel Scheller

Although the code in master worked well "for me", I got attention by the "@all" keyword and gave it a shot. Running TNT Serie HD for >20 minutes now, no issues.

One question though: When watching soccer matches (e.g. on Sky Sport HD channels), I recognized some really rare, seldom, unregularily occuring picture glitching (pixelation, decode failures) while audio plays fine (no dropout), which at first seemed like a reception problem. However, I can rule that out with much certainty due to audio playing fine (reception problems usually also cause audio defects) and "femon" showing no errors at all. Maybe your latest fixes also fixes this? ;)

Best regards,
Daniel

n37

H264 is OK. No more "workaround enabled" messages. A number of mpeg2 video channels have no picture. The last sample I sent you was of such a channel. If you need more, I got plenty. :) But the problem with them is probably the same.

Alexander Pipelka
Owner

@n37 Thank you, for testing.
I'll take a look at the samples after christmas-madness ;-)

Alexander Pipelka
Owner

@n37
I just ran my demux tester over the stream. Works withour any problems for me.
Please test the current "demuxer" branch.

Alexander Pipelka
Owner

@herrnst
Hi Daniel,
Please checkout the new "streaminfo" branch (of the plugin). There is no need to update the xbmc addon.
Maybe there are some visible improvements (or even not).

Alexander Pipelka
Owner

@n37 This only applies to the audio problem, "sample2" is coming up next.

Daniel Scheller

@pipelka
Hi Alex,

some quick-observations before bed-time (longer testing happening tomorrow throughout the day ;) :

What worked before still works, no changes. Codec-Info overlay is missing device state, signal quality/snr and probably BER/UNC values (they show "Unknown (incompatible device)", and 0% quality values - BER/UNC are usually 0 anyway), but that probably needs a streaminfo-binary of the XBMC addon supporting protocol 5 to work again.

Don't know if this fits here, but: I've found two channels (WDR Dortmund/Essen, "regionale Dritte") that somehow don't work since the demuxer merge anymore (black video, heavy audio stutter, lots of resyncing in XBMC up to permanent 100%-usage of one CPU core - smells like a locking problem - and making XBMC lock up entirely if keeping the channel active for long enough). This happens with streaminfo HEAD ( https://github.com/pipelka/vdr-plugin-xvdr/tree/590f32e4bd1cb40768f80a14b8f1f1e053c194de ) and master as of Dec 19 ( https://github.com/pipelka/vdr-plugin-xvdr/tree/bf9c86ffc025e39932bfa8bdf98ec56e3db25414 ).

Both channels are MPEG2 video and MP2 audio:

WDR Dortmund;ARD:129000:C0M256:C:6900:533=2:534=deu@3,535=mis@3:538:0:35101:9999:351:0
WDR Essen;ARD:129000:C0M256:C:6900:543=2:544=deu@3,545=mis@3:548:0:35102:9999:351:0

Syslog/VDR output with streaminfo branch: http://pastebin.com/5SL6X7h9
Output of "demux 127.0.0.1 3" at http://pastebin.com/3NrKARFm

If this is unrelated, I of course can open a new issue.

Regards,
Daniel

Alexander Pipelka
Owner

@herrnst Thanks for testing this.
Hopefully you will notice faster switching times (when the cache populates over time) with the "streaminfo" branch.

I disabled the VDR GetSignalQuality() / GetSignalStrength() functions because they are causing LiveTV freezes on my system. I think this is some major bug (feature?) within VDR (but I have to investigate, ...).

About the lock up problem: Does this also happen with the "demuxer" branch ?
The "demuxer" branch isn't fully merged into "master" and "streaminfo" yet.

Alexander Pipelka
Owner

@n37 I just tested "sample2".
It plays without any problems. There must be some other reason why this isn't working for your.

Again, please repeat your tests with the current "demuxer" branch.

Thanks.

Daniel Scheller

@pipelka
Nope, behaviour remains the same with demuxer HEAD (ec606b8). Otherwise, everything else working like a charm.

Alexander Pipelka
Owner

@herrnst
Ok.
Same procedure as every time: Please record a couple of seconds of this channel.
Thanks.

Daniel Scheller

https://dl.dropbox.com/s/6tqlbevb5dp80a9/wdr-do.tar.bz2?dl=1

Also added the output of the demux command while recording. Please note the recording plays perfectly via the XBMC recordings GUI.

Alexander Pipelka
Owner

@herrnst Thank you.
These streams are special as there is more than 1 frame per PES packet.
Please recheck with the updated "demuxer".

I only accept positive feedback ;-)

Daniel Scheller

@pipelka
Nice work ;) Channels play, no more lockups, all other channels still work perfectly! One little caveat, though:

Those two "bastards" start out playing fluently (getting deinterlaced properly and displayed perfectly at 50 FPS). Though after a few seconds, I notice the picture being played jerky. When looking at the codec info overlay, I see an output framerate of about 46-48FPS (instead of 50) and the error value heavily flipping between 0% and 100% (CPU usage at normal levels, video decoded via VDPAU). xbmc.log fills with this:

12:24:13 T:140031020721920   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: 0.030000, Bufferlevel: 4
12:24:13 T:140031020721920   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: 0.030000, Bufferlevel: 3
12:24:13 T:140031020721920   DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: 0.010000, Bufferlevel: 4

Have to add that I use a build patched with @FernetMenta 's VDPAU/XVBA patches (see https://github.com/herrnst/xbmc/tree/removecorelcd-and-fernetmentapatches for reference). I'm currently not sure if this is caused by the TS packet structure ("demux" shows one large video chunk, then many small ones for other streams, while on other channels it seems more evenly distributed) and something caused by the patchset, as the judder doesn't happen on a win32 XBMC build installed from mirrors.xbmc.org (error flipping between 0/20/40 percent, but that's because the display runs at 60Hz).

Even if this might be out of XVDR's scope, I believe OpenELEC users might be affected, as OpenELEC uses this patchset by default.

Anyways, thanks for all your efforts ;)

Daniel Scheller

Hmmm. Doublechecked using the "rivaling" ;) VDR interface (vnsi - everything at latest HEAD), the judder problem does not occur with that.

Alexander Pipelka
Owner

I expected something like that ;-)
I took the easy route and hoped to get away with it.

Maybe for the last time, please checkout the updated (and rebased) "demuxer" branch.

PS: vnsi and xvdr are just tracking different targets, no rivals at all.

Daniel Scheller

Nope, not quite there yet it seems ;P

Unfortunately, there's currently "progressive-as-interlaced"-content playing, but "error" still jumps around like crazy, and it's 40-44 FPS instead of 50. Built from commit 5a424f7.

Note: The "rivaling" wasn't meant really serious ;)

Alexander Pipelka
Owner

Aaargh, ...
I'm fully aware of the problem now.
I hate these streams.

Just 1 more easy-route try.
Updated "demuxer", ...

Daniel Scheller

That change gives 2 FPS and some crazy a/v sync output, but a constant error of 38% ;)

See https://www.dropbox.com/s/pcwqme1ik5xh5an/VIDEO0015.mp4 (cellphone capture) of the action. Everything else still working without problems.

(Really wondering why all channels simply don't behave or get streamed to the DVB network the same way...)

Alexander Pipelka
Owner

Ok. I have to fully implement that.
The xvdr "legacy" version has a codepath handling these streams, I always considered this as dead code and removed it a very long time ago. That's hitting me now.

Rainer Hochecker

I was mentioned in this thread, so here's my comment :)
I have just re-written all parsers to be compliant to the spec:

video start codes may occur anywhere within the payload of a PES packet, and start codes may be split by a
PES packet header

Alexander Pipelka
Owner

@FernetMenta
No. Video packets must always be aligned with PES packet borders.

The problem here is that a single PES packet contains a sequence of "IBBPBBPBBPBB" frames.
I just have to consider this.

Thanks.

Rainer Hochecker

No. Video packets must always be aligned with PES packet borders.

hmm, I posted an excerpt from the spec. Can you point me to your source?

Alexander Pipelka
Owner

@herrnst
Once more, please.
But this time a true positive feedback, please.
The branch is currently a complete mess. I will clean it up later. Next year ;-)

Happy new year. Over and out.

PS: Yes. I know. New years eve is tomorrow. But I won't touch any keyboard until 2013.

Daniel Scheller

@pipelka

Wow. Really impressed and amazed about all the effort you put in this... Regarding the issue - it ain't no more ;) Problem appears completely fixed! (Just quickly zapped through channels, though)

I REALLY hope other users will also benefit from this! Of course, really time for a break now! ;)

[english off]
Wünsche (allen hier beteiligten) einen guten Rutsch und guten, gesunden Start fürs Jahr 2013! ;)

n37
n37 commented

Hi,

The mpeg2 video issue is fixed but after a "signal lost/signal restored" situation with h264 video I often get terrible a/v desyncs and stuttering playback.

Alexander Pipelka
Owner

Great.
But please describe exactly what a "signal lost/signal restored" situation is.

I the signal really lost, or does the picture simply freeze and after a couple of seconds you get signal lost / restored messages ?

n37
n37 commented

The picture simply freezes and after a couple of seconds I get signal lost / restored messages. Often after such a situation a/v is out of sync.

Alexander Pipelka
Owner

This happens with the current "demuxer" or "master" ?
It should be fixed by 821698c592b43ac8581ea84c7e62b6dc560332f0 on "demuxer" and d398271 on "master".

n37
n37 commented

With both.

Alexander Pipelka
Owner

Sh*t.
I just found a buffer overflow in the H264 parser. Maybe that causes your problem.
The fix is in both branches now.
Please recheck if this finally fixes it.

Alexander Pipelka
Owner

BTW, please also check if VDR restarts when this is happening.

n37
n37 commented

Unfortunately this does not fix it for me. This is with the latest master.

http://pastebin.com/CVfWAjsD

Alexander Pipelka
Owner

Hmm, ...
Looks like a problem with the AC3 stream.
Can you please prepare a sample stream for me ?

n37
n37 commented

Yesterday's commits have fixed it so I am closing this finally. :)

n37 n37 closed this
Alexander Pipelka
Owner

Can't believe this.
I really got used to this thread ;-)

But please also check the next commits (today maybe tomorrow). There will be a couple of sensitive stream information caching changes.

Thanks for your help on fixing this.

n37

Thank you for all the work you put into this project.

Daniel Scheller

Signed! Thanks for all your efforts!

James Mika

I guess I'm late to the party, here is me hoping pipelka is still checking this. I just noticed that on some of my channels I've got the 2 fps problem herrnst described earlier, I don't use them very much so thats why I didn't notice earlier.

After I stumbled upon the first problematic channel I went to search for more and thats the result:

2 fps ones: https://docs.google.com/file/d/0B237cLvJw0HySF9SWGhtdTlrbXM/edit

And while I was checking I found 2 special cases, these play at 50 fps and fluctuate every now and then. If I check the xbmc overlay I can see that the "drop" value (which stands for dropped frames I guess) is constantly growing, every time it adds a few dropped frames the "pc" value is switching from 1 to 0 and back, error rate is fluctuating from 0-800%. Both of these channels are on a different mux so I would rule out bad reception since other channels on the same mux work fine?

https://docs.google.com/file/d/0B237cLvJw0HyTno4eXZLQ1RwVVU/edit

All of this is tested with "demuxer" and "master-pre-vdr-1.7.34". If there is a need for logs instead of the recording snippets I'll recompile the xvdr plugin with the debug option again.

Alexander Pipelka
Owner

Hehe, ...
I really love these MPEG2 streams.

This one sends DTS/PTS information only for I-Frames.

Ok. Please fetch "master" or "master-pre-vdr-1.7.34".
Should be fixed now.

James Mika

Sorry couldn't test earlier, had a recording scheduled that ran until now. No good news sadly, now I got the 2 fps problem on every single channel even h264 720p/1080i ones. Going back to previous version makes my usual channels work again.

Non debug log, It's late already going to recompile with debug option tomorrow if needed.

syslog http://pastebin.com/aK08uHD0
xbmc http://pastebin.com/Em1xJtph

Alexander Pipelka
Owner

Thanks. The fix was crap. Please repull.

James Mika

Thanks a lot, I can confirm that the 2 fps problem is solved now. :)

pc switching from 1 to 0 and back with the dropped frames still exists (also on the channels with the initial 2 fps problem) but I'm not 100% sure if that is a problem with xvdr or the xbmc player. I'll check against vnsi and maybe need to ask in the xbmc forums to confirm.

Thanks again for all the work you put in for our tv watching pleasure.

edit: Same problem with vnsi on said channels, so I'd say it's a problem with the xbmc player.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Something went wrong with that request. Please try again.