Avermedia A825 dual tuner - occasionally stops one tuner when starting the other one (1 Viewer)

feizex

Portal Member
July 26, 2009
24
1
Home Country
Hi,

I have been tracking this down ever since I installed MP a couple of years ago.

The problem is that I can't use both tuners reliably. Because occasionally when one is in use and MP tries tuning the other, it will stop the one already in use.

Currently my workaround is to disable one of the tuners on this card (USB stick). Obviously that is sub-optimal.

Sometimes I can tune on both channels OK. But other times tuning one, stops the other.

I think perhaps this is an issue peculiar to this card. But then, maybe it is MP fully resetting the card when it should only reset one tuner.

I have attached one instance of failure logs with some notes. Sorry it is a bit long. I have * the point of failure.

Please help.

Thanks,
Feizex.
:D

MediaPortal Version: MediaPortal 1.2.1.0
Windows Version: Windows XP (Service Pack 3)
CPU Type: AMD Phenom II dual-core 3.1GHz
1. TV Card: Avermedia TwinStar (A825) Dual DVB-T USB
3. TV Card: WinFast DTV2000H (PCI)

Code:
Recording begins on card 3 at 19:30:04. 
The MP client card tune at 19:41:56 to card 4 stops recording on card 3. 


recording starts at 7.30pm
tswriter
29-10-2011 19:28:25.125 Recorder:Pid 247 Continuity error... 9 ( prev 7 ) - bad signal?
29-10-2011 19:30:03.00 analyzer: reset
29-10-2011 19:30:03.00 PatParser:Reset(0)
29-10-2011 19:30:03.00 NIT:Reset
29-10-2011 19:30:03.00 PatParser::Reset done
29-10-2011 19:30:03.00 cagrabber: reset
29-10-2011 19:30:03.00 epg: reset
29-10-2011 19:30:03.00 epg:ResetEPG()
29-10-2011 19:30:03.00 mhw reset
29-10-2011 19:30:03.954 CMpTsFilter::Pause()
29-10-2011 19:30:04.204 CMpTsFilter::Run()
29-10-2011 19:30:04.204 pmtgrabber: set callback:fdfa18
29-10-2011 19:30:04.204 pmtgrabber: grab pmt:401 sid:301
29-10-2011 19:30:04.282 pmtgrabber: got pmt 401 sid:301
29-10-2011 19:30:04.282 pmtgrabber: PMT pids changed from:
29-10-2011 19:30:04.282  pcr      pid:    0 
29-10-2011 19:30:04.282  pmt      pid:    0 
29-10-2011 19:30:04.282 pmtgrabber: PMT pids changed to:
29-10-2011 19:30:04.282  pcr      pid:   a1 
29-10-2011 19:30:04.282  pmt      pid:  401 
29-10-2011 19:30:04.282  video    pid:   a1 type: MPEG2
29-10-2011 19:30:04.282  audio    pid:   51 language: eng type: MPEG2 - audio
29-10-2011 19:30:04.282 pmtgrabber: got new pmt version:12 ffffffff, service_id:301
29-10-2011 19:30:04.282 pmtgrabber: do callback pid 401
29-10-2011 19:30:04.297 analyzer: set video pid:a1
29-10-2011 19:30:04.297 analyzer: reset
29-10-2011 19:30:04.297 analyzer: set audio pid:51
29-10-2011 19:30:04.297 analyzer: reset
29-10-2011 19:30:04.360 Recorder: RECORD    set filename:D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts
29-10-2011 19:30:04.360 Recorder: RECORD    Received from TvService: pmt pid:0x401 serviceId: 0x301 pmtlength:128
29-10-2011 19:30:04.360 Recorder: RECORD    Old pids cleared
29-10-2011 19:30:04.360 Recorder: RECORD    got pmt - tableid: 0x2 section_length: 128 sid: 0x301
29-10-2011 19:30:04.360 Recorder: RECORD    PMT parsed  - Pid 0x401 ServiceId 0x301 stream count: 5
29-10-2011 19:30:04.360 Recorder: RECORD    pcr pid:0xa1
29-10-2011 19:30:04.360 Recorder: RECORD    SetPcrPid clear old PIDs
29-10-2011 19:30:04.360 Recorder: RECORD    add video stream pid: 0xa1 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 16
29-10-2011 19:30:04.360 Recorder: RECORD    add audio stream pid: 0x51 fake pid: 0x40 stream type: 0x4 logical type: 0x4 descriptor length: 17
29-10-2011 19:30:04.360 Recorder: RECORD    add teletext stream pid: 0x29 fake pid: 0x8c stream type: 0x6 logical type: 0x56 descriptor length: 15
29-10-2011 19:30:04.360 Recorder: RECORD    stream rejected - pid: 0x33 stream type: 0x6 logical type: 0xffffffff descriptor length: 8
29-10-2011 19:30:04.360 Recorder: RECORD    stream rejected - pid: 0x1388 stream type: 0xb logical type: 0xb descriptor length: 23
29-10-2011 19:30:04.375 Recorder: RECORD    SetVideoAudioObserver observer ok
29-10-2011 19:30:04.407 Recorder: RECORD    Start 'D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts'
29-10-2011 19:30:04.469 Recorder: RECORD    start of audio detected
29-10-2011 19:30:04.766 Recorder: RECORD    start of video detected
29-10-2011 19:30:04.766 Recorder: RECORD    clear TS packet queue
29-10-2011 19:30:04.782 CDiskRecorder::WriteToRecording() - Reset write buffer throttle
29-10-2011 19:30:04.782 Recorder: RECORD    Info : Next broadcaster program clock reference rollover : 0 days 20:55:04 0
29-10-2011 19:33:51.688 Recorder: TIMESHIFT Stop 'D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer'

tvlog - 7.30pm recording
2011-10-29 19:20:51.954000 [PowerEventThread(8)]: TV service PowerEventThread 30
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Scheduler: Time to record SBS ONE 7:30 PM-8:30 PM Big, Bigger, Biggest
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller: find free card for channel SBS ONE
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller: find card for channel SBS ONE
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller:   got 1 tuning details for SBS ONE
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller:   channel #1 DVBT:tv:SBS SBS ONE Freq:571500 ONID:12802 TSID:768 SID:769 PMT:0x401 FTA:True LCN:3 BandWidth:7 
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller:    card:4 type:DvbT can tune to channel
2011-10-29 19:30:02.969625 [scheduler thread(26)]: Controller:    card:4 type:DvbT users: 1
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:3 type:DvbT can tune to channel
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:3 type:DvbT users: 0
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:7 type:DvbT can tune to channel
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:7 type:DvbT users: 0
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:1 type:DvbIP is disabled
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:6 type:RadioWebStream is disabled
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:8 type:Analog is disabled
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller: found 3 card(s) for channel
2011-10-29 19:30:02.985250 [scheduler thread(26)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 11 msec
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:3 type:DvbT is available
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:7 type:DvbT is available
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller:    card:4 type:DvbT is not available, tuned to different transponder
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller: found 2 free card(s)
2011-10-29 19:30:02.985250 [scheduler thread(26)]: AdvancedCardAllocation.GetFreeCardsForChannel took 13 msec
2011-10-29 19:30:02.985250 [scheduler thread(26)]: scheduler: try max 2 of 2 FREE cards for recording
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Controller: epg stop
2011-10-29 19:30:02.985250 [scheduler thread(26)]: Scheduler : record, first tune to channel
2011-10-29 19:30:02.985250 [scheduler thread(26)]: card: Tune 3 to SBS ONE
2011-10-29 19:30:02.985250 [scheduler thread(26)]: card: user: scheduler1171:3:-1 tune DVBT:tv:SBS SBS ONE Freq:571500 ONID:12802 TSID:768 SID:769 PMT:0x401 FTA:True LCN:3 BandWidth:7
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvbt: Tune:DVBT:tv:SBS SBS ONE Freq:571500 ONID:12802 TSID:768 SID:769 PMT:0x401 FTA:True LCN:3 BandWidth:7
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvb:Submiting tunerequest Channel:SBS ONE subChannel:-1 
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvb:Getting new subchannel
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvb:GetNewSubChannel:0 #0
2011-10-29 19:30:03.000875 [scheduler thread(26)]: TvDvbChannel ctor new subchIndex:0
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvb:Submit tunerequest size:1 new:0
2011-10-29 19:30:03.000875 [scheduler thread(26)]: subch:0 OnBeforeTune
2011-10-29 19:30:03.000875 [scheduler thread(26)]: dvb:Submit tunerequest calling put_TuneRequest
2011-10-29 19:30:03.938375 [scheduler thread(26)]: dvb:Submit tunerequest done calling put_TuneRequest
2011-10-29 19:30:03.938375 [scheduler thread(26)]: subch:0 OnAfterTune
2011-10-29 19:30:03.954000 [scheduler thread(26)]: RunGraph
2011-10-29 19:30:03.954000 [scheduler thread(26)]: subch:0 OnGraphStart
2011-10-29 19:30:03.954000 [scheduler thread(26)]: dvb:  RunGraph
2011-10-29 19:30:04.204000 [scheduler thread(26)]: dvb:  LockedInOnSignal ok
2011-10-29 19:30:04.204000 [scheduler thread(26)]: subch:0 OnGraphStarted
2011-10-29 19:30:04.204000 [scheduler thread(26)]: subch:0 SetupPmtGrabber:pid 401 sid:301
2011-10-29 19:30:04.204000 [scheduler thread(26)]: subch:0 set pmt grabber pmt:401 sid:301
2011-10-29 19:30:04.204000 [scheduler thread(26)]: WaitForPMT: Waiting for PMT 401
2011-10-29 19:30:04.282125 [(14)]: subch:0 OnPMTReceived() pmt:401 ran:True dynamic:False
2011-10-29 19:30:04.282125 [scheduler thread(26)]: WaitForPMT: Found PMT after 0.078125 seconds.
2011-10-29 19:30:04.282125 [scheduler thread(26)]: subch:0 SendPmt:301 301 FFFFFFFF 12
2011-10-29 19:30:04.282125 [scheduler thread(26)]: Decode pmt
2011-10-29 19:30:04.282125 [scheduler thread(26)]: Set descriptor data with length 3
2011-10-29 19:30:04.282125 [scheduler thread(26)]: Set descriptor data with length 3
2011-10-29 19:30:04.297750 [scheduler thread(26)]: PROBLEM : descriptor lengths dont match 6 9
2011-10-29 19:30:04.297750 [scheduler thread(26)]: Set descriptor data with length 3
2011-10-29 19:30:04.297750 [scheduler thread(26)]: PROBLEM : descriptor lengths dont match 7 10
2011-10-29 19:30:04.297750 [scheduler thread(26)]: Set descriptor data with length 3
2011-10-29 19:30:04.297750 [scheduler thread(26)]: Set descriptor data with length 3
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0 SendPMT: Channel is FTA, nothing to do.
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0 SetMpegPidMapping
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:A1 pcr
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:401 pmt
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:A1 video type:MPEG-2
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0    map pid:A1 video type:MPEG-2
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:51 audio lang: type:MPEG-1
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0    map pid:51 audio lang: type:MPEG-1
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:29 teletext type:6
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0    map pid:29 teletext type:6
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:33 type:6
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0  pid:1388 type:B
2011-10-29 19:30:04.297750 [scheduler thread(26)]: subch:0 stop tif
2011-10-29 19:30:04.297750 [scheduler thread(26)]: WaitForPmt: PMT handling took 0.015625 seconds.
2011-10-29 19:30:04.297750 [scheduler thread(26)]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2011-10-29 19:30:04.297750 [scheduler thread(26)]: card: Tuner locked: True
2011-10-29 19:30:04.297750 [scheduler thread(26)]: **************************************************
2011-10-29 19:30:04.297750 [scheduler thread(26)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 20 *****
2011-10-29 19:30:04.297750 [scheduler thread(26)]: **************************************************
2011-10-29 19:30:04.297750 [scheduler thread(26)]: card: tuned user: scheduler1171 subchannel: 0
2011-10-29 19:30:04.297750 [scheduler thread(26)]: user:scheduler1171 add
2011-10-29 19:30:04.297750 [scheduler thread(26)]: Scheduler: MakeFileName() using "seriesformat" (_isSerie=True)
2011-10-29 19:30:04.360250 [scheduler thread(26)]: Scheduler : record to D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts
2011-10-29 19:30:04.360250 [scheduler thread(26)]: Recorder.start add audioVideoEventHandler
2011-10-29 19:30:04.360250 [scheduler thread(26)]: card: StartRecording 3 D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts
2011-10-29 19:30:04.360250 [scheduler thread(26)]: StartRecording to D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts
2011-10-29 19:30:04.360250 [scheduler thread(26)]: subch:0 StartRecord(D:\tv\MediaPortal Capture\SBS ONE\Big, Bigger, Biggest\Big, Bigger, Biggest    2011-10-29.ts)
2011-10-29 19:30:04.360250 [scheduler thread(26)]: subch:0-0 tswriter StartRecording...
2011-10-29 19:30:04.360250 [scheduler thread(26)]: SetRecorderPids
2011-10-29 19:30:04.375875 [scheduler thread(26)]: Set video / audio observer
2011-10-29 19:30:04.407125 [scheduler thread(26)]: DVB subch:0 Started recording
2011-10-29 19:30:04.407125 [scheduler thread(26)]: card: WaitForRecordingFile - waiting _eventAudio & _eventVideo
2011-10-29 19:30:04.469625 [(14)]: PID seen - type = Audio
2011-10-29 19:30:04.469625 [(14)]: Recorder audioVideoEventHandler Audio
2011-10-29 19:30:04.766500 [(14)]: PID seen - type = Video
2011-10-29 19:30:04.766500 [(14)]: Recorder audioVideoEventHandler Video
2011-10-29 19:30:04.782125 [scheduler thread(26)]: card: WaitForRecordingFile - video and audio are seen after 0.375 seconds
2011-10-29 19:30:04.782125 [Channel state thread(29)]: Controller: DoSetChannelStates for 19 channels
2011-10-29 19:30:04.782125 [scheduler thread(26)]: Scheduler: adding new row in db for title="Big, Bigger, Biggest" of type="0"
2011-10-29 19:30:04.797750 [Channel state thread(29)]: ChannelStates.GetChannelMapping took 10 msec
2011-10-29 19:30:04.797750 [Channel state thread(29)]: ChannelStates.GetTuningChannels took 4 msec
2011-10-29 19:30:04.813375 [Channel state thread(29)]: ChannelStates.GetAllTimeshiftingAndRecordingChannels took 12 msec
2011-10-29 19:30:04.813375 [Channel state thread(29)]: ChannelStates.DoSetChannelStates took 29 msec
2011-10-29 19:30:04.813375 [scheduler thread(26)]: Scheduler: recList: count: 1 add scheduleid: 1171 card: AF9015 BDA Filter
2011-10-29 19:30:53.079000 [PowerEventThread(8)]: TV service PowerEventThread 30


TV Log - Below is when my recording stopped 19:41:56 (recording started 4 seconds late) - selected channel on remote machine - recording stopped and did not restart - WHY!!!
2011-10-29 19:35:56.594625 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 29 msec
2011-10-29 19:40:54.101000 [PowerEventThread(8)]: TV service PowerEventThread 30
2011-10-29 19:40:54.101000 [PowerEventThread(8)]: TV service PowerEventThread 30
2011-10-29 19:41:51.726000 [(21)]: CiMenuSupported called cardid 7
2011-10-29 19:41:51.726000 [(21)]: CiMenuSupported card WinFast DTV2000 H BDA DVB-T Tuner/Demod (Philips FMD1216ME) supported: False
2011-10-29 19:41:56.835375 [(21)]: Controller: TimeShiftingWouldUseCard 7 Digital 1
2011-10-29 19:41:56.835375 [(21)]: Controller: find free card for channel 7 Digital
2011-10-29 19:41:56.835375 [(21)]: Controller: find card for channel 7 Digital
2011-10-29 19:41:56.835375 [(21)]: Controller:   got 1 tuning details for 7 Digital
2011-10-29 19:41:56.835375 [(21)]: Controller:   channel #1 DVBT:tv:Seven Network 7 Digital Freq:177500 ONID:4115 TSID:1282 SID:1312 PMT:0x200 FTA:True LCN:7 BandWidth:7 
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:4 type:DvbT can tune to channel
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:4 type:DvbT users: 0
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:3 type:DvbT can tune to channel
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:3 type:DvbT users: 1
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:7 type:DvbT can tune to channel
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:7 type:DvbT users: 0
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:1 type:DvbIP is disabled
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:6 type:RadioWebStream is disabled
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:8 type:Analog is disabled
2011-10-29 19:41:56.835375 [(21)]: Controller: found 3 card(s) for channel
2011-10-29 19:41:56.835375 [(21)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 5 msec
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:4 type:DvbT is available
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:7 type:DvbT is available
2011-10-29 19:41:56.835375 [(21)]: Controller:    card:3 type:DvbT is not available, tuned to different transponder
2011-10-29 19:41:56.835375 [(21)]: Controller: found 2 free card(s)
2011-10-29 19:41:56.851000 [(21)]: AdvancedCardAllocation.GetFreeCardsForChannel took 7 msec
2011-10-29 19:41:56.851000 [(21)]: CiMenuSupported called cardid 4
2011-10-29 19:41:56.851000 [(21)]: CiMenuSupported card AF9015 BDA Filter 2 supported: False
2011-10-29 19:41:56.913500 [(21)]: Controller: StartTimeShifting 7 Digital 1
2011-10-29 19:41:56.913500 [(21)]: Controller: find free card for channel 7 Digital
2011-10-29 19:41:56.913500 [(21)]: Controller: find card for channel 7 Digital
2011-10-29 19:41:56.913500 [(21)]: Controller:   got 1 tuning details for 7 Digital
2011-10-29 19:41:56.913500 [(21)]: Controller:   channel #1 DVBT:tv:Seven Network 7 Digital Freq:177500 ONID:4115 TSID:1282 SID:1312 PMT:0x200 FTA:True LCN:7 BandWidth:7 
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:4 type:DvbT can tune to channel
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:4 type:DvbT users: 0
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:3 type:DvbT can tune to channel
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:3 type:DvbT users: 1
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:7 type:DvbT can tune to channel
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:7 type:DvbT users: 0
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:1 type:DvbIP is disabled
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:6 type:RadioWebStream is disabled
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:8 type:Analog is disabled
2011-10-29 19:41:56.913500 [(21)]: Controller: found 3 card(s) for channel
2011-10-29 19:41:56.913500 [(21)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 5 msec
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:4 type:DvbT is available
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:7 type:DvbT is available
2011-10-29 19:41:56.913500 [(21)]: Controller:    card:3 type:DvbT is not available, tuned to different transponder
2011-10-29 19:41:56.913500 [(21)]: Controller: found 2 free card(s)
2011-10-29 19:41:56.913500 [(21)]: AdvancedCardAllocation.GetFreeCardsForChannel took 7 msec
2011-10-29 19:41:56.913500 [(21)]: Controller: try max 2 of 2 cards for timeshifting

*this causes the analyser reset below that is spot on the time that the recorder stopped!
*2011-10-29 19:41:56.929125 [(21)]: card: CardTune 4 7 Digital boysroom:4:-1
2011-10-29 19:41:56.929125 [(21)]: card: Tune 4 to 7 Digital
2011-10-29 19:41:56.929125 [(21)]: card: user: boysroom:4:-1 tune DVBT:tv:Seven Network 7 Digital Freq:177500 ONID:4115 TSID:1282 SID:1312 PMT:0x200 FTA:True LCN:7 BandWidth:7
2011-10-29 19:41:56.929125 [(21)]: TimeShifter.OnBeforeTune: resetting audio/video events
2011-10-29 19:41:56.929125 [(21)]: dvbt: Tune:DVBT:tv:Seven Network 7 Digital Freq:177500 ONID:4115 TSID:1282 SID:1312 PMT:0x200 FTA:True LCN:7 BandWidth:7
2011-10-29 19:41:56.929125 [(21)]: dvb:Submiting tunerequest Channel:7 Digital subChannel:-1 
2011-10-29 19:41:56.929125 [(21)]: dvb:Getting new subchannel
2011-10-29 19:41:56.929125 [(21)]: dvb:GetNewSubChannel:0 #0
2011-10-29 19:41:56.929125 [(21)]: TvDvbChannel ctor new subchIndex:0
2011-10-29 19:41:56.929125 [(21)]: dvb:Submit tunerequest size:1 new:0
2011-10-29 19:41:56.929125 [(21)]: subch:0 OnBeforeTune
2011-10-29 19:41:56.929125 [(21)]: dvb:Submit tunerequest calling put_TuneRequest
2011-10-29 19:41:57.913500 [(21)]: dvb:Submit tunerequest done calling put_TuneRequest
2011-10-29 19:41:57.913500 [(21)]: subch:0 OnAfterTune
2011-10-29 19:41:57.913500 [(21)]: RunGraph
2011-10-29 19:41:57.913500 [(21)]: subch:0 OnGraphStart
2011-10-29 19:41:57.913500 [(21)]: dvb:  RunGraph
2011-10-29 19:41:58.163500 [(21)]: dvb:  LockedInOnSignal ok
2011-10-29 19:41:58.163500 [(21)]: subch:0 OnGraphStarted
2011-10-29 19:41:58.163500 [(21)]: subch:0 SetupPmtGrabber:pid 200 sid:520
2011-10-29 19:41:58.163500 [(21)]: subch:0 set pmt grabber pmt:200 sid:520
2011-10-29 19:41:58.163500 [(21)]: TimeShifter.OnAfterTune: resetting audio/video time
2011-10-29 19:41:58.163500 [(21)]: WaitForPMT: Waiting for PMT 200
2011-10-29 19:41:58.226000 [(3)]: subch:0 OnPMTReceived() pmt:200 ran:True dynamic:False
2011-10-29 19:41:58.226000 [(21)]: WaitForPMT: Found PMT after 0.0625 seconds.
2011-10-29 19:41:58.226000 [(21)]: subch:0 SendPmt:520 520 FFFFFFFF 12
2011-10-29 19:41:58.226000 [(21)]: Decode pmt
2011-10-29 19:41:58.226000 [(21)]: Set descriptor data with length 6
2011-10-29 19:41:58.226000 [(21)]: Set descriptor data with length 7
2011-10-29 19:41:58.226000 [(21)]: Set descriptor data with length 3
2011-10-29 19:41:58.226000 [(21)]: subch:0 SendPMT: Channel is FTA, nothing to do.
2011-10-29 19:41:58.226000 [(21)]: subch:0 SetMpegPidMapping
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:201 pcr
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:200 pmt
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:201 video type:MPEG-2
2011-10-29 19:41:58.226000 [(21)]: subch:0    map pid:201 video type:MPEG-2
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:202 audio lang:eng type:MPEG-1
2011-10-29 19:41:58.226000 [(21)]: subch:0    map pid:202 audio lang:eng type:MPEG-1
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:204 teletext type:6
2011-10-29 19:41:58.226000 [(21)]: subch:0    map pid:204 teletext type:6
2011-10-29 19:41:58.226000 [(21)]: subch:0  pid:299 type:B
2011-10-29 19:41:58.226000 [(21)]: subch:0 stop tif
2011-10-29 19:41:58.226000 [(21)]: WaitForPmt: PMT handling took 0 seconds.
2011-10-29 19:41:58.226000 [(21)]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2011-10-29 19:41:58.226000 [(21)]: card: Tuner locked: True
2011-10-29 19:41:58.226000 [(21)]: **************************************************
2011-10-29 19:41:58.226000 [(21)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2011-10-29 19:41:58.226000 [(21)]: **************************************************
2011-10-29 19:41:58.226000 [(21)]: card: tuned user: boysroom subchannel: 0
2011-10-29 19:41:58.226000 [(21)]: user:boysroom add
2011-10-29 19:41:58.226000 [(21)]: card2:boysroom 4 0
2011-10-29 19:41:58.226000 [(21)]: Controller: boysroom 4 0
2011-10-29 19:41:58.226000 [(21)]: control2:boysroom 4 0
2011-10-29 19:41:58.241625 [(21)]: Controller: delete timeshift files D:\tv\MediaPortal Capture\Timeshift\live4-0.ts
2011-10-29 19:41:58.241625 [(21)]: card: StartTimeShifting 4 D:\tv\MediaPortal Capture\Timeshift\live4-0.ts 
2011-10-29 19:41:58.241625 [(21)]: card: CAM enabled : False
2011-10-29 19:41:58.241625 [(21)]: subch:0 SetTimeShiftFileName:D:\tv\MediaPortal Capture\Timeshift\live4-0.ts
2011-10-29 19:41:58.241625 [(21)]: Set video / audio observer
2011-10-29 19:41:58.241625 [(21)]: subch:0 SetTimeShiftFileName fill in pids
2011-10-29 19:41:58.241625 [(21)]: subch:0-0 tswriter StartTimeshifting...
2011-10-29 19:41:58.257250 [(21)]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo
2011-10-29 19:41:58.257250 [(3)]: PID seen - type = Video
2011-10-29 19:41:58.257250 [(3)]: audioVideoEventHandler Video
2011-10-29 19:41:58.429125 [(3)]: PID seen - type = Audio
2011-10-29 19:41:58.429125 [(3)]: audioVideoEventHandler Audio
2011-10-29 19:41:58.429125 [(21)]: card: WaitForTimeShiftFile - video and audio are seen after 0.171875 seconds
2011-10-29 19:41:58.429125 [(21)]: user:boysroom card:4 sub:0 add stream:D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer
2011-10-29 19:41:58.429125 [(21)]: RTSP: add stream stream4.0 file:D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer
2011-10-29 19:41:58.429125 [(21)]: Controller: StartTimeShifting started on card:4 to D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer
2011-10-29 19:41:58.429125 [(21)]: user:boysroom remove
2011-10-29 19:41:58.429125 [(21)]: subch:0-0 tswriter StopTimeshifting...
2011-10-29 19:41:58.429125 [(21)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2011-10-29 19:41:58.429125 [(21)]: DVB subch:0 Decompose()
2011-10-29 19:41:58.429125 [(21)]: FreeSubChannel CA: freeing sub channel : 0
2011-10-29 19:41:58.429125 [(21)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2011-10-29 19:41:58.429125 [(21)]: dvb:StopGraph called
2011-10-29 19:41:58.429125 [(21)]: tvcard:FreeAllSubChannels
2011-10-29 19:41:58.429125 [(21)]: dvb:StopGraph
2011-10-29 19:41:58.429125 [(21)]: dvb:StopGraph called
2011-10-29 19:41:58.444750 [(21)]: tvcard:FreeAllSubChannels
2011-10-29 19:41:58.444750 [(21)]: dvb:StopGraph filterstate already stopped, returning.
2011-10-29 19:41:58.444750 [TS_File_Cleanup(24)]: card: delete timeshift files D:\tv\MediaPortal Capture\Timeshift\live7-0.ts
2011-10-29 19:41:58.444750 [TS_File_Cleanup(24)]: card:   trying to delete D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer
2011-10-29 19:41:58.444750 [Channel state thread(19)]: Controller: DoSetChannelStates for 19 channels
2011-10-29 19:41:58.444750 [Channel state thread(19)]: ChannelStates.GetChannelMapping took 4 msec
2011-10-29 19:41:58.444750 [Channel state thread(19)]: ChannelStates.GetTuningChannels took 4 msec
2011-10-29 19:41:58.460375 [(21)]: CiMenuSupported called cardid 4
2011-10-29 19:41:58.460375 [(21)]: CiMenuSupported card AF9015 BDA Filter 2 supported: False
2011-10-29 19:41:58.460375 [Channel state thread(19)]: ChannelStates.GetAllTimeshiftingAndRecordingChannels took 14 msec
2011-10-29 19:41:58.460375 [Channel state thread(19)]: ChannelStates.DoSetChannelStates took 25 msec
2011-10-29 19:42:03.444750 [TS_File_Cleanup(24)]: card: delete timeshift files D:\tv\MediaPortal Capture\Timeshift\live7-0.ts
2011-10-29 19:42:03.444750 [TS_File_Cleanup(24)]: card:   trying to delete D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer
2011-10-29 19:42:03.444750 [TS_File_Cleanup(24)]: card:   deleted file D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer
2011-10-29 19:42:03.444750 [TS_File_Cleanup(24)]: card:   trying to delete D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer1.ts
2011-10-29 19:42:03.444750 [TS_File_Cleanup(24)]: card:   deleted file D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer1.ts
2011-10-29 19:44:03.960375 [(21)]: CiMenuSupported called cardid 4
2011-10-29 19:44:03.960375 [(21)]: CiMenuSupported card AF9015 BDA Filter 2 supported: False



TSwriter - Continuity error at 19:39 - but there is nothing in TV log above at this time and recording does not stop till 19:41:56 (per recording)...
29-10-2011 19:35:56.563 CDiskRecorder::Flush() - Throttle to 15416 bytes
29-10-2011 19:35:56.563 Recorder: TIMESHIFT start of audio detected
29-10-2011 19:35:56.610 CDiskRecorder::Flush() - Throttle to 32336 bytes (max)
29-10-2011 19:39:46.47 Recorder:Pid 207 Continuity error... 6 ( prev 8 ) - bad signal?
29-10-2011 19:39:46.47 Recorder:Pid 2d0 Continuity error... 2 ( prev 0 ) - bad signal?
29-10-2011 19:39:46.63 Recorder:Pid 247 Continuity error... 3 ( prev 1 ) - bad signal?
*29-10-2011 19:41:56.929 analyzer: reset
29-10-2011 19:41:56.929 PatParser:Reset(0)
29-10-2011 19:41:56.929 NIT:Reset
29-10-2011 19:41:56.929 PatParser::Reset done
29-10-2011 19:41:56.929 cagrabber: reset
29-10-2011 19:41:56.929 epg: reset
29-10-2011 19:41:56.929 epg:ResetEPG()
29-10-2011 19:41:56.929 mhw reset
29-10-2011 19:41:57.913 CMpTsFilter::Pause()
29-10-2011 19:41:58.163 CMpTsFilter::Run()
29-10-2011 19:41:58.163 pmtgrabber: set callback:fdfc18
29-10-2011 19:41:58.163 pmtgrabber: grab pmt:200 sid:520
29-10-2011 19:41:58.226 pmtgrabber: got pmt 200 sid:520
29-10-2011 19:41:58.226 pmtgrabber: PMT pids changed from:
29-10-2011 19:41:58.226  pcr      pid:    0 
29-10-2011 19:41:58.226  pmt      pid:    0 
29-10-2011 19:41:58.226 pmtgrabber: PMT pids changed to:
29-10-2011 19:41:58.226  pcr      pid:  201 
29-10-2011 19:41:58.226  pmt      pid:  200 
29-10-2011 19:41:58.226  video    pid:  201 type: MPEG2
29-10-2011 19:41:58.226  audio    pid:  202 language: eng type: MPEG1 - audio
29-10-2011 19:41:58.226 pmtgrabber: got new pmt version:12 ffffffff, service_id:520
29-10-2011 19:41:58.226 pmtgrabber: do callback pid 200
29-10-2011 19:41:58.226 analyzer: set video pid:201
29-10-2011 19:41:58.226 analyzer: reset
29-10-2011 19:41:58.226 analyzer: set audio pid:202
29-10-2011 19:41:58.226 analyzer: reset
29-10-2011 19:41:58.241 Recorder: TIMESHIFT SetVideoAudioObserver observer ok
29-10-2011 19:41:58.241 Recorder: TIMESHIFT set filename:D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer
29-10-2011 19:41:58.241 CDiskRecorder::SetChannelType() - Channel type is 0
29-10-2011 19:41:58.241 CDiskRecorder::SetChannelType() - Channel type is 0
29-10-2011 19:41:58.241 Recorder: TIMESHIFT paused=yes
29-10-2011 19:41:58.241 Recorder: TIMESHIFT Received from TvService: pmt pid:0x200 serviceId: 0x520 pmtlength:69
29-10-2011 19:41:58.241 Recorder: TIMESHIFT Old pids cleared
29-10-2011 19:41:58.241 Recorder: TIMESHIFT got pmt - tableid: 0x2 section_length: 69 sid: 0x520
29-10-2011 19:41:58.241 Recorder: TIMESHIFT PMT parsed  - Pid 0x200 ServiceId 0x520 stream count: 4
29-10-2011 19:41:58.241 Recorder: TIMESHIFT pcr pid:0x201
29-10-2011 19:41:58.241 Recorder: TIMESHIFT SetPcrPid clear old PIDs
29-10-2011 19:41:58.241 Recorder: TIMESHIFT add video stream pid: 0x201 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 5
29-10-2011 19:41:58.241 Recorder: TIMESHIFT add audio stream pid: 0x202 fake pid: 0x40 stream type: 0x3 logical type: 0x3 descriptor length: 6
29-10-2011 19:41:58.241 Recorder: TIMESHIFT add teletext stream pid: 0x204 fake pid: 0x8e stream type: 0x6 logical type: 0x56 descriptor length: 7
29-10-2011 19:41:58.241 Recorder: TIMESHIFT stream rejected - pid: 0x299 stream type: 0xb logical type: 0xb descriptor length: 18
29-10-2011 19:41:58.241 Recorder: TIMESHIFT paused=no
29-10-2011 19:41:58.241 Recorder: TIMESHIFT Start 'D:\tv\MediaPortal Capture\Timeshift\live4-0.ts.tsbuffer'
29-10-2011 19:41:58.257 Recorder: TIMESHIFT start of video detected
29-10-2011 19:41:58.288 Recorder: TIMESHIFT clear TS packet queue
29-10-2011 19:41:58.288 CDiskRecorder::WriteToTimeshiftFile() - Reset write buffer throttle
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 376 bytes
29-10-2011 19:41:58.288 Recorder: TIMESHIFT Info : Next broadcaster program clock reference rollover : 0 days 04:34:33 0
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 564 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 940 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 940 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 940 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 940 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 940 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 1880 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 1880 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 1880 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 1880 bytes
29-10-2011 19:41:58.288 CDiskRecorder::Flush() - Throttle to 1880 bytes
29-10-2011 19:41:58.304 CDiskRecorder::Flush() - Throttle to 3760 bytes
29-10-2011 19:41:58.304 CDiskRecorder::Flush() - Throttle to 3760 bytes
29-10-2011 19:41:58.304 CDiskRecorder::Flush() - Throttle to 3760 bytes
29-10-2011 19:41:58.304 CDiskRecorder::Flush() - Throttle to 6016 bytes
29-10-2011 19:41:58.335 CDiskRecorder::Flush() - Throttle to 7520 bytes
29-10-2011 19:41:58.335 CDiskRecorder::Flush() - Throttle to 9400 bytes
29-10-2011 19:41:58.351 CDiskRecorder::Flush() - Throttle to 15416 bytes
29-10-2011 19:41:58.382 CDiskRecorder::Flush() - Throttle to 32336 bytes (max)
29-10-2011 19:41:58.429 Recorder: TIMESHIFT start of audio detected
29-10-2011 19:41:58.429 Recorder: TIMESHIFT Stop 'D:\tv\MediaPortal Capture\Timeshift\live7-0.ts.tsbuffer'
29-10-2011 19:41:58.429 Recorder: TIMESHIFT Reset
29-10-2011 19:41:58.429 CDiskRecorder::Reset() - Reset write buffer throttle
29-10-2011 19:41:58.429 del m_pVideoAnalyzer
29-10-2011 19:41:58.429 analyzer: reset
29-10-2011 19:41:58.429 del m_pPmtGrabber
29-10-2011 19:41:58.429 del m_pRecorder
29-10-2011 19:41:58.429 del m_pTimeShifting
29-10-2011 19:41:58.429 del m_pTeletextGrabber
29-10-2011 19:41:58.429 del m_pCaGrabber
29-10-2011 19:41:58.429 del done...
29-10-2011 19:41:58.429 CMpTsFilter::Pause()
29-10-2011 19:41:58.429 CMpTsFilter::Stop()
29-10-2011 19:44:09.913 Recorder: TIMESHIFT paused=yes
29-10-2011 19:44:09.913 epg: reset
29-10-2011 19:44:09.913 epg:ResetEPG()
29-10-2011 19:44:09.913 mhw reset
29-10-2011 19:44:10.835 pmtgrabber: set callback:fdfc18
29-10-2011 19:44:10.835 pmtgrabber: grab pmt:100 sid:421
29-10-2011 19:44:10.851 pmtgrabber: got pmt 100 sid:421
29-10-2011 19:44:10.851 pmtgrabber: PMT pids changed from:
29-10-2011 19:44:10.851  pcr      pid:    0 
29-10-2011 19:44:10.851  pmt      pid:  100 
29-10-2011 19:44:10.851 pmtgrabber: PMT pids changed to:
29-10-2011 19:44:10.851  pcr      pid:   87 
29-10-2011 19:44:10.851  pmt      pid:  100 
29-10-2011 19:44:10.851  video    pid:  207 type: MPEG2
29-10-2011 19:44:10.851  audio    pid:  2d0 language: eng type: MPEG2 - audio
29-10-2011 19:44:10.851 pmtgrabber: got new pmt version:2 ffffffff, service_id:421
 

mm1352000

Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    Hi feizex

    Lots of comments for you...

    First, I think it is a tuner hardware or signal problem. I mean it is clear that TV Server is not intentionally stopping the recording - that would be logged. It is fully possible that tuning one channel on one tuner can cause interference for the other tuner, and that can be channel dependent - see -->here<-- as an example. The continuity errors in the TsWriter log indicate that there is signal corruption in the stream received from the tuner and given that your signal strength and quality are 100/100 I wonder if your signal is too strong (not that I would read too much into the signal stats). I note that some tuner drivers "fight" stream corruption while others just give up like your tuner appears to be doing (it is the tuner driver that is silently stopping the stream being written to the recording file).

    Your options are basically to try to change the characteristics of the signal received by the tuner, either by changing the antenna position/orientation, amplifier, attenuator or band pass filter. There is no guarantee that it will help and without professional equipment and knowledge you're going to be really lucky if you find something that works. If you can afford it, get a professional in to check the signal quality and level and perform tests while he/she is there to see if any adjustments to signal strength either up or down can improve the situation. Otherwise you might have to live with it or buy another tuner...

    mm
     

    feizex

    Portal Member
    July 26, 2009
    24
    1
    Home Country
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    ok thanks. If it is the card driver then I give up. I could only ever get it to run with the driver version that came with it. Website ones won't work.

    Is there any chance it could be MP when it does these resets?
    *29-10-2011 19:41:56.929 analyzer: reset
    29-10-2011 19:41:56.929 PatParser:Reset(0)
    29-10-2011 19:41:56.929 NIT:Reset
    29-10-2011 19:41:56.929 PatParser::Reset done
    29-10-2011 19:41:56.929 cagrabber: reset
    29-10-2011 19:41:56.929 epg: reset
    29-10-2011 19:41:56.929 epg:ResetEPG()
    29-10-2011 19:41:56.929 mhw reset

    Thanks,
    Feizex.
     

    mm1352000

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    Hi again

    ok thanks. If it is the card driver then I give up. I could only ever get it to run with the driver version that came with it. Website ones won't work.
    When you say "won't work", what exactly do you mean - do they not install, or does the tuner simply not work properly with them? It could be worthwhile trying them again. I can try to help with them if possible...

    Is there any chance it could be MP when it does these resets?
    No. Those resets are completely normal - it is TsWriter being prepared to start timeshifting on the other tuner. They are completely isolated to the other tuner.

    mm
     

    mawhite

    Portal Member
    October 23, 2011
    21
    0
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    I run that card as tuners 3 & 4. I probably should try using them a bit to see if I have any issue.
     

    feizex

    Portal Member
    July 26, 2009
    24
    1
    Home Country
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    Mawhite, could you please advise what driver version your A825 uses? Thanks!

    Mine says version 8.0.0.22 17/1/2009 in device manager. This is the one that came on CD in box.

    In the past I have tried 8.0.0.26 drivers from Avermedia website, but from memory I could not tune any channels.

    They state for MCE only. Not sure what that is supposed to mean. I have XP so MCE2005 driver should work.
    I did try Win7 64bit OS + 8.0.64.26 driver at one point. No joy either - again from memory.

    Driver listing here (scroll to end)
    AVerMedia AVerTV Global - TV Tuners, Media Player, IPTV and Multimedia Products - AVerTV TwinStar
     

    mm1352000

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Re: Avermedia A825 dual tuner - occasionally stops one tuner when starting the other

    Hmmm, interesting that AverMedia don't offer an XP driver on their website. According to the LinuxTV wiki the device is based on the AfaTech AFA9035 chipset (link). If the drivers from AverMedia don't work and you're feeling a little adventurous then you could try installing drivers from a similar AFA9035-based device. Note: take care as theoretically that can damage the tuner, however if you choose the drivers carefully it is *very* unlikely.
     

    Users who are viewing this thread

    Top Bottom