pipelka / vdr-plugin-xvdr

DISCONTINUED - XVDR Plugin for VDR
GNU General Public License v2.0
43 stars 22 forks source link

No video with some channels #41

Closed n37 closed 11 years ago

n37 commented 12 years ago

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 commented 12 years ago

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 commented 12 years ago

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.

pipelka commented 12 years ago

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 ?

drdaz commented 12 years ago

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'?

drdaz commented 12 years ago

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

n37 commented 12 years ago

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.

pipelka commented 12 years ago

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

I bit more patience please. ;-)

drdaz commented 12 years ago

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 :-)

sharepass11 commented 11 years ago

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

sharepass11 commented 11 years ago

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 commented 11 years ago

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 commented 11 years ago

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

pipelka commented 11 years ago

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 commented 11 years ago

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)

pipelka commented 11 years ago

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 commented 11 years ago

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

pipelka commented 11 years ago

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

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

Thanks

pipelka commented 11 years ago

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

sharepass11 commented 11 years ago

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 commented 11 years ago

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

pipelka commented 11 years ago

@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 ?

sharepass11 commented 11 years ago

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

pipelka commented 11 years ago

@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".

sharepass11 commented 11 years ago

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.

pipelka commented 11 years ago

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

sharepass11 commented 11 years ago

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

n37 commented 11 years ago

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.

pipelka commented 11 years ago

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.

pipelka commented 11 years ago

@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.

pipelka commented 11 years ago

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

herrnst commented 11 years ago

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 commented 11 years ago

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.

pipelka commented 11 years ago

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

pipelka commented 11 years ago

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

pipelka commented 11 years ago

@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).

pipelka commented 11 years ago

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

herrnst commented 11 years ago

@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

pipelka commented 11 years ago

@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.

pipelka commented 11 years ago

@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.

herrnst commented 11 years ago

@pipelka Nope, behaviour remains the same with demuxer HEAD (https://github.com/pipelka/vdr-plugin-xvdr/commit/ec606b8bc1d9c270ef58ddbdea1997f22344d3c5). Otherwise, everything else working like a charm.

pipelka commented 11 years ago

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

herrnst commented 11 years ago

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.

pipelka commented 11 years ago

@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 ;-)

herrnst commented 11 years ago

@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 ;)

herrnst commented 11 years ago

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

pipelka commented 11 years ago

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.

herrnst commented 11 years ago

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 ;)

pipelka commented 11 years ago

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

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

herrnst commented 11 years ago

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...)

pipelka commented 11 years ago

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.