search

pipelka / vdr-plugin-xvdr

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

Live TV audio, randomly selects wrong audio track #52

Closed Stu-e-zz closed 12 years ago

Stu-e-zz commented 12 years ago

I have also posted this issue here: https://github.com/opdenkamp/xbmc/issues/479 I'm not sure if XBMC or the VDR plugin is at fault.

With live TV viewing, audio for the visually impaired is randomly selected instead of the main audio. The DVB PMT for the TV channel gives ISO_639 language descriptor tags for both of the audio PIDS. Both are 'eng' language. The main audio PID is 0x00 'Undefined' audio type and the visually impaired commentary audio PID is 0x03 'Visual impaired commentary'.

Looking at the XVDR plugin code there is no reference to the ISO_639 language descriptor tag audio_type field. XBMC therefore does not ever see the audio_type for the audio channel and cannot make an informed decision on which audio channel to select. Unless of course you also send the PMT containing the ISO_639 language descriptor tag to XBMC.

Is the audio_type field missing from the live TV stream transmitted to XBMC?

pipelka commented 12 years ago

For XVDR it currently works like this:

If there are 2 streams weighted the same it's purely random which one is the first one.

Short: Yes the audio_type field is currently not honoured. But, it's a nice hint. Adding the audio_type to the weight of a stream should fix this and create a unique sorting order.

I'll take a look at this at the weekend.

Regards, Alex

Stu-e-zz commented 12 years ago

Great I have seen a very old copy of ISO 13818-1 and for the ISO_639_language_descriptor tag it lists only 4 values for audio_type: 0x00 Undefined 0x01 Clean effects 0x02 Hearing impaired 0x03 Visual impaired commentary

Undefined seems to be the one to carry the most weight.

Thanks

Stu-e-zz commented 12 years ago

I'm trying to implement a new function to return the language audio type but my compiler complains there is no member function getAudioType() for the ISO639LanguageDescriptor class. Please what am I doing wrong here?

int cLivePatFilter::GetLanguageAudioType(SI::PMT::Stream& stream) { SI::AudioType LangAudioType; SI::Descriptor d; for (SI::Loop::Iterator it; (d = stream.streamDescriptors.getNext(it)); ) { switch (d->getDescriptorTag()) { case SI::ISO639LanguageDescriptorTag: { SI::ISO639LanguageDescriptor ld = (SI::ISO639LanguageDescriptor *)d; LangAudioType = ld->getAudioType(); break; } default: ; } delete d; } return (int)LangAudioType; }

Thanks

pipelka commented 12 years ago

Please recheck if the "master" branch fixes your problem. These commits should add the language type handling: 7ca1d2ab1111af8473983282c14eb02c7dc468d8, c140834f3b4f1e3437203e5ecf90abf6c788d456

If it doesn't work please also check the "dynamic-pids" branch.

Thanks, Alex

Stu-e-zz commented 12 years ago

Ahh! you beat me to it. I was just about to issue my first ever pull request. I will give it a try thanks.

Whilst working on a solution I found that in cLivePatFilter::Process, the newstreams variable is always zero, hence that section of code is never executed. This causes problems with my fix. Are you aware of this?

pipelka commented 12 years ago

Well, the situation with using the static VDR PID's and scanning for PID's (with cLivePatFilter) is a bit of a mess. I tackled the problem in the "dynamic-pids" branch by removing the VDR static PID's and just use the livepatfilter to find the streams. This currently increases channel switch-times a bit but works more reliable.

I guess the "dynamic-pids" branch will work and "master" will not work (I'm currently unable to test this).

Stu-e-zz commented 12 years ago

OK both branches fail. I inserted debug output to show the weight and audio type. In the master branch the audio type for both audio channels is 17 which I suspect is garbage probably because it is not being written to due to the fact newstreams is always zero. Apr 2 21:30:20 localhost vdr: [8046] XVDR: cLiveStreamer::reorderStreams index 1 stream audio type=17 w=8701 Apr 2 21:30:20 localhost vdr: [8046] XVDR: cLiveStreamer::reorderStreams index 2 stream audio type=17 w=8702

In the dynamic-pids branch both audio types are zero. Apr 2 22:19:05 localhost vdr: [8642] XVDR: cLiveStreamer::reorderStreams index 0 stream audio type=0 w=10400 Apr 2 22:19:05 localhost vdr: [8642] XVDR: cLiveStreamer::reorderStreams index 1 stream audio type=0 w=10401

pipelka commented 12 years ago

Yes. The patch for master is rubbish. I think I'll try to fix this for dynamic-pids first. BTW, did your solution work ?

Stu-e-zz commented 12 years ago

Yes, but I did have to comment out the line testing numstreams to get it to work.

pipelka commented 12 years ago

Ok. Ok. If you have it working, please enlighten me.

pipelka commented 12 years ago

Nevermind. I think I already found it. 230f446995fa2735e542fc84deaadd9741978b2e

Stu-e-zz commented 12 years ago

Yes I did something like that to:

    SI::ISO639LanguageDescriptor::Language l;
    for (SI::Loop::Iterator it2; ld->languageLoop.getNext(l, it2); )
    {
      langAudioType = l.getAudioType();
      DEBUGLOG("cLivePatFilter::GetLangAudioType Found ISO639LanguageDescriptorTag on PID %d, audio type %d\n", stream.getPid(), (int)langAudioType);
    }

Almost everything else is similar to what I did except I never touched cParser::ParsePESHeader or cLiveStreamer::StreamChannel. Which is probably a mistake on my part.

pipelka commented 12 years ago

Ok. Please recheck if the "dynamic-pids" branch fixes the problem now.

Stu-e-zz commented 12 years ago

OK I'll test it tonight so please give me about 8hours 'ish

Stu-e-zz commented 12 years ago

You might want to rename the iterator variable for the inner loop to it2 or something

Stu-e-zz commented 12 years ago

Looks good. No problems so far. Here is a snippet from my log with some added debug:

Apr 3 22:36:36 localhost vdr: [15715] XVDR: cLiveStreamer::reorderStreams index 0 stream audio type=0 w=10400 Apr 3 22:36:36 localhost vdr: [15715] XVDR: cLiveStreamer::reorderStreams index 1 stream audio type=3 w=10101 Apr 3 22:36:36 localhost vdr: [15715] XVDR: Stream 0: Type 4 / eng Weight: 10400 Apr 3 22:36:36 localhost vdr: [15715] XVDR: Stream 1: Type 4 / eng Weight: 10101 Apr 3 22:36:36 localhost vdr: [15715] XVDR: Stream 2: Type 11 / Weight: 3 Apr 3 22:36:36 localhost vdr: [15715] XVDR: Stream 3: Type 20 / eng Weight: 2

Stu-e-zz commented 12 years ago

This is working fine for me at least. Lots of channel switching, gets the correct audio track every time. Thanks

ghost commented 12 years ago

Unfortunately this isn't working for me. I have compiled master version of vdr-plugin-xvdr and xbmc-audio-vdr but still getting random audio track instead of preffered audio language. According to XVDR log correct audio track is getting highest height, but it's not being transmitted to xbmc when it is opening the live stream.

XVDR debug log: Jun 5 13:04:03 HTPC vdr: [3510] XVDR: Client with ID 3 connected: 127.0.0.1:48162 Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Received chan=1, ser=145, op=1, edl=28 Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Welcome client 'XBMC Media Center' with protocol version '3' Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Preferred language: cze,ces / type: 0 Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Received chan=1, ser=146, op=20, edl=8 Jun 5 13:04:03 HTPC vdr: [3536] XVDR: -------------------------------------- Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Channel streaming request: 12 - Nat Geo HD Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Found available device 1 Jun 5 13:04:03 HTPC vdr: [3536] XVDR: cStreamdevPatFilter("Nat Geo HD") Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Starting live receiver Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Creating demuxers Jun 5 13:04:03 HTPC vdr: [3537] XVDR: LiveQueue started Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Starting PAT scanner Jun 5 13:04:03 HTPC vdr: [3536] XVDR: VDR active, sending stream start message Jun 5 13:04:03 HTPC vdr: [3538] cLiveStreamer stream processor thread started (pid=3499, tid=3538) Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Successfully switched to channel 12 - Nat Geo HD Jun 5 13:04:03 HTPC vdr: [3536] XVDR: Started streaming of channel Nat Geo HD (timeout 3 seconds, priority 50) Jun 5 13:04:03 HTPC vdr: [3539] receiver on device 1 thread started (pid=3499, tid=3539) Jun 5 13:04:03 HTPC vdr: [3540] TS buffer on device 1 thread started (pid=3499, tid=3540) Jun 5 13:04:03 HTPC vdr: [3506] XVDR: PMT scanner adding PID 6201 (ISO/IEC 14496-10 Video (MPEG-4 part 10/AVC, aka H.264)) Jun 5 13:04:03 HTPC vdr: [3506] XVDR: PMT scanner adding PID 6211 (ISO/IEC 13818-3 Audio) (cze) Jun 5 13:04:03 HTPC vdr: [3506] XVDR: PMT scanner adding PID 6212 (ISO/IEC 13818-3 Audio) (eng) Jun 5 13:04:03 HTPC vdr: [3506] XVDR: PMT scanner: adding PID 6213 (ISO/IEC 13818-1 Private PES data) AC3 (eng) Jun 5 13:04:03 HTPC vdr: [3506] XVDR: PMT scanner: adding PID 6214 (ISO/IEC 13818-1 Private PES data) AC3 (rus) Jun 5 13:04:03 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:04 vdr: last message repeated 12 times Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: NEW AUDIO INFORMATION: Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Channels: 2 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Samplerate: 48000 Hz Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Bitrate: 128000 bps Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: NEW AUDIO INFORMATION: Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Channels: 2 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Samplerate: 48000 Hz Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Bitrate: 128000 bps Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: NEW AUDIO INFORMATION: Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Channels: 6 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Samplerate: 48000 Hz Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Bitrate: 448000 bps Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: NEW AUDIO INFORMATION: Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Channels: 6 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Samplerate: 48000 Hz Jun 5 13:04:04 HTPC vdr: [3538] XVDR: Bitrate: 448000 bps Jun 5 13:04:04 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:04 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:04 vdr: last message repeated 12 times Jun 5 13:04:04 HTPC vdr: [3522] XVDR: Received chan=1, ser=147, op=7, edl=0 Jun 5 13:04:04 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:05 vdr: last message repeated 18 times Jun 5 13:04:05 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:05 HTPC vdr: [3538] XVDR: NEW PICTURE INFORMATION: Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Picture Width: 1920 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Picture Height: 1080 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Display Aspect Ratio: 1.78 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: -------------------------------------- Jun 5 13:04:05 HTPC vdr: [3538] XVDR: streaming of channel started Jun 5 13:04:05 HTPC vdr: [3538] XVDR: sendStreamChange Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 0 / cze Weight: 11401 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 0 / eng Weight: 1402 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 1 / rus Weight: 404 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 1 / eng Weight: 403 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 11 / Weight: 0 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: MPEG2AUDIO: 6211 (cze) Jun 5 13:04:05 HTPC vdr: [3538] XVDR: MPEG2AUDIO: 6212 (eng) Jun 5 13:04:05 HTPC vdr: [3538] XVDR: AC3: 6214 (rus) Jun 5 13:04:05 HTPC vdr: [3538] XVDR: AC3: 6213 (eng) Jun 5 13:04:05 HTPC vdr: [3538] XVDR: H264: 6201 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 0 / cze Weight: 11400 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 0 / eng Weight: 1401 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 1 / eng Weight: 403 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 1 / rus Weight: 402 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: Stream : Type 11 / Weight: 4 Jun 5 13:04:05 HTPC vdr: [3538] XVDR: sendStreamInfo Jun 5 13:04:05 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:07 vdr: last message repeated 36 times Jun 5 13:04:07 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:07 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:07 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:07 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:08 vdr: last message repeated 27 times Jun 5 13:04:08 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:08 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:08 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:08 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:09 vdr: last message repeated 26 times Jun 5 13:04:09 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:09 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:09 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:10 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:10 vdr: last message repeated 14 times Jun 5 13:04:10 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:10 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:10 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:10 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:10 vdr: last message repeated 2 times Jun 5 13:04:10 HTPC vdr: [3522] XVDR: Received chan=1, ser=148, op=7, edl=0 Jun 5 13:04:10 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:11 vdr: last message repeated 22 times Jun 5 13:04:11 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:11 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:11 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:11 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:13 vdr: last message repeated 35 times Jun 5 13:04:13 HTPC vdr: [3538] XVDR: Stream : Type 0 / cze Weight: 11400 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: Stream : Type 0 / eng Weight: 1401 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: Stream : Type 1 / rus Weight: 403 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: Stream : Type 1 / eng Weight: 402 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: Stream : Type 11 / Weight: 4 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: sendStreamInfo Jun 5 13:04:13 HTPC vdr: [3538] XVDR: sendSignalInfo Jun 5 13:04:13 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: H.264 SPS: cropping 0 0 0 2 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: H.264 SPS: PAR 1 / 1 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: H.264 SPS: -> video size 1920x1080, aspect 1:1 Jun 5 13:04:13 HTPC vdr: [3538] XVDR: no payload, size 0 Jun 5 13:04:13 vdr: last message repeated 7 times ...

XBMC debug log 13:04:03 T:3008141120 DEBUG: LIRC: Update - NEW at 383300:000000037ff07bdd 00 KEY_OK mceusb (KEY_OK) 13:04:03 T:3008141120 DEBUG: OnKey: 11 (0b) pressed, action is Select 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All TV channels/11.pvr) 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 13:04:03 T:3008141120 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 13:04:03 T:3008141120 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 13:04:03 T:3008141120 NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/11.pvr 13:04:03 T:3008141120 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 13:04:03 T:3008141120 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 13:04:03 T:3008141120 DEBUG: LinuxRendererGL: Cleaning up GL resources 13:04:03 T:2486172480 DEBUG: Thread CDVDPlayer start, auto delete: 0 13:04:03 T:2486172480 NOTICE: Creating InputStream 13:04:03 T:2486172480 DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Nat Geo HD' 13:04:03 T:2486172480 NOTICE: AddOnLog: VDR XVDR Client: Logged in at '1338894243+7200' to 'VDR-XVDR Server' Version: '0.9.5' with protocol version '3' 13:04:03 T:2486172480 INFO: AddOnLog: VDR XVDR Client: Preferred Audio Language: cze 13:04:03 T:2486172480 DEBUG: AddOnLog: VDR XVDR Client: changing to channel 12 (priority 50) 13:04:03 T:2486172480 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All TV channels/11.pvr 13:04:03 T:2486172480 NOTICE: Creating Demuxer 13:04:03 T:2486172480 DEBUG: CDVDPlayer::SetCaching - caching state 2 13:04:03 T:2486172480 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 13:04:03 T:2486172480 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 13:04:03 T:3008141120 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 13:04:03 T:3008141120 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All TV channels/11.pvr) 13:04:03 T:3008141120 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 13:04:03 T:3008141120 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 13:04:04 T:2903493440 DEBUG: AddOnLog: VDR XVDR Client: SendPing 13:04:05 T:2486172480 DEBUG: AddOnLog: VDR XVDR Client: DemuxReset 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 0:6211 with codec_id 86016 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 1:6212 with codec_id 86016 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 2:6214 with codec_id 86019 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 3:6213 with codec_id 86019 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 4:6201 with codec_id 28 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 0:6211 with codec_id 86016 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 1:6212 with codec_id 86016 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 2:6214 with codec_id 86019 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 3:6213 with codec_id 86019 13:04:05 T:2486172480 DEBUG: CDVDDemuxPVRClient::UpdateStreams(): update stream 4:6201 with codec_id 28 13:04:05 T:2486172480 NOTICE: Opening audio stream: 3 source: 256 13:04:05 T:2486172480 NOTICE: Finding audio codec for: 86019 13:04:05 T:2486172480 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 13:04:05 T:2486172480 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcore-0-i486-linux.so) 13:04:05 T:2486172480 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcore-0-i486-linux.so 13:04:05 T:2486172480 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-52-i486-linux.so) 13:04:05 T:2486172480 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so 13:04:05 T:2486172480 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 13:04:05 T:2486172480 NOTICE: Creating audio thread 13:04:05 T:2467912512 DEBUG: Thread CDVDPlayerAudio start, auto delete: 0 13:04:05 T:2467912512 NOTICE: running thread: CDVDPlayerAudio::Process() 13:04:05 T:2467912512 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(25870210156.000000, 1) 13:04:05 T:2467912512 NOTICE: Creating audio device with codec id: 86019, channels: 6, sample rate: 48000, no pass-through 13:04:05 T:2467912512 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 13:04:05 T:2467912512 INFO: CPCMRemap: Configured speaker layout: 2.0 13:04:05 T:2467912512 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 13:04:05 T:2467912512 DEBUG: CPCMRemap: Forcing side channel map to back channels 13:04:05 T:2467912512 INFO: CPCMRemap: O channel map: FL,FR 13:04:05 T:2467912512 DEBUG: CPCMRemap: Downmix normalization is disabled 13:04:05 T:2467912512 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 13:04:05 T:2467912512 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 13:04:05 T:2467912512 DEBUG: CPulseAudioDirectSound::CPulseAudioDirectSound - Requested channels changed from 6 to 2 13:04:05 T:2467912512 DEBUG: RemoveActiveDevice - Removing device 1 13:04:05 T:2467912512 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 13:04:05 T:2467912512 DEBUG: RemoveActiveDevice - Removing device 0 13:04:05 T:2467912512 ERROR: PulseAudio: Failed to connect context 13:04:05 T:2467912512 ERROR: PulseAudio: Failed to create context 13:04:05 T:2467912512 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 13:04:05 T:2467912512 DEBUG: RemoveActiveDevice - Removing device 2 13:04:05 T:2467912512 INFO: CPCMRemap: Configured speaker layout: 2.0 13:04:05 T:2467912512 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 13:04:05 T:2467912512 DEBUG: CPCMRemap: Forcing side channel map to back channels 13:04:05 T:2467912512 INFO: CPCMRemap: O channel map: FL,FR 13:04:05 T:2467912512 DEBUG: CPCMRemap: Downmix normalization is disabled 13:04:05 T:2467912512 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 13:04:05 T:2467912512 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 13:04:05 T:2467912512 DEBUG: CALSADirectSound::CALSADirectSound - Requested channels changed from 6 to 2 13:04:05 T:2467912512 DEBUG: RemoveActiveDevice - Removing device 1 13:04:05 T:2467912512 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 13:04:05 T:2467912512 DEBUG: RemoveActiveDevice - Removing device 0 13:04:05 T:2467912512 DEBUG: Initialize - using alsa device hdmi-remap 13:04:05 T:2467912512 DEBUG: CALSADirectSound::Initialize - frame count:1024, packet count:4, buffer size:4096 13:04:05 T:2467912512 DEBUG: CALSADirectSound::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough 0 - audioDevice: hdmi-remap 13:04:05 T:2467912512 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 13:04:05 T:2486172480 DEBUG: CDVDPlayer::HandleMessages - player started 1 13:04:05 T:2486172480 NOTICE: Opening video stream: 4 source: 256 13:04:05 T:2486172480 NOTICE: Creating video codec with codec id: 28 13:04:05 T:2486172480 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:no 13:04:05 T:2486172480 DEBUG: FactoryCodec - Video: - Opening 13:04:05 T:2486172480 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avfilter-1-i486-linux.so) 13:04:05 T:2486172480 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avfilter-1-i486-linux.so 13:04:05 T:2486172480 NOTICE: CDVDVideoCodecFFmpeg::Open() Creating VDPAU(1920x1080, 28) 13:04:05 T:2486172480 NOTICE: vdp_device = 0x00000001 vdp_st = 0x00000000 13:04:05 T:2486172480 NOTICE: VDPAU Decoder capabilities: 13:04:05 T:2486172480 NOTICE: name level macbs width height 13:04:05 T:2486172480 NOTICE: ------------------------------------ 13:04:05 T:2486172480 NOTICE: MPEG1 0 8192 2048 2048 13:04:05 T:2486172480 NOTICE: MPEG2_SIMPLE 3 8192 2048 2048 13:04:05 T:2486172480 NOTICE: MPEG2_MAIN 3 8192 2048 2048 13:04:05 T:2486172480 NOTICE: H264_MAIN 41 8192 2048 2048 13:04:05 T:2486172480 NOTICE: H264_HIGH 41 8192 2048 2048 13:04:05 T:2486172480 NOTICE: VC1_SIMPLE 1 8190 2048 2048 13:04:05 T:2486172480 NOTICE: VC1_MAIN 2 8190 2048 2048 13:04:05 T:2486172480 NOTICE: VC1_ADVANCED 4 8190 2048 2048 13:04:05 T:2486172480 NOTICE: MPEG4_PART2_ASP 5 8192 2048 2048 13:04:05 T:2486172480 NOTICE: ------------------------------------ 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_NOISE_REDUCTION 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_SHARPNESS 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_DEINTERLACE_TEMPORAL_SPATIAL 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_INVERSE_TELECINE 13:04:05 T:2486172480 NOTICE: Mixer feature: VDP_VIDEO_MIXER_FEATURE_HIGH_QUALITY_SCALING_L1 13:04:05 T:2486172480 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 (VDPAU acceleration) 13:04:05 T:2486172480 DEBUG: FactoryCodec - Video: ff-h264_vdpau-vdpau - Opened 13:04:05 T:2459519808 DEBUG: Thread CVideoReferenceClock start, auto delete: 0 13:04:05 T:2459519808 DEBUG: CVideoReferenceClock: Setting up GLX 13:04:05 T:2486172480 NOTICE: Creating video thread 13:04:05 T:2451127104 DEBUG: Thread CDVDPlayerVideo start, auto delete: 0 13:04:05 T:2451127104 NOTICE: running thread: video_thread 13:04:05 T:2451127104 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(25871024656.000000, 0) 13:04:05 T:2451127104 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 13:04:05 T:2451127104 NOTICE: (VDPAU) screenWidth:0 vidWidth:1920 surfaceWidth:1920 13:04:05 T:2451127104 NOTICE: (VDPAU) screenHeight:0 vidHeight:1080 surfaceHeight:1088 13:04:05 T:2451127104 NOTICE: Creating 1920x1080 pixmap 13:04:05 T:2451127104 DEBUG: Found 4 fbconfigs. 13:04:05 T:2451127104 DEBUG: Using fbconfig index 0. 13:04:05 T:2459519808 DEBUG: CVideoReferenceClock: Detected refreshrate: 50 hertz 13:04:05 T:2451127104 NOTICE: (VDPAU) Total Output Surfaces Available: 2 of a max (tmp: 2 const: 4) 13:04:05 T:2451127104 NOTICE: (VDPAU) Creating the video mixer 13:04:05 T:2451127104 NOTICE: Setting Sharpness to 0.280000 13:04:06 T:2451127104 NOTICE: fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 13:04:06 T:2451127104 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: VDPAU 13:04:06 T:2451127104 WARNING: CRenderManager::Configure - timeout waiting for previous frame 13:04:06 T:3008141120 DEBUG: Activating window ID: 12005 13:04:06 T:3008141120 DEBUG: ------ Window Deinit (MyPVR.xml) ------ 13:04:06 T:3008141120 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 13:04:06 T:3008141120 INFO: Loading skin file: VideoFullScreen.xml 13:04:06 T:3008141120 NOTICE: Using GL_TEXTURE_2D 13:04:06 T:3008141120 NOTICE: GL: Using VDPAU render method 13:04:06 T:3008141120 NOTICE: GL: NPOT texture support detected 13:04:06 T:3008141120 NOTICE: GL: Using GL_ARB_pixel_buffer_object 13:04:06 T:3008141120 DEBUG: GL: ConvolutionFilterShader: using convolution-6x6.glsl defines:

define HAS_FLOAT_TEXTURE 1

                                        #define XBMC_STRETCH 0
                                        #define USE1DTEXTURE 1

13:04:06 T:3008141120 DEBUG: GL: Vertex Shader compilation log: 13:04:06 T:3008141120 DEBUG: GL: Vertex Shader compiled successfully 13:04:06 T:3008141120 DEBUG: GL: Pixel Shader compilation log: 13:04:06 T:3008141120 DEBUG: 0(44) : warning C7506: OpenGL does not define the global type half3 0(101) : warning C7506: OpenGL does not define the global type half 13:04:06 T:3008141120 DEBUG: GL: Fragment Shader compiled successfully 13:04:06 T:2486172480 DEBUG: CDVDPlayer::HandleMessages - player started 2 13:04:06 T:2486172480 DEBUG: set caching from pvr to done. audio (1) = 9. video (1) = 6 13:04:06 T:2486172480 DEBUG: CDVDPlayer::SetCaching - caching state 0 13:04:06 T:2486172480 ERROR: Get - failed to get stream 13:04:06 T:2467912512 DEBUG: CDVDPlayerAudio:: synctype set to 1: skip/duplicate 13:04:06 T:2467912512 DEBUG: CPCMRemap:: max gain: 3.414214, enabling limiter 13:04:06 T:2467912512 DEBUG: CDVDPlayerAudio:: Discontinuity - was:25871013152.662998, should be:25870210451.771839, error:-802700.891159 13:04:06 T:3008141120 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 13:04:09 T:2451127104 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 13:04:10 T:2951404352 DEBUG: CecLogMessage - sending ping 13:04:10 T:2982284096 DEBUG: CecLogMessage - command 'PING' sent 13:04:10 T:2973891392 DEBUG: CecLogMessage - PING - command accepted 13:04:10 T:2903493440 DEBUG: AddOnLog: VDR XVDR Client: SendPing 13:04:10 T:2451127104 DEBUG: CalcFrameRate framerate was:25.000000 calculated:50.000000 13:04:10 T:2451127104 NOTICE: fps: 50.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 13:04:10 T:2451127104 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 50.00. format: VDPAU 13:04:10 T:2451127104 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) (weight: 0.000) 13:04:11 T:3008141120 NOTICE: Using GL_TEXTURE_2D 13:04:11 T:3008141120 NOTICE: GL: Using VDPAU render method 13:04:11 T:3008141120 NOTICE: GL: NPOT texture support detected 13:04:11 T:3008141120 NOTICE: GL: Using GL_ARB_pixel_buffer_object 13:04:11 T:3008141120 DEBUG: GL: ConvolutionFilterShader: using convolution-6x6.glsl defines:

define HAS_FLOAT_TEXTURE 1

                                        #define XBMC_STRETCH 0
                                        #define USE1DTEXTURE 1

13:04:11 T:3008141120 DEBUG: GL: Vertex Shader compilation log: 13:04:11 T:3008141120 DEBUG: GL: Vertex Shader compiled successfully 13:04:11 T:3008141120 DEBUG: GL: Pixel Shader compilation log: 13:04:11 T:3008141120 DEBUG: 0(44) : warning C7506: OpenGL does not define the global type half3 0(101) : warning C7506: OpenGL does not define the global type half 13:04:11 T:3008141120 DEBUG: GL: Fragment Shader compiled successfully 13:04:13 T:2451127104 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 13:04:16 T:2903493440 DEBUG: AddOnLog: VDR XVDR Client: SendPing 13:04:22 T:2903493440 DEBUG: AddOnLog: VDR XVDR Client: SendPing

ghost commented 11 years ago

anyone with an idea, why preferred audio track is not working correctly here? (logs above) cze audio 6211 is getting highest weight according to vdr log but xbmc opens eng audio 6213 instead. hmm...