Play Recording not possible - different audio streams (1 Viewer)

[spenca]

Hi all!

I had always, since I run Mediaportal, problems with some, records of episodes when I want to play them with MP. Recording works, but playback sucks! MP locks completely and I have to kill it over Task Manager. If I play the file with Windows Mediaplayer it works! It often happens with episodes from an Austrian Station ORF1.

So I searched yesterday again for some reason. What I found yesterday was some miracle in Mediainfo. It says me that there are 3 audio streams. Two MPEG and one AC3 stream. The first MPEG audio stream is about 70 Mbytes. The second MPEG and the AC3 audio are only some kbytes big, so how could this be?

I want to demultiplex the file today or tomorrow evening, so that I can confirm that is really that small. But in the meantime, someone can explain this behaviour? Strange isn't it? Could this be the cause of the MP hangs? Why the recording sucks?

Thanks a million!!

Mario

EDIT: I collected logs for the hang, but there's nothing in debug level, MP just stops without logs! What comes in my mind just now, is it senseful to collect some logs when recording?


MediaPortal Version: 1.1.2
MediaPortal Skin: StreamedMP
Windows Version: Windows 7
CPU Type:
HDD:
Memory: 4G
Motherboard:
Video Card: Nvidia 460
Video Card Driver: Newest Nvidia
Sound Card:
Sound Card AC3:
Sound Card Driver:
1. TV Card: Digital Everywhere DTV
1. TV Card Type:
1. TV Card Driver:
2. TV Card:
2. TV Card Type:
2. TV Card Driver:
3. TV Card:
3. TV Card Type:
3. TV Card Driver:
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
MPEG2 Video Codec: MS
MPEG2 Audio Codec: ffdshow AC3/DTS passthrough
h.264 Video Codec:
Satelite/CableTV Provider:
HTPC Case:
Cooling:
Power Supply:
Remote:
TV:
TV - HTPC Connection:

 

[spenca]

Hi all!

This is the oupt of Mediainfo from the sucked .ts file:

Allgemein
ID : 4 (0x4)
Vollständiger Name : D:\TVServer\Recordings\Grey's Anatomy - ORF1 - 2011-05-10.ts
Format : MPEG-TS
Dateigröße : 3,36 GiB
Dauer : 59min
Gesamte Bitrate : 8 149 Kbps

Video
ID : 48 (0x30)
Menü-ID : 137 (0x89)
Format : MPEG Video
Format-Version : Version 2
Format-Profil : Main@Main
Format-Einstellungen für BVOP : Ja
Format-Einstellungen für Matrix : üblich
Format_Settings_GOP : M=3, N=12
Codec-ID : 2
Dauer : 59min
Bitraten-Modus : variabel
Bitrate : 6 970 Kbps
nominale Bitrate : 10 000 Kbps
Breite : 720 Pixel
Höhe : 576 Pixel
Bildseitenverhältnis : 16:9
Bildwiederholungsrate : 25,000 FPS
Standard : PAL
ColorSpace : YUV
ChromaSubsampling : 4:2:0
BitDepth/String : 8 bits
Scantyp : Interlaced
Scanreihenfolge : oberes Feld zuerst
Bits/(Pixel*Frame) : 0.672
Stream-Größe : 2,87 GiB (86%)

Audio #1
ID : 64 (0x40)
Menü-ID : 137 (0x89)
Format : MPEG Audio
Format-Version : Version 1
Format-Profil : Layer 2
Format_Settings_Mode : Joint stereo
Format_Settings_ModeExtension : MS Stereo
Codec-ID : 3
Dauer : 59min
Bitraten-Modus : konstant
Bitrate : 160 Kbps
Kanäle : 2 Kanäle
Samplingrate : 48,0 KHz
Video Verzögerung : -172ms
Stream-Größe : 67,5 MiB (2%)
Sprache : Deutsch
Sprache, nähere Info : Clean effects

Audio #2
ID : 65 (0x41)
Menü-ID : 137 (0x89)
Format : MPEG Audio
Format-Version : Version 1
Format-Profil : Layer 2
Format_Settings_Mode : Joint stereo
Format_Settings_ModeExtension : MS Stereo
Codec-ID : 3
Dauer : 17s 424ms
Bitraten-Modus : konstant
Bitrate : 160 Kbps
Kanäle : 2 Kanäle
Samplingrate : 48,0 KHz
Stream-Größe : 340 KiB (0%)
Sprache : Englisch
Sprache, nähere Info : Clean effects

Audio #3
ID : 66 (0x42)
Menü-ID : 137 (0x89)
Format : AC-3
Format/Info : Audio Coding 3
Format_Settings_ModeExtension : CM (complete main)
Codec-ID : 6
Dauer : 17s 696ms
Bitraten-Modus : konstant
Bitrate : 448 Kbps
Kanäle : 2 Kanäle
Kanal-Positionen : Front: L R
Samplingrate : 48,0 KHz
BitDepth/String : 16 bits
Stream-Größe : 968 KiB (0%)
Sprache : Deutsch
Sprache, nähere Info : Clean effects

Text
ID : 99 (0x63)-100
Menü-ID : 137 (0x89)
Format : Teletext
Sprache : Deutsch


I remuxed the file using tsmuxer withou the bad streams and playback worked again! Super!

Is there anyone out who can tell me now where I start to debug the problem? I think I should start from recording?

Thank!

Mario

 

[spenca]

Hi all! It's just me again. I found a log from recording the bad file. Any ideas?

View attachment 82258

 

[spenca]

Hi Folks!

Searched in the logs for errors, but found nothing I would understand as fault.

Please can someone have a look? In the logs the important part is from time 03:20 until 03:21.

Thx!

Mario

 

[spenca]

Hi all!

Found out, that the bad episode uses Audiodualmono Mode. So there should be a German and English stream in one Stereo stream.

More or less i need some help from you, PLEASE!

Mario

 

[mm1352000]

Hi Mario

I don't really know if this will help you, but Audiodualmono is supported.

1. Open MediaPortal configuration.
2. Expand the "TV" section.
3. Select the "TV Client" node.
4. Go to the "Audio settings" tab.
5. Down at the bottom, tick "enable AudioDualMono" switching.

Does that help?

[Edit: note that you may have to set up language preferences too...]

 

[spenca]

Hi all,

I made Log Files using Watchdog, perhaps someone can help me?

Thx!

Mario

View attachment 82979

 

[mm1352000]

Hi Mario

Did you try my instructions?

According to your logs it looks like there are only two audio channels available for Orf1:

[COLLAPSE]2011-05-31 03:02:15.358000 [scheduler thread(28)]: card: Tune 4 to ORF1

2011-05-31 03:02:15.359000 [scheduler thread(28)]: card: user: scheduler265:4:-1 tune DVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.400000 [scheduler thread(28)]: dvbs: TuneVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.401000 [scheduler thread(28)]: dvbs: TuneVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.402000 [scheduler thread(28)]: dvb:GetNewSubChannel:0 #0

2011-05-31 03:02:15.409000 [scheduler thread(28)]: TvDvbChannel ctor new subchIndex:0

2011-05-31 03:02:15.410000 [scheduler thread(28)]: LNB low:9750 hi:10600 switch:11700

2011-05-31 03:02:15.411000 [scheduler thread(28)]: DigitalEverywhere DVB-S2 modulation set to:ModNotSet

2011-05-31 03:02:15.412000 [scheduler thread(28)]: DigitalEverywhere Pilot set to:NotSet

2011-05-31 03:02:15.413000 [scheduler thread(28)]: DigitalEverywhere RollOff set to:NotSet

2011-05-31 03:02:15.415000 [scheduler thread(28)]: DigitalEverywhere fec set to:6

2011-05-31 03:02:15.416000 [scheduler thread(28)]: dvbs:channel modulation is set to ModNotSet

2011-05-31 03:02:15.417000 [scheduler thread(28)]: dvbs:channel FECRate is set to Rate5_6

2011-05-31 03:02:15.418000 [scheduler thread(28)]: FireDTV: diseqc isn't used - skip it

2011-05-31 03:02:15.519000 [scheduler thread(28)]: dvbubmiting tunerequest Channel:ORF1 subChannel:0

2011-05-31 03:02:15.521000 [scheduler thread(28)]: dvb:using existing subchannel:0

2011-05-31 03:02:15.522000 [scheduler thread(28)]: dvbubmit tunerequest size:1 new:0

2011-05-31 03:02:15.523000 [scheduler thread(28)]: subch:0 OnBeforeTune

2011-05-31 03:02:15.527000 [scheduler thread(28)]: dvbubmit tunerequest calling put_TuneRequest

2011-05-31 03:02:15.528000 [scheduler thread(28)]: dvbubmit tunerequest done calling put_TuneRequest

2011-05-31 03:02:15.529000 [scheduler thread(28)]: subch:0 OnAfterTune

2011-05-31 03:02:15.531000 [scheduler thread(28)]: FireDTV: HW Filtering disabled.

2011-05-31 03:02:15.535000 [scheduler thread(28)]: subch:0 OnGraphStart

2011-05-31 03:02:15.536000 [scheduler thread(28)]: dvb: RunGraph

2011-05-31 03:02:15.855000 [scheduler thread(28)]: dvb: LockedInOnSignal ok

2011-05-31 03:02:15.858000 [scheduler thread(28)]: subch:0 OnGraphStarted

2011-05-31 03:02:15.859000 [scheduler thread(28)]: subch:0 SetupPmtGrabberid 3E9 sid:32C9

2011-05-31 03:02:15.860000 [scheduler thread(28)]: subch:0 set pmt grabber pmt:3E9 sid:32C9

2011-05-31 03:02:15.863000 [scheduler thread(28)]: WaitForPMT: Waiting for PMT 3E9

2011-05-31 03:02:15.955000 [(11)]: subch:0 OnPMTReceived() pmt:3E9 ran:True dynamic:False

2011-05-31 03:02:15.957000 [scheduler thread(28)]: WaitForPMT: Found PMT after 0,094 seconds.

2011-05-31 03:02:15.958000 [scheduler thread(28)]: subch:0 SendPmt:32C9 32C9 FFFFFFFF 1F

2011-05-31 03:02:15.960000 [scheduler thread(28)]: Decode pmt

2011-05-31 03:02:15.961000 [scheduler thread(28)]: descr1 len:6 9 4 d 5 e0 c9

2011-05-31 03:02:15.962000 [scheduler thread(28)]: descr1 len:6 9 4 d 95 e0 fb

2011-05-31 03:02:15.964000 [scheduler thread(28)]: descr1 len:6 9 4 6 48 e0 65

2011-05-31 03:02:15.965000 [scheduler thread(28)]: descr1 len:6 9 4 17 2 e1 2d

2011-05-31 03:02:15.966000 [scheduler thread(28)]: descr1 len:6 9 4 18 33 e1 91

2011-05-31 03:02:15.967000 [scheduler thread(28)]: descr1 len:6 9 4 9 c4 e1 e1

2011-05-31 03:02:15.968000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.969000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.970000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 6 9

2011-05-31 03:02:15.971000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.972000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 6 9

2011-05-31 03:02:15.974000 [scheduler thread(28)]: Set descriptor data with length 7

2011-05-31 03:02:15.975000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 3 10

2011-05-31 03:02:15.976000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.977000 [scheduler thread(28)]: subch:0 SendPMT version:31 len:127 13001

2011-05-31 03:02:15.982000 [scheduler thread(28)]: FireDTV:GetCAMStatus() finished

2011-05-31 03:02:15.983000 [scheduler thread(28)]: FireDTV:cam is inserted

2011-05-31 03:02:15.984000 [scheduler thread(28)]: FireDTV:cam is valid

2011-05-31 03:02:15.985000 [scheduler thread(28)]: FireDTV:cam is ready

2011-05-31 03:02:15.987000 [scheduler thread(28)]: FireDTV:cam is able to descramble

2011-05-31 03:02:15.991000 [scheduler thread(28)]: FireDTV cam specs : manufacturer_code=208, application_manufacturer=0

2011-05-31 03:02:15.992000 [scheduler thread(28)]: FireDTV cam name : "CryptoWorks"

2011-05-31 03:02:15.993000 [scheduler thread(28)]: FireDTV: #0/1 pmt data:0x0 0x2 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x81 0x3 0x1 0x2 0xB0 0x7F 0x32 0xC9 0xFF 0x0 0x0 0xE0 0xA0 0xF0 0x24 0x9 0x4 0xD 0x5 0xE0 0xC9 0x9 0x4 0xD 0x95 0xE0 0xFB 0x9 0x4 0x6 0x48 0xE0 0x65 0x9 0x4 0x17 0x2 0xE1 0x2D 0x9 0x4 0x18 0x33 0xE1 0x91 0x9 0x4 0x9 0xC4 0xE1 0xE1 0x2 0xE0 0xA0 0xF0 0x3 0x52 0x1 0x1 0x3 0xE0 0xA1 0xF0 0x9 0x52 0x1 0x2 0xA 0x4 0x67 0x65 0x72 0x1 0x6 0xE0 0xA3 0xF0 0xC 0x6A 0x1 0x0 0x52 0x1 0x5 0xA 0x4 0x67 0x65 0x72 0x1 0x6 0xE0 0xA5 0xF0 0xA 0x56 0x5 0x67 0x65 0x72 0x9 0x0 0x52 0x1 0x4 0x6 0xE0 0xA9 0xF0 0x13 0x52 0x1 0x6 0x45 0x6 0x4 0x1 0xF0 0x5 0x1 0xF7 0xC3 0x6 0x4 0x1 0xF0 0x5 0x1 0xF7 0xE8

2011-05-31 03:02:16.104000 [scheduler thread(28)]: FireDTV:GetCAMStatus() finished

2011-05-31 03:02:16.105000 [scheduler thread(28)]: FireDTV:cam is inserted

2011-05-31 03:02:16.106000 [scheduler thread(28)]: FireDTV:cam is valid

2011-05-31 03:02:16.107000 [scheduler thread(28)]: FireDTV:cam is ready

2011-05-31 03:02:16.109000 [scheduler thread(28)]: FireDTV:cam is able to descramble

2011-05-31 03:02:16.110000 [scheduler thread(28)]: subch:0 cam flags:True

2011-05-31 03:02:16.111000 [scheduler thread(28)]: subch:0 SetMpegPidMapping

2011-05-31 03:02:16.112000 [scheduler thread(28)]: subch:0 pid:A0 pcr

2011-05-31 03:02:16.114000 [scheduler thread(28)]: subch:0 pid:3E9 pmt

2011-05-31 03:02:16.116000 [scheduler thread(28)]: subch:0 pid:A0 video type:MPEG-2

2011-05-31 03:02:16.117000 [scheduler thread(28)]: subch:0 map pid:A0 video type:MPEG-2

2011-05-31 03:02:16.121000 [scheduler thread(28)]: subch:0 pid:A1 audio lang:ger type:MPEG-1

2011-05-31 03:02:16.123000 [scheduler thread(28)]: subch:0 map pid:A1 audio lang:ger type:MPEG-1

2011-05-31 03:02:16.125000 [scheduler thread(28)]: subch:0 pid:A3 audio lang:ger type:AC3

2011-05-31 03:02:16.126000 [scheduler thread(28)]: subch:0 map pid:A3 audio lang:ger type:AC3

2011-05-31 03:02:16.128000 [scheduler thread(28)]: subch:0 pid:A5 teletext type:6

2011-05-31 03:02:16.130000 [scheduler thread(28)]: subch:0 map pid:A5 teletext type:6

2011-05-31 03:02:16.131000 [scheduler thread(28)]: subch:0 pid:A9 type:6

2011-05-31 03:02:16.132000 [scheduler thread(28)]: FireDTV: HW Filtering disabled.

2011-05-31 03:02:16.136000 [scheduler thread(28)]: subch:0 stop tif

2011-05-31 03:02:16.137000 [scheduler thread(28)]: WaitForPmt: PMT handling took 0,179 seconds.

2011-05-31 03:02:16.173000 [scheduler thread(28)]: card: Tuner locked: True

2011-05-31 03:02:16.175000 [scheduler thread(28)]: **************************************************

2011-05-31 03:02:16.176000 [scheduler thread(28)]: ***** SIGNAL LEVEL: 82, SIGNAL QUALITY: 82 *****

2011-05-31 03:02:16.177000 [scheduler thread(28)]: **************************************************[/COLLAPSE]

If the AC3 track is the problem, try to untick "prefer AC3" and enable the audio dualmono switching option in the "audio settings" tab for the TV section in MediaPortal configuration...

mm

 

[spenca]

Hi Mario

Did you try my instructions?

According to your logs it looks like there are only two audio channels available for Orf1:

[COLLAPSE]2011-05-31 03:02:15.358000 [scheduler thread(28)]: card: Tune 4 to ORF1

2011-05-31 03:02:15.359000 [scheduler thread(28)]: card: user: scheduler265:4:-1 tune DVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.400000 [scheduler thread(28)]: dvbs: TuneVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.401000 [scheduler thread(28)]: dvbs: TuneVBS:tv:ORF ORF1 Freq:12692000 ONID:1 TSID:1117 SID:13001 PMT:0x3E9 FTA:False LCN:1120 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet

2011-05-31 03:02:15.402000 [scheduler thread(28)]: dvb:GetNewSubChannel:0 #0

2011-05-31 03:02:15.409000 [scheduler thread(28)]: TvDvbChannel ctor new subchIndex:0

2011-05-31 03:02:15.410000 [scheduler thread(28)]: LNB low:9750 hi:10600 switch:11700

2011-05-31 03:02:15.411000 [scheduler thread(28)]: DigitalEverywhere DVB-S2 modulation set to:ModNotSet

2011-05-31 03:02:15.412000 [scheduler thread(28)]: DigitalEverywhere Pilot set to:NotSet

2011-05-31 03:02:15.413000 [scheduler thread(28)]: DigitalEverywhere RollOff set to:NotSet

2011-05-31 03:02:15.415000 [scheduler thread(28)]: DigitalEverywhere fec set to:6

2011-05-31 03:02:15.416000 [scheduler thread(28)]: dvbs:channel modulation is set to ModNotSet

2011-05-31 03:02:15.417000 [scheduler thread(28)]: dvbs:channel FECRate is set to Rate5_6

2011-05-31 03:02:15.418000 [scheduler thread(28)]: FireDTV: diseqc isn't used - skip it

2011-05-31 03:02:15.519000 [scheduler thread(28)]: dvbubmiting tunerequest Channel:ORF1 subChannel:0

2011-05-31 03:02:15.521000 [scheduler thread(28)]: dvb:using existing subchannel:0

2011-05-31 03:02:15.522000 [scheduler thread(28)]: dvbubmit tunerequest size:1 new:0

2011-05-31 03:02:15.523000 [scheduler thread(28)]: subch:0 OnBeforeTune

2011-05-31 03:02:15.527000 [scheduler thread(28)]: dvbubmit tunerequest calling put_TuneRequest

2011-05-31 03:02:15.528000 [scheduler thread(28)]: dvbubmit tunerequest done calling put_TuneRequest

2011-05-31 03:02:15.529000 [scheduler thread(28)]: subch:0 OnAfterTune

2011-05-31 03:02:15.531000 [scheduler thread(28)]: FireDTV: HW Filtering disabled.

2011-05-31 03:02:15.535000 [scheduler thread(28)]: subch:0 OnGraphStart

2011-05-31 03:02:15.536000 [scheduler thread(28)]: dvb: RunGraph

2011-05-31 03:02:15.855000 [scheduler thread(28)]: dvb: LockedInOnSignal ok

2011-05-31 03:02:15.858000 [scheduler thread(28)]: subch:0 OnGraphStarted

2011-05-31 03:02:15.859000 [scheduler thread(28)]: subch:0 SetupPmtGrabberid 3E9 sid:32C9

2011-05-31 03:02:15.860000 [scheduler thread(28)]: subch:0 set pmt grabber pmt:3E9 sid:32C9

2011-05-31 03:02:15.863000 [scheduler thread(28)]: WaitForPMT: Waiting for PMT 3E9

2011-05-31 03:02:15.955000 [(11)]: subch:0 OnPMTReceived() pmt:3E9 ran:True dynamic:False

2011-05-31 03:02:15.957000 [scheduler thread(28)]: WaitForPMT: Found PMT after 0,094 seconds.

2011-05-31 03:02:15.958000 [scheduler thread(28)]: subch:0 SendPmt:32C9 32C9 FFFFFFFF 1F

2011-05-31 03:02:15.960000 [scheduler thread(28)]: Decode pmt

2011-05-31 03:02:15.961000 [scheduler thread(28)]: descr1 len:6 9 4 d 5 e0 c9

2011-05-31 03:02:15.962000 [scheduler thread(28)]: descr1 len:6 9 4 d 95 e0 fb

2011-05-31 03:02:15.964000 [scheduler thread(28)]: descr1 len:6 9 4 6 48 e0 65

2011-05-31 03:02:15.965000 [scheduler thread(28)]: descr1 len:6 9 4 17 2 e1 2d

2011-05-31 03:02:15.966000 [scheduler thread(28)]: descr1 len:6 9 4 18 33 e1 91

2011-05-31 03:02:15.967000 [scheduler thread(28)]: descr1 len:6 9 4 9 c4 e1 e1

2011-05-31 03:02:15.968000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.969000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.970000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 6 9

2011-05-31 03:02:15.971000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.972000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 6 9

2011-05-31 03:02:15.974000 [scheduler thread(28)]: Set descriptor data with length 7

2011-05-31 03:02:15.975000 [scheduler thread(28)]: PROBLEM : descriptor lengths dont match 3 10

2011-05-31 03:02:15.976000 [scheduler thread(28)]: Set descriptor data with length 3

2011-05-31 03:02:15.977000 [scheduler thread(28)]: subch:0 SendPMT version:31 len:127 13001

2011-05-31 03:02:15.982000 [scheduler thread(28)]: FireDTV:GetCAMStatus() finished

2011-05-31 03:02:15.983000 [scheduler thread(28)]: FireDTV:cam is inserted

2011-05-31 03:02:15.984000 [scheduler thread(28)]: FireDTV:cam is valid

2011-05-31 03:02:15.985000 [scheduler thread(28)]: FireDTV:cam is ready

2011-05-31 03:02:15.987000 [scheduler thread(28)]: FireDTV:cam is able to descramble

2011-05-31 03:02:15.991000 [scheduler thread(28)]: FireDTV cam specs : manufacturer_code=208, application_manufacturer=0

2011-05-31 03:02:15.992000 [scheduler thread(28)]: FireDTV cam name : "CryptoWorks"

2011-05-31 03:02:15.993000 [scheduler thread(28)]: FireDTV: #0/1 pmt data:0x0 0x2 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x81 0x3 0x1 0x2 0xB0 0x7F 0x32 0xC9 0xFF 0x0 0x0 0xE0 0xA0 0xF0 0x24 0x9 0x4 0xD 0x5 0xE0 0xC9 0x9 0x4 0xD 0x95 0xE0 0xFB 0x9 0x4 0x6 0x48 0xE0 0x65 0x9 0x4 0x17 0x2 0xE1 0x2D 0x9 0x4 0x18 0x33 0xE1 0x91 0x9 0x4 0x9 0xC4 0xE1 0xE1 0x2 0xE0 0xA0 0xF0 0x3 0x52 0x1 0x1 0x3 0xE0 0xA1 0xF0 0x9 0x52 0x1 0x2 0xA 0x4 0x67 0x65 0x72 0x1 0x6 0xE0 0xA3 0xF0 0xC 0x6A 0x1 0x0 0x52 0x1 0x5 0xA 0x4 0x67 0x65 0x72 0x1 0x6 0xE0 0xA5 0xF0 0xA 0x56 0x5 0x67 0x65 0x72 0x9 0x0 0x52 0x1 0x4 0x6 0xE0 0xA9 0xF0 0x13 0x52 0x1 0x6 0x45 0x6 0x4 0x1 0xF0 0x5 0x1 0xF7 0xC3 0x6 0x4 0x1 0xF0 0x5 0x1 0xF7 0xE8

2011-05-31 03:02:16.104000 [scheduler thread(28)]: FireDTV:GetCAMStatus() finished

2011-05-31 03:02:16.105000 [scheduler thread(28)]: FireDTV:cam is inserted

2011-05-31 03:02:16.106000 [scheduler thread(28)]: FireDTV:cam is valid

2011-05-31 03:02:16.107000 [scheduler thread(28)]: FireDTV:cam is ready

2011-05-31 03:02:16.109000 [scheduler thread(28)]: FireDTV:cam is able to descramble

2011-05-31 03:02:16.110000 [scheduler thread(28)]: subch:0 cam flags:True

2011-05-31 03:02:16.111000 [scheduler thread(28)]: subch:0 SetMpegPidMapping

2011-05-31 03:02:16.112000 [scheduler thread(28)]: subch:0 pid:A0 pcr

2011-05-31 03:02:16.114000 [scheduler thread(28)]: subch:0 pid:3E9 pmt

2011-05-31 03:02:16.116000 [scheduler thread(28)]: subch:0 pid:A0 video type:MPEG-2

2011-05-31 03:02:16.117000 [scheduler thread(28)]: subch:0 map pid:A0 video type:MPEG-2

2011-05-31 03:02:16.121000 [scheduler thread(28)]: subch:0 pid:A1 audio lang:ger type:MPEG-1

2011-05-31 03:02:16.123000 [scheduler thread(28)]: subch:0 map pid:A1 audio lang:ger type:MPEG-1

2011-05-31 03:02:16.125000 [scheduler thread(28)]: subch:0 pid:A3 audio lang:ger type:AC3

2011-05-31 03:02:16.126000 [scheduler thread(28)]: subch:0 map pid:A3 audio lang:ger type:AC3

2011-05-31 03:02:16.128000 [scheduler thread(28)]: subch:0 pid:A5 teletext type:6

2011-05-31 03:02:16.130000 [scheduler thread(28)]: subch:0 map pid:A5 teletext type:6

2011-05-31 03:02:16.131000 [scheduler thread(28)]: subch:0 pid:A9 type:6

2011-05-31 03:02:16.132000 [scheduler thread(28)]: FireDTV: HW Filtering disabled.

2011-05-31 03:02:16.136000 [scheduler thread(28)]: subch:0 stop tif

2011-05-31 03:02:16.137000 [scheduler thread(28)]: WaitForPmt: PMT handling took 0,179 seconds.

2011-05-31 03:02:16.173000 [scheduler thread(28)]: card: Tuner locked: True

2011-05-31 03:02:16.175000 [scheduler thread(28)]: **************************************************

2011-05-31 03:02:16.176000 [scheduler thread(28)]: ***** SIGNAL LEVEL: 82, SIGNAL QUALITY: 82 *****

2011-05-31 03:02:16.177000 [scheduler thread(28)]: **************************************************[/COLLAPSE]

If the AC3 track is the problem, try to untick "prefer AC3" and enable the audio dualmono switching option in the "audio settings" tab for the TV section in MediaPortal configuration...

mm

Hi mm!

The logs only show the faulty playback, not the recording. So there are no logs about recording in tswriter or elsewhere.

Please have a look for playing the file "D:/TVServer/Recordings/ORF1/Grey's Anatomy 2011-05-31/Grey's Anatomy.ts".

Do you need debug logs for recording, are you interested?

I tried your instructions, no success! I also tried untick "prefer AC3" and tick "audio dualmode", but also no success.

Thx! Mario

 

[mm1352000]

Hi again Mario

The logs only show the faulty playback, not the recording. So there are no logs about recording in tswriter or elsewhere.

That is not correct. TsWriter is responsible for recording and timeshifting live TV. It *always* logs the available video and audio streams when you start viewing or recording a channel. It will also detect and log any changes in the available streams (new audio stream, format change etc.) and pass that information on to TV Server. TsReader is responsible for playback. You have not provided any TsReader logs so I cannot tell why the playback is not working...

Please have a look for playing the file "D:/TVServer/Recordings/ORF1/Grey's Anatomy 2011-05-31/Grey's Anatomy.ts".

There are no playback logs, but there are TsWriter recording logs:

[COLLAPSE]31-05-2011 03:02:15.405 analyzer: reset

31-05-2011 03:02:15.406 PatParser:Reset(0)

31-05-2011 03:02:15.406 NIT:Reset

31-05-2011 03:02:15.407 PatParser::Reset done

31-05-2011 03:02:15.408 cagrabber: reset

31-05-2011 03:02:15.524 epg: reset

31-05-2011 03:02:15.524 epg:ResetEPG()

31-05-2011 03:02:15.526 mhw reset

31-05-2011 03:02:15.537 CMpTsFilter:ause()

31-05-2011 03:02:15.546 CMpTsFilter::Run()

31-05-2011 03:02:15.861 pmtgrabber: set callback:76feb8

31-05-2011 03:02:15.861 pmtgrabber: grab pmt:3e9 sid:32c9

31-05-2011 03:02:15.948 pmtgrabber: got pmt 3e9 sid:32c9

31-05-2011 03:02:15.949 pmtgrabber: PMT pids changed from:

31-05-2011 03:02:15.949 pcr pid: 0

31-05-2011 03:02:15.950 pmt pid: 0

31-05-2011 03:02:15.950 pmtgrabber: PMT pids changed to:

31-05-2011 03:02:15.951 pcr pid: a0

31-05-2011 03:02:15.951 pmt pid: 3e9

31-05-2011 03:02:15.952 video pid: a0 type: MPEG2

31-05-2011 03:02:15.952 audio pid: a1 language: ger type: MPEG1 - audio

31-05-2011 03:02:15.953 audio pid: a3 language: ger type: AC3

31-05-2011 03:02:15.953 pmtgrabber: got new pmt version:1f ffffffff, service_id:32c9

31-05-2011 03:02:15.953 pmtgrabber: do callback pid 3e9

31-05-2011 03:02:16.118 analyzer: set video pid:a0

31-05-2011 03:02:16.120 analyzer: reset

31-05-2011 03:02:16.123 analyzer: set audio pid:a1

31-05-2011 03:02:16.124 analyzer: reset

31-05-2011 03:02:16.127 analyzer: set audio pid:a3

31-05-2011 03:02:16.127 analyzer: reset

31-05-2011 03:02:16.202 Recorder: RECORD set filename:\TVServer\Recordings\ORF1\Grey's Anatomy 2011-05-31\Grey's Anatomy.ts

31-05-2011 03:02:16.206 Recorder: RECORD Received from TvService: pmt pid:0x3e9 serviceId: 0x32c9 pmtlength:127

31-05-2011 03:02:16.206 Recorder: RECORD Old pids cleared

31-05-2011 03:02:16.207 Recorder: RECORD got pmt - tableid: 0x2 section_length: 127 sid: 0x32c9

31-05-2011 03:02:16.208 Recorder: RECORD PMT parsed - Pid 0x3e9 ServiceId 0x32c9 stream count: 5

31-05-2011 03:02:16.208 Recorder: RECORD pcr pid:0xa0

31-05-2011 03:02:16.209 Recorder: RECORD SetPcrPid clear old PIDs

31-05-2011 03:02:16.209 Recorder: RECORD add video stream pid: 0xa0 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 3

31-05-2011 03:02:16.210 Recorder: RECORD add audio stream pid: 0xa1 fake pid: 0x40 stream type: 0x3 logical type: 0x3 descriptor length: 9

31-05-2011 03:02:16.210 Recorder: RECORD add audio stream pid: 0xa3 fake pid: 0x41 stream type: 0x6 logical type: 0x81 descriptor length: 12

31-05-2011 03:02:16.211 Recorder: RECORD add teletext stream pid: 0xa5 fake pid: 0x66 stream type: 0x6 logical type: 0x56 descriptor length: 10

31-05-2011 03:02:16.211 Recorder: RECORD stream rejected - pid: 0xa9 stream type: 0x6 logical type: 0xffffffff descriptor length: 19

31-05-2011 03:02:16.213 Recorder: RECORD SetVideoAudioObserver observer ok

31-05-2011 03:02:16.215 Recorder: RECORD Start 'D:\TVServer\Recordings\ORF1\Grey's Anatomy 2011-05-31\Grey's Anatomy.ts'

31-05-2011 03:02:16.354 Recorder: RECORD start of video detected

31-05-2011 03:02:16.382 Recorder: RECORD start of audio detected

31-05-2011 03:02:16.385 Recorder: RECORD clear TS packet queue

31-05-2011 03:02:16.386 Recorder: RECORD Info : Next broadcaster program clock reference rollover : 0 days 03:14:49 0

31-05-2011 03:02:16.416 Recorder: RECORD start of audio detected

31-05-2011 03:06:00.781 pmtgrabber: PMT pids changed from:

31-05-2011 03:06:00.782 pcr pid: a0

31-05-2011 03:06:00.783 pmt pid: 3e9

31-05-2011 03:06:00.784 video pid: a0 type: MPEG2

31-05-2011 03:06:00.784 audio pid: a1 language: ger type: MPEG1 - audio

31-05-2011 03:06:00.785 audio pid: a3 language: ger type: AC3

31-05-2011 03:06:00.786 pmtgrabber: PMT pids changed to:

31-05-2011 03:06:00.786 pcr pid: a0

31-05-2011 03:06:00.787 pmt pid: 3e9

31-05-2011 03:06:00.788 video pid: a0 type: MPEG2

31-05-2011 03:06:00.789 audio pid: a1 language: ger type: MPEG1 - audio

31-05-2011 03:06:00.789 audio pid: a2 language: eng type: MPEG1 - audio

31-05-2011 03:06:00.790 audio pid: a3 language: ger type: AC3

31-05-2011 03:06:00.791 pmtgrabber: got new pmt version:0 1f, service_id:32c9

31-05-2011 03:06:00.792 pmtgrabber: do callback pid 3e9

31-05-2011 03:06:00.952 analyzer: set video pid:a0

31-05-2011 03:06:00.952 analyzer: reset

31-05-2011 03:06:00.957 analyzer: set audio pid:a3

31-05-2011 03:06:00.957 analyzer: reset

31-05-2011 03:06:00.966 Recorder: RECORD Received from TvService: pmt pid:0x3e9 serviceId: 0x32c9 pmtlength:141

31-05-2011 03:06:00.967 Recorder: RECORD Old pids cleared

31-05-2011 03:06:00.967 Recorder: RECORD got pmt - tableid: 0x2 section_length: 141 sid: 0x32c9

31-05-2011 03:06:00.968 Recorder: RECORD PMT parsed - Pid 0x3e9 ServiceId 0x32c9 stream count: 6

31-05-2011 03:06:00.968 Recorder: RECORD pcr pid:0xa0

31-05-2011 03:06:00.969 Recorder: RECORD SetPcrPid clear old PIDs

31-05-2011 03:06:00.969 Recorder: RECORD determine new start pcr

31-05-2011 03:06:00.970 Recorder: RECORD add video stream pid: 0xa0 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 3

31-05-2011 03:06:00.970 Recorder: RECORD add audio stream pid: 0xa1 fake pid: 0x40 stream type: 0x3 logical type: 0x3 descriptor length: 9

31-05-2011 03:06:00.971 Recorder: RECORD add audio stream pid: 0xa2 fake pid: 0x41 stream type: 0x3 logical type: 0x3 descriptor length: 9

31-05-2011 03:06:00.971 Recorder: RECORD add audio stream pid: 0xa3 fake pid: 0x42 stream type: 0x6 logical type: 0x81 descriptor length: 12

31-05-2011 03:06:00.971 Recorder: RECORD add teletext stream pid: 0xa5 fake pid: 0x67 stream type: 0x6 logical type: 0x56 descriptor length: 10

31-05-2011 03:06:00.972 Recorder: RECORD stream rejected - pid: 0xa9 stream type: 0x6 logical type: 0xffffffff descriptor length: 19

31-05-2011 03:06:00.973 Recorder: RECORD start of video detected

31-05-2011 03:06:00.975 Recorder: RECORD start of audio detected

31-05-2011 03:06:00.977 Recorder: RECORD clear TS packet queue

31-05-2011 03:06:00.977 Recorder: RECORD Info : Next broadcaster program clock reference rollover : 0 days 03:11:05 0

31-05-2011 03:06:00.978 Recorder: RECORD start of audio detected

31-05-2011 03:06:05.87 Recorder: RECORD start of audio detected

31-05-2011 04:00:09.793 Recorder: RECORD Stop 'D:\TVServer\Recordings\ORF1\Grey's Anatomy 2011-05-31\Grey's Anatomy.ts'

31-05-2011 04:00:09.795 Recorder: RECORD Reset

31-05-2011 04:00:09.801 del m_pVideoAnalyzer

31-05-2011 04:00:09.801 analyzer: reset

31-05-2011 04:00:09.803 del m_pPmtGrabber

31-05-2011 04:00:09.804 del m_pRecorder

31-05-2011 04:00:09.805 del m_pTimeShifting

31-05-2011 04:00:09.806 del m_pTeletextGrabber

31-05-2011 04:00:09.807 del m_pCaGrabber

31-05-2011 04:00:09.808 del done...

31-05-2011 04:00:09.824 CMpTsFilter:ause()

31-05-2011 04:00:09.825 CMpTsFilter:top()[/COLLAPSE]

I have highlighted 2 things.
First: when the recording starts at 3:02, there are only two audio streams - MPEG and AC3. Both are German.
Then, at about 3:06, a third audio stream is detected. It is an English MPEG audio stream.

It seems like ORF are dynamically adding and removing audio streams. When Grey's Anatomy comes on, they add an English stream. They probably turn the English stream off when the show finishes. I can't tell from the logs whether all of those streams actually have audio information. Based on the information that you posted earlier I'm guessing that the two German streams don't contain any data after the English stream starts, but I really don't know...

I tried your instructions, no success! I also tried untick "prefer AC3" and tick "audio dualmode", but also no success.

Okay. I'm going to need you to upload a *short* sample to our FTP server. The sample should be *no larger than 100 MB*, but it must contain all three audio streams, including the change from 2->3 audio streams. Please also post logs showing the sample being recorded. You can find the FTP server details at the bottom of this page.

mm