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