[mythtv-users] Scheduled Recording - File Missing

Wed Mar 12 09:57:50 UTC 2008

Hi List,

I have had this problem whilst using 0.20.2 debian-multimedia package, 
and I just had it again today using SVN @ 16487 (around the time 0.21 
was released).

I have a scheduled recording to record Neighbours at 0510 each morning, 
the logs suggest recording was started, and I don't see any glaring 
errors, but no file is created. I am running a looped daemon to monitor 
directory file changes in my only storage group directory, and this 
detects no new files at the time of the showing.

Neighbours was setup as a "Record at this timeslot every day".

I enclose the logs of a minute before and after the recording was meant 
to start, using "--verbose most" backend commandline.

This problem was not confined to just this one showing, prior to 
upgrading to SVN I had no recordings at all for any shows for a whole 3 
days with similar symptoms, yet it worked for a week prior. After 
upgrading to SVN it recorded ~10 shows successfully yesterday but today 
I notice Neighbours had fell short of the mark.

Using a Hauppauge Nova-T 500 DVB-T, EIT as listing information. Dual 
tuner card with 2 tuners setup with multirec set with 2 virtual tuners 
on each card. Mythfrontend reports 4 tuners in total.

Yours,
Frosty.

* LOG FOLLOWS*

2008-03-12 05:09:29.647 Scheduler: FillRecordingDir: Starting
2008-03-12 05:09:29.647 Scheduler: FillRecordingDir: The only directory 
in the Default Storage Group is /data/mythtv/recorded, so it will be 
used by default.
2008-03-12 05:09:29.648 Scheduler: FillRecordingDir: Finished
2008-03-12 05:09:29.648 TVRec(3): RecordPending on inputid 3
2008-03-12 05:09:29.649 CardUtil:   Group ID 2
2008-03-12 05:09:29.650 CardUtil:   Card ID 4
2008-03-12 05:09:29.650 TVRec(4): RecordPending on inputid 3
2008-03-12 05:09:29.814 DTVSM(0): Time Offset: -2.57981
2008-03-12 05:09:30.005 TVRec(4): ASK_RECORDING 4 29 0 0
2008-03-12 05:09:30.006 MythEvent: ASK_RECORDING 4 29 0 0
2008-03-12 05:09:30.007 MythSocket(ad839ab8:41): UpRef: 2
2008-03-12 05:09:30.010 MythSocket(ad839ab8:41): DownRef: 1
2008-03-12 05:09:30.011 MythSocket(a9b4ab38:42): UpRef: 2
2008-03-12 05:09:30.011 write -> 42 598     
BACKEND_MESSAGE[]:[]ASK_RECORDING 4 29 0 0[]:[]Neighbours[]:[]Dar...
2008-03-12 05:09:30.012 MythSocket(a9b4ab38:42): DownRef: 1
2008-03-12 05:09:30.236 TVRec(3): ASK_RECORDING 3 29 0 0
2008-03-12 05:09:30.238 MythEvent: ASK_RECORDING 3 29 0 0
2008-03-12 05:09:30.239 MythSocket(ad839ab8:41): UpRef: 2
2008-03-12 05:09:30.242 MythSocket(ad839ab8:41): DownRef: 1
2008-03-12 05:09:30.242 MythSocket(a9b4ab38:42): UpRef: 2
2008-03-12 05:09:30.243 write -> 42 598     
BACKEND_MESSAGE[]:[]ASK_RECORDING 3 29 0 0[]:[]Neighbours[]:[]Dar...
2008-03-12 05:09:30.243 MythSocket(a9b4ab38:42): DownRef: 1
2008-03-12 05:09:30.651 CardUtil:   Group ID 2
2008-03-12 05:09:30.654 CardUtil:   Card ID 4
2008-03-12 05:09:30.806 DTVSM(0): Time Offset: -2.70256
2008-03-12 05:09:31.655 CardUtil:   Group ID 2
2008-03-12 05:09:31.658 CardUtil:   Card ID 4
2008-03-12 05:09:31.850 DTVSM(0): Time Offset: -2.82881
2008-03-12 05:09:32.659 CardUtil:   Group ID 2
2008-03-12 05:09:32.662 CardUtil:   Card ID 4
2008-03-12 05:09:32.842 DTVSM(0): Time Offset: -2.95531
2008-03-12 05:09:33.663 CardUtil:   Group ID 2
2008-03-12 05:09:33.663 JobQueue: Currently set to run up to 1 job(s) max.
2008-03-12 05:09:33.670 JobQueue: GetJobsInQueue: findJobs search 
bitmask 4, found 9 total jobs
2008-03-12 05:09:33.671 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311051000 in Finished state.
2008-03-12 05:09:33.671 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1003 @ 20080311092500 in Finished state.
2008-03-12 05:09:33.672 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1071 @ 20080311103000 in Finished state.
2008-03-12 05:09:33.672 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1019 @ 20080311113000 in Finished state.
2008-03-12 05:09:33.673 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1006 @ 20080311143500 in Finished state.
2008-03-12 05:09:33.673 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311180000 in Finished state.
2008-03-12 05:09:33.674 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1011 @ 20080311210000 in Finished state.
2008-03-12 05:09:33.675 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1020 @ 20080311223000 in Finished state.
2008-03-12 05:09:33.675 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1001 @ 20080312012500 in Finished state.
2008-03-12 05:09:33.666 CardUtil:   Card ID 4
2008-03-12 05:09:33.834 DTVSM(0): Time Offset: -3.08206
2008-03-12 05:09:34.678 CardUtil:   Group ID 2
2008-03-12 05:09:34.682 CardUtil:   Card ID 4
2008-03-12 05:09:34.826 DTVSM(0): Time Offset: -3.20581
2008-03-12 05:09:35.683 CardUtil:   Group ID 2
2008-03-12 05:09:35.686 CardUtil:   Card ID 4
2008-03-12 05:09:35.818 DTVSM(0): Time Offset: -3.32981
2008-03-12 05:09:36.403 EITHelper: Added 2 events
2008-03-12 05:09:36.687 CardUtil:   Group ID 2
2008-03-12 05:09:36.690 CardUtil:   Card ID 4
2008-03-12 05:09:36.810 EITHelper: Added 1 events
2008-03-12 05:09:36.818 DTVSM(0): Time Offset: -3.45406
2008-03-12 05:09:37.691 CardUtil:   Group ID 2
2008-03-12 05:09:37.694 CardUtil:   Card ID 4
2008-03-12 05:09:37.814 DTVSM(0): Time Offset: -3.57831
2008-03-12 05:09:38.695 CardUtil:   Group ID 2
2008-03-12 05:09:38.698 CardUtil:   Card ID 4
2008-03-12 05:09:38.806 DTVSM(0): Time Offset: -3.70281
2008-03-12 05:09:39.699 CardUtil:   Group ID 2
2008-03-12 05:09:39.702 CardUtil:   Card ID 4
2008-03-12 05:09:39.802 DTVSM(0): Time Offset: -3.82457
2008-03-12 05:09:40.703 CardUtil:   Group ID 2
2008-03-12 05:09:40.706 CardUtil:   Card ID 4
2008-03-12 05:09:40.794 DTVSM(0): Time Offset: -3.82132
2008-03-12 05:09:41.707 CardUtil:   Group ID 2
2008-03-12 05:09:41.710 CardUtil:   Card ID 4
2008-03-12 05:09:41.838 DTVSM(0): Time Offset: -3.82132
2008-03-12 05:09:42.711 CardUtil:   Group ID 2
2008-03-12 05:09:42.714 CardUtil:   Card ID 4
2008-03-12 05:09:42.830 DTVSM(0): Time Offset: -3.82107
2008-03-12 05:09:43.715 CardUtil:   Group ID 2
2008-03-12 05:09:43.718 CardUtil:   Card ID 4
2008-03-12 05:09:43.822 DTVSM(0): Time Offset: -3.82082
2008-03-12 05:09:44.719 CardUtil:   Group ID 2
2008-03-12 05:09:44.722 CardUtil:   Card ID 4
2008-03-12 05:09:44.819 EITHelper: Added 1 events
2008-03-12 05:09:44.826 DTVSM(0): Time Offset: -3.82131
2008-03-12 05:09:45.225 EITHelper: Added 1 events
2008-03-12 05:09:45.723 CardUtil:   Group ID 2
2008-03-12 05:09:45.726 CardUtil:   Card ID 4
2008-03-12 05:09:45.818 DTVSM(0): Time Offset: -3.82156
2008-03-12 05:09:46.727 CardUtil:   Group ID 2
2008-03-12 05:09:46.730 CardUtil:   Card ID 4
2008-03-12 05:09:46.810 DTVSM(0): Time Offset: -3.82181
2008-03-12 05:09:47.731 CardUtil:   Group ID 2
2008-03-12 05:09:47.734 CardUtil:   Card ID 4
2008-03-12 05:09:47.802 DTVSM(0): Time Offset: -3.81881
2008-03-12 05:09:48.735 CardUtil:   Group ID 2
2008-03-12 05:09:48.738 CardUtil:   Card ID 4
2008-03-12 05:09:48.846 DTVSM(0): Time Offset: -3.81906
2008-03-12 05:09:49.739 CardUtil:   Group ID 2
2008-03-12 05:09:49.742 CardUtil:   Card ID 4
2008-03-12 05:09:49.838 DTVSM(0): Time Offset: -3.81931
2008-03-12 05:09:50.743 CardUtil:   Group ID 2
2008-03-12 05:09:50.746 CardUtil:   Card ID 4
2008-03-12 05:09:50.830 DTVSM(0): Time Offset: -3.81956
2008-03-12 05:09:51.747 CardUtil:   Group ID 2
2008-03-12 05:09:51.750 CardUtil:   Card ID 4
2008-03-12 05:09:51.822 DTVSM(0): Time Offset: -3.81981
2008-03-12 05:09:52.751 CardUtil:   Group ID 2
2008-03-12 05:09:52.754 CardUtil:   Card ID 4
2008-03-12 05:09:52.818 DTVSM(0): Time Offset: -3.81981
2008-03-12 05:09:53.241 EITHelper: Added 4 events
2008-03-12 05:09:53.649 EITHelper: Added 2 events
2008-03-12 05:09:53.755 CardUtil:   Group ID 2
2008-03-12 05:09:53.758 CardUtil:   Card ID 4
2008-03-12 05:09:53.810 DTVSM(0): Time Offset: -3.81956
2008-03-12 05:09:54.053 EITHelper: Added 1 events
2008-03-12 05:09:54.759 CardUtil:   Group ID 2
2008-03-12 05:09:54.762 CardUtil:   Card ID 4
2008-03-12 05:09:54.802 DTVSM(0): Time Offset: -3.81931
2008-03-12 05:09:55.763 CardUtil:   Group ID 2
2008-03-12 05:09:55.766 CardUtil:   Card ID 4
2008-03-12 05:09:55.846 DTVSM(0): Time Offset: -3.82206
2008-03-12 05:09:56.767 CardUtil:   Group ID 2
2008-03-12 05:09:56.770 CardUtil:   Card ID 4
2008-03-12 05:09:56.838 DTVSM(0): Time Offset: -3.8248
2008-03-12 05:09:57.771 CardUtil:   Group ID 2
2008-03-12 05:09:57.774 CardUtil:   Card ID 4
2008-03-12 05:09:57.830 DTVSM(0): Time Offset: -3.82431
2008-03-12 05:09:58.775 CardUtil:   Group ID 2
2008-03-12 05:09:58.778 CardUtil:   Card ID 4
2008-03-12 05:09:58.822 DTVSM(0): Time Offset: -3.82381
2008-03-12 05:09:59.779 CardUtil:   Group ID 2
2008-03-12 05:09:59.782 CardUtil:   Card ID 4
2008-03-12 05:09:59.818 DTVSM(0): Time Offset: -3.82356
2008-03-12 05:10:00.783 CardUtil:   Group ID 2
2008-03-12 05:10:00.786 CardUtil:   Card ID 4
2008-03-12 05:10:00.810 DTVSM(0): Time Offset: -3.82256
2008-03-12 05:10:01.664 EITHelper: Added 3 events
2008-03-12 05:10:01.787 CardUtil:   Group ID 2
2008-03-12 05:10:01.790 CardUtil:   Card ID 4
2008-03-12 05:10:01.806 DTVSM(0): Time Offset: -3.82182
2008-03-12 05:10:02.070 EITHelper: Added 1 events
2008-03-12 05:10:02.478 EITHelper: Added 1 events
2008-03-12 05:10:02.791 CardUtil:   Group ID 2
2008-03-12 05:10:02.794 CardUtil:   Card ID 4
2008-03-12 05:10:02.795 TVRec(3): StartRecording(Neighbours)
2008-03-12 05:10:02.795 TVRec(3): Checking input group recorders - begin
2008-03-12 05:10:02.796 TVRec(3): Checking input group recorders - done
2008-03-12 05:10:02.798 DTVSM(0): Time Offset: -3.82107
2008-03-12 05:10:02.819 ProgramInfo: StartedRecording: Recording to 
'/data/mythtv/recorded/1005_20080312051000.mpg'
2008-03-12 05:10:02.837 TVRec(3): StartedRecording(0xa581b5b0) 
fn(/data/mythtv/recorded/1005_20080312051000.mpg)
2008-03-12 05:10:02.838 MythEvent: RECORDING_LIST_CHANGE
2008-03-12 05:10:02.838 TVRec(3): ClearFlags(CancelNextRecording,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,EITScannerRunning,
2008-03-12 05:10:02.838 MythSocket(ad839ab8:41): UpRef: 2
2008-03-12 05:10:02.846 EITCache: Wrote 2 modified entries of 309 for 
channel 1011 to database.
2008-03-12 05:10:02.854 MythSocket(ad839ab8:41): DownRef: 1
2008-03-12 05:10:02.861 EITCache: Wrote 1 modified entries of 128 for 
channel 1018 to database.
2008-03-12 05:10:02.866 MythSocket(a9b4ab38:42): UpRef: 2
2008-03-12 05:10:02.871 EITCache: Wrote 1 modified entries of 138 for 
channel 1020 to database.
2008-03-12 05:10:02.882 write -> 42 51      
BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2008-03-12 05:10:02.901 EITCache: Wrote 74 modified entries of 223 for 
channel 1028 to database.
2008-03-12 05:10:02.907 MythSocket(a9b4ab38:42): DownRef: 1
2008-03-12 05:10:02.912 EITCache: Wrote 2 modified entries of 310 for 
channel 1030 to database.
2008-03-12 05:10:02.930 EITCache: Wrote 2 modified entries of 202 for 
channel 1082 to database.
2008-03-12 05:10:02.932 EITCache: Wrote 2 modified entries of 218 for 
channel 1083 to database.
2008-03-12 05:10:02.936 EITCache: Wrote 2 modified entries of 36 for 
channel 1108 to database.
2008-03-12 05:10:02.943 EITCache: Wrote 2 modified entries of 678 for 
channel 1710 to database.
2008-03-12 05:10:02.945 EITCache: Wrote 4 modified entries of 52 for 
channel 1712 to database.
2008-03-12 05:10:02.950 EITCache: Wrote 2 modified entries of 70 for 
channel 1723 to database.
2008-03-12 05:10:02.952 EITCache: Wrote 2 modified entries of 382 for 
channel 1725 to database.
2008-03-12 05:10:02.953 TVRec(3): ClearFlags(EITScannerRunning,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-03-12 05:10:02.954 TVRec(3): Changing from None to RecordingOnly
2008-03-12 05:10:02.955 TVRec(3): 
ClearFlags(FrontendReady,CancelNextRecording,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-03-12 05:10:02.955 TVRec(3): Request: Program(yes) channel() 
input() flags(Recording,)
2008-03-12 05:10:02.956 TVRec(3): HW Tuner: 3->3
2008-03-12 05:10:02.992 TVRec(3): TeardownSignalMonitor() -- begin
2008-03-12 05:10:02.993 DVBSM(1): Stop() -- begin
2008-03-12 05:10:02.994 SM(1)::Stop: begin
2008-03-12 05:10:03.034 SM(1)::Stop: end
2008-03-12 05:10:03.034 DVBSH(1): RemoveListener(0xa625ada0) -- begin
2008-03-12 05:10:03.034 DVBSH(1): RemoveListener(0xa625ada0) -- locked
2008-03-12 05:10:03.086 DVBSH(1): RunTS(): shutdown
2008-03-12 05:10:03.090 DVBSH(1): RemovePIDFilter(0x0)
2008-03-12 05:10:03.091 PIDInfo(1): Closing filter for pid 0x0
2008-03-12 05:10:03.091 DVBSH(1): RemovePIDFilter(0x10)
2008-03-12 05:10:03.092 PIDInfo(1): Closing filter for pid 0x10
2008-03-12 05:10:03.092 DVBSH(1): RemovePIDFilter(0x11)
2008-03-12 05:10:03.093 PIDInfo(1): Closing filter for pid 0x11
2008-03-12 05:10:03.093 DVBSH(1): RemovePIDFilter(0x14)
2008-03-12 05:10:03.094 PIDInfo(1): Closing filter for pid 0x14
2008-03-12 05:10:03.096 DVBSH(1): RunTS(): end
2008-03-12 05:10:03.096 DVBSH(1): RemoveListener(0xa625ada0) -- end
2008-03-12 05:10:03.098 DVBSM(1): Stop() -- end
2008-03-12 05:10:03.099 DVBSM(1): Stop() -- begin
2008-03-12 05:10:03.099 SM(1)::Stop: begin
2008-03-12 05:10:03.100 SM(1)::Stop: end
2008-03-12 05:10:03.100 DVBSM(1): Stop() -- end
2008-03-12 05:10:03.101 TVRec(3): TeardownSignalMonitor() -- end
2008-03-12 05:10:03.101 DVBSM(1): Stop() -- begin
2008-03-12 05:10:03.101 TVRec(3): ClearFlags(SignalMonitorRunning,) -> 
RunMainLoop,WaitingForSignal,
2008-03-12 05:10:03.102 SM(1)::Stop: begin
2008-03-12 05:10:03.106 TVRec(3): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,
2008-03-12 05:10:03.110 SM(1)::Stop: end
2008-03-12 05:10:03.114 TVRec(3): ClearFlags(PENDINGACTIONS,) -> 
RunMainLoop,
2008-03-12 05:10:03.118 DVBSM(1): Stop() -- end
2008-03-12 05:10:03.122 TVRec(3): No recorder yet, calling TuningFrequency
2008-03-12 05:10:03.130 SM(1)::Stop: begin
2008-03-12 05:10:03.134 DVBChan(3:1): Opening DVB channel
2008-03-12 05:10:03.142 SM(1)::Stop: end
2008-03-12 05:10:03.146 DVBChan(3:1): SetChannelByString(5):
2008-03-12 05:10:03.156 DVBChan(3:1): 682166670 qam_64 a 2/3 1/2 8 2 
1/32 n v
2008-03-12 05:10:03.157 DVBChan(3:1): Old Params: 634166670 qam_16 a 3/4 
3/4 8 2 1/32 n v
            DVBChan(3:1): New Params: 682166670 qam_64 a 2/3 1/2 8 2 
1/32 n v
2008-03-12 05:10:03.157 DVBChan(3:1): Tune(): Tuning to 682166670Hz
2008-03-12 05:10:03.497 dvbchannel.cpp:wait_for_backend: Status: 
Signal,Carrier,Sync,Lock,
2008-03-12 05:10:03.498 DVBChan(3:1): Tune(): Frequency tuning successful.
2008-03-12 05:10:03.498 DVBChan(3:1): SetChannelByString(5): Tuned to 
frequency.
2008-03-12 05:10:03.499 TVRec(3): Starting Signal Monitor
2008-03-12 05:10:03.499 TVRec(3): SetupSignalMonitor(1, 0)
2008-03-12 05:10:03.500 DVBChan(3:1): Opening DVB channel
2008-03-12 05:10:03.504 DVBSM(1): Can measure Signal Strength
2008-03-12 05:10:03.504 DVBSM(1): Can measure S/N
2008-03-12 05:10:03.508 DVBSM(1): Can measure Bit Error Rate
2008-03-12 05:10:03.510 DVBSM(1): Can count Uncorrected Blocks
2008-03-12 05:10:03.510 SM(1)::RemoveFlags: Seen() Match() Wait()
2008-03-12 05:10:03.511 DVBSM(1): DVBSignalMonitor::ctor initial flags 
Seen() Match() Wait(Sig,SNR,BER,UB,)
2008-03-12 05:10:03.511 TVRec(3): Signal monitor successfully created
2008-03-12 05:10:03.512 TVRec(3): Setting up table monitoring.
2008-03-12 05:10:03.514 Using profile 'Live TV' to record
2008-03-12 05:10:03.514 TVRec(3): DVB service_id 12866 on net_id 9018 
tsid 12290
2008-03-12 05:10:03.515 DTVSM(1)::SetDVBService(transport_id: 12290, 
network_id: 9018, service_id: 12866):
2008-03-12 05:10:03.515 SM(1)::RemoveFlags: Seen(PMT,SDT,Crypt,) 
Match(PMT,SDT,Crypt,) Wait()
2008-03-12 05:10:03.515 SM(1)::AddFlags: Seen() Match() Wait(PMT,SDT,)
2008-03-12 05:10:03.516 SM(1)::AddFlags: Seen() Match() Wait(PMT,SDT,Pos,)
2008-03-12 05:10:03.516 TVRec(3): Successfully set up DVB table monitoring.
2008-03-12 05:10:03.517 SM(1)::Start: begin
2008-03-12 05:10:03.518 SM(1)::AddFlags: Seen() Match() Wait(Sig,)
2008-03-12 05:10:03.518 SM(1)::Start: end
2008-03-12 05:10:03.526 TVRec(3): SetFlags(SignalMonitorRunning,) -> 
RunMainLoop,SignalMonitorRunning,
2008-03-12 05:10:03.527 TVRec(3): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,SignalMonitorRunning,
2008-03-12 05:10:03.527 TVRec(3): SetFlags(WaitingForSignal,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-03-12 05:10:03.528 TVRec(3): ClearFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-03-12 05:10:03.528 TVRec(3): SetFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2008-03-12 05:10:03.522 SM(1)::RemoveFlags: Seen() Match() Wait(Pos,)
2008-03-12 05:10:03.529 AutoExpire: Cardid 3: is starting a recording on 
an unknown fsID soon.
2008-03-12 05:10:03.538 AutoExpire: CalcParams()
2008-03-12 05:10:03.539 Cardid 3: max bitrate 142089 KB/min
2008-03-12 05:10:03.540 Maximal bitrate of busy encoders is 142089 KB/min
--- GetFilesystemInfos directory list start ---
Dir: jawa:/data/mythtv/recorded
     Location: Local
     Drive ID: 1
     TotalKB : 195303400
     UsedKB  : 2008-03-12 05:10:03.542 DVBSM(1): UpdateValues -- Signal 
Locked59366920

     FreeKB  : 135936480

--- GetFilesystemInfos directory list end ---2008-03-12 05:10:03.546 
DVBSH(1): AddListener(0xa58e1e08) -- begin

2008-03-12 05:10:03.555 DVBSH(1): AddListener(0xa58e1e08) -- locked
2008-03-12 05:10:03.558 fsID #1: Total:   186.3 GB   Used:    56.6 GB   
Free:   129.6 GB
2008-03-12 05:10:03.562 DVBSH(1): AddListener(0xa58e1e08) -- end
2008-03-12 05:10:03.564 DVBSH(1): RunTS(): begin
2008-03-12 05:10:03.567     Cardid 3: max bitrate 18945 Kb/sec, fsID 1 
max is now 142089 KB/min
2008-03-12 05:10:03.574 DVBSH(1): AddPIDFilter(0x0) priority 2
2008-03-12 05:10:03.578   Max of 142089 KB/min for fsID 1 is higher than 
the existing Max of 0 so we'll use this Max instead
2008-03-12 05:10:03.582 PIDInfo(1): Opening filter for pid 0x0
2008-03-12 05:10:03.586 AutoExpire: CalcParams(): Max required Free 
Space: 3.0 GB w/freq: 15 min
2008-03-12 05:10:03.590 DVBSH(1): AddPIDFilter(0x10) priority 2
2008-03-12 05:10:03.595 Started recording: Neighbours "Darren Stark 
makes a shock return to Ramsay Street": channel 1005 on cardid 3, sourceid 1
2008-03-12 05:10:03.602 PIDInfo(1): Opening filter for pid 0x10
2008-03-12 05:10:03.607 Scheduler: Update next_record for 75
2008-03-12 05:10:03.618 DVBSH(1): AddPIDFilter(0x11) priority 2
2008-03-12 05:10:03.622 MythEvent: SCHEDULE_CHANGE
2008-03-12 05:10:03.626 PIDInfo(1): Opening filter for pid 0x11
2008-03-12 05:10:03.630 MythSocket(ad839ab8:41): UpRef: 2
2008-03-12 05:10:03.638 MythSocket(ad839ab8:41): DownRef: 1
2008-03-12 05:10:03.638 MythSocket(a9b4ab38:42): UpRef: 2
2008-03-12 05:10:03.639 write -> 42 45      
BACKEND_MESSAGE[]:[]SCHEDULE_CHANGE[]:[]empty
2008-03-12 05:10:03.639 MythSocket(a9b4ab38:42): DownRef: 1
2008-03-12 05:10:03.634 DVBSH(1): AddPIDFilter(0x14) priority 2
2008-03-12 05:10:03.643 PIDInfo(1): Opening filter for pid 0x14
2008-03-12 05:10:03.834 DTVSM(0): Time Offset: -3.82307
2008-03-12 05:10:04.826 DTVSM(0): Time Offset: -3.82182
2008-03-12 05:10:05.360 EITHelper: Added 1 events
2008-03-12 05:10:05.814 DTVSM(0): Time Offset: -3.82032
2008-03-12 05:10:06.806 DTVSM(0): Time Offset: -3.81882
2008-03-12 05:10:07.846 DTVSM(0): Time Offset: -3.82032
2008-03-12 05:10:08.834 DTVSM(0): Time Offset: -3.82132
2008-03-12 05:10:09.822 DTVSM(0): Time Offset: -3.82207
2008-03-12 05:10:10.169 EITHelper: Added 2 events
2008-03-12 05:10:10.574 EITHelper: Added 1 events
2008-03-12 05:10:10.810 DTVSM(0): Time Offset: -3.82257
2008-03-12 05:10:11.802 DTVSM(0): Time Offset: -3.81982
2008-03-12 05:10:12.842 DTVSM(0): Time Offset: -3.82007
2008-03-12 05:10:13.830 DTVSM(0): Time Offset: -3.82007
2008-03-12 05:10:14.818 DTVSM(0): Time Offset: -3.81982
2008-03-12 05:10:15.810 DTVSM(0): Time Offset: -3.81932
2008-03-12 05:10:16.798 DTVSM(0): Time Offset: -3.81857
2008-03-12 05:10:17.838 DTVSM(0): Time Offset: -3.82057
2008-03-12 05:10:18.584 EITHelper: Added 2 events
2008-03-12 05:10:18.826 DTVSM(0): Time Offset: -3.82231
2008-03-12 05:10:18.991 EITHelper: Added 1 events
2008-03-12 05:10:19.818 DTVSM(0): Time Offset: -3.82132
2008-03-12 05:10:20.810 DTVSM(0): Time Offset: -3.82032
2008-03-12 05:10:21.802 DTVSM(0): Time Offset: -3.81957
2008-03-12 05:10:22.794 AutoExpire: Adding Programs to 'Do Not Expire' List
2008-03-12 05:10:22.798     1005 @ 2008-03-12T05:10:00 in use by 
recorder on jawa
2008-03-12 05:10:22.799 AutoExpire: ExpireLiveTV(10000)
2008-03-12 05:10:22.799 AutoExpire: FillDBOrdered: Adding Short LiveTV 
programs in starttime order
2008-03-12 05:10:22.800 AutoExpire: SendDeleteMessages. Nothing to expire.
2008-03-12 05:10:22.847 DTVSM(0): Time Offset: -3.82218
2008-03-12 05:10:23.842 DTVSM(0): Time Offset: -3.82193
2008-03-12 05:10:24.830 DTVSM(0): Time Offset: -3.82168
2008-03-12 05:10:25.818 DTVSM(0): Time Offset: -3.82143
2008-03-12 05:10:26.806 DTVSM(0): Time Offset: -3.82119
2008-03-12 05:10:27.005 EITHelper: Added 2 events
2008-03-12 05:10:27.411 EITHelper: Added 1 events
2008-03-12 05:10:27.816 EITHelper: Added 1 events
2008-03-12 05:10:27.846 DTVSM(0): Time Offset: -3.82393
2008-03-12 05:10:28.838 DTVSM(0): Time Offset: -3.82368
2008-03-12 05:10:29.826 DTVSM(0): Time Offset: -3.82343
2008-03-12 05:10:30.009 TVRec(4): Deleting stale pending recording 3 
'Neighbours'
2008-03-12 05:10:30.866 DTVSM(0): Time Offset: -3.82643
2008-03-12 05:10:31.806 DTVSM(0): Time Offset: -3.82618
2008-03-12 05:10:32.794 DTVSM(0): Time Offset: -3.82593
2008-03-12 05:10:33.676 JobQueue: Currently set to run up to 1 job(s) max.
2008-03-12 05:10:33.679 JobQueue: GetJobsInQueue: findJobs search 
bitmask 4, found 9 total jobs
2008-03-12 05:10:33.679 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311051000 in Finished state.
2008-03-12 05:10:33.680 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1003 @ 20080311092500 in Finished state.
2008-03-12 05:10:33.680 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1071 @ 20080311103000 in Finished state.
2008-03-12 05:10:33.681 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1019 @ 20080311113000 in Finished state.
2008-03-12 05:10:33.681 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1006 @ 20080311143500 in Finished state.
2008-03-12 05:10:33.682 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311180000 in Finished state.
2008-03-12 05:10:33.682 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1011 @ 20080311210000 in Finished state.
2008-03-12 05:10:33.683 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1020 @ 20080311223000 in Finished state.
2008-03-12 05:10:33.683 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1001 @ 20080312012500 in Finished state.
2008-03-12 05:10:33.834 DTVSM(0): Time Offset: -3.82568
2008-03-12 05:10:34.826 DTVSM(0): Time Offset: -3.82568
2008-03-12 05:10:35.423 EITHelper: Added 1 events
2008-03-12 05:10:35.818 DTVSM(0): Time Offset: -3.82568
2008-03-12 05:10:35.831 EITHelper: Added 1 events
2008-03-12 05:10:36.810 DTVSM(0): Time Offset: -3.82568
2008-03-12 05:10:37.802 DTVSM(0): Time Offset: -3.82568
2008-03-12 05:10:38.842 DTVSM(0): Time Offset: -3.82532
2008-03-12 05:10:39.830 DTVSM(0): Time Offset: -3.82457
2008-03-12 05:10:40.818 DTVSM(0): Time Offset: -3.76132
2008-03-12 05:10:41.806 DTVSM(0): Time Offset: -3.76058
2008-03-12 05:10:42.846 DTVSM(0): Time Offset: -3.70056
2008-03-12 05:10:43.834 DTVSM(0): Time Offset: -3.63731
2008-03-12 05:10:43.840 EITHelper: Added 1 events
2008-03-12 05:10:44.247 EITHelper: Added 1 events
2008-03-12 05:10:44.822 DTVSM(0): Time Offset: -3.57381
2008-03-12 05:10:45.818 DTVSM(0): Time Offset: -3.51081
2008-03-12 05:10:46.806 DTVSM(0): Time Offset: -3.44456
2008-03-12 05:10:47.846 DTVSM(0): Time Offset: -3.38456
2008-03-12 05:10:48.834 DTVSM(0): Time Offset: -3.32456
2008-03-12 05:10:49.822 DTVSM(0): Time Offset: -3.26131
2008-03-12 05:10:50.810 DTVSM(0): Time Offset: -3.19781
2008-03-12 05:10:51.798 DTVSM(0): Time Offset: -3.13406
2008-03-12 05:10:52.653 EITHelper: Added 1 events
2008-03-12 05:10:52.842 DTVSM(0): Time Offset: -3.07356
2008-03-12 05:10:53.058 EITHelper: Added 1 events
2008-03-12 05:10:53.834 DTVSM(0): Time Offset: -3.01306
2008-03-12 05:10:54.826 DTVSM(0): Time Offset: -2.94956
2008-03-12 05:10:55.814 DTVSM(0): Time Offset: -2.88606
2008-03-12 05:10:56.802 DTVSM(0): Time Offset: -2.88506
2008-03-12 05:10:57.842 DTVSM(0): Time Offset: -2.82481
2008-03-12 05:10:58.830 DTVSM(0): Time Offset: -2.82381
2008-03-12 05:10:59.818 DTVSM(0): Time Offset: -2.82281
2008-03-12 05:11:00.806 DTVSM(0): Time Offset: -2.82182
2008-03-12 05:11:01.080 EITHelper: Added 4 events
2008-03-12 05:11:01.491 EITHelper: Added 2 events
2008-03-12 05:11:01.846 DTVSM(0): Time Offset: -2.82357
2008-03-12 05:11:01.896 EITHelper: Added 1 events
2008-03-12 05:11:02.838 DTVSM(0): Time Offset: -2.82557
2008-03-12 05:11:03.502 EITHelper: Added 1 events
2008-03-12 05:11:03.830 DTVSM(0): Time Offset: -2.82457
2008-03-12 05:11:04.822 DTVSM(0): Time Offset: -2.82382
2008-03-12 05:11:05.810 DTVSM(0): Time Offset: -2.82307
2008-03-12 05:11:06.798 DTVSM(0): Time Offset: -2.82232
2008-03-12 05:11:07.838 DTVSM(0): Time Offset: -2.82482
2008-03-12 05:11:08.826 DTVSM(0): Time Offset: -2.82382
2008-03-12 05:11:09.520 EITHelper: Added 3 events
2008-03-12 05:11:09.814 DTVSM(0): Time Offset: -2.82257
2008-03-12 05:11:09.927 EITHelper: Added 1 events
2008-03-12 05:11:10.335 EITHelper: Added 1 events
2008-03-12 05:11:10.806 DTVSM(0): Time Offset: -2.82132
2008-03-12 05:11:11.850 DTVSM(0): Time Offset: -2.82357
2008-03-12 05:11:12.838 DTVSM(0): Time Offset: -2.82582
2008-03-12 05:11:13.826 DTVSM(0): Time Offset: -2.82482
2008-03-12 05:11:14.818 DTVSM(0): Time Offset: -2.82406
2008-03-12 05:11:15.806 DTVSM(0): Time Offset: -2.82332
2008-03-12 05:11:16.846 DTVSM(0): Time Offset: -2.82581
2008-03-12 05:11:17.834 DTVSM(0): Time Offset: -2.82507
2008-03-12 05:11:17.946 EITHelper: Added 2 events
2008-03-12 05:11:18.355 EITHelper: Added 1 events
2008-03-12 05:11:18.759 EITHelper: Added 1 events
2008-03-12 05:11:18.822 DTVSM(0): Time Offset: -2.82407
2008-03-12 05:11:19.814 DTVSM(0): Time Offset: -2.82307
2008-03-12 05:11:20.364 EITHelper: Added 1 events
2008-03-12 05:11:20.854 DTVSM(0): Time Offset: -2.82506
2008-03-12 05:11:21.846 DTVSM(0): Time Offset: -2.82731
2008-03-12 05:11:22.802 AutoExpire: Adding Programs to 'Do Not Expire' List
2008-03-12 05:11:22.806     1005 @ 2008-03-12T05:10:00 in use by 
recorder on jawa
2008-03-12 05:11:22.834 DTVSM(0): Time Offset: -2.82956
2008-03-12 05:11:23.830 DTVSM(0): Time Offset: -2.82906
2008-03-12 05:11:24.818 DTVSM(0): Time Offset: -2.82856
2008-03-12 05:11:25.806 DTVSM(0): Time Offset: -2.82806
2008-03-12 05:11:26.376 EITHelper: Added 2 events
2008-03-12 05:11:26.783 EITHelper: Added 1 events
2008-03-12 05:11:26.846 DTVSM(0): Time Offset: -2.83056
2008-03-12 05:11:27.188 EITHelper: Added 1 events
2008-03-12 05:11:27.838 DTVSM(0): Time Offset: -2.82981
2008-03-12 05:11:28.830 DTVSM(0): Time Offset: -2.82931
2008-03-12 05:11:29.822 DTVSM(0): Time Offset: -2.82906
2008-03-12 05:11:30.810 DTVSM(0): Time Offset: -2.82856
2008-03-12 05:11:31.850 DTVSM(0): Time Offset: -2.83131
2008-03-12 05:11:32.842 DTVSM(0): Time Offset: -2.83106
2008-03-12 05:11:33.684 JobQueue: Currently set to run up to 1 job(s) max.
2008-03-12 05:11:33.686 JobQueue: GetJobsInQueue: findJobs search 
bitmask 4, found 9 total jobs
2008-03-12 05:11:33.687 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311051000 in Finished state.
2008-03-12 05:11:33.687 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1003 @ 20080311092500 in Finished state.
2008-03-12 05:11:33.688 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1071 @ 20080311103000 in Finished state.
2008-03-12 05:11:33.688 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1019 @ 20080311113000 in Finished state.
2008-03-12 05:11:33.689 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1006 @ 20080311143500 in Finished state.
2008-03-12 05:11:33.689 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1005 @ 20080311180000 in Finished state.
2008-03-12 05:11:33.690 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1011 @ 20080311210000 in Finished state.
2008-03-12 05:11:33.690 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1020 @ 20080311223000 in Finished state.
2008-03-12 05:11:33.691 JobQueue: GetJobsInQueue: Ignore 'Flag 
Commercials' Job for 1001 @ 20080312012500 in Finished state.
2008-03-12 05:11:33.834 DTVSM(0): Time Offset: -2.83106
2008-03-12 05:11:34.826 DTVSM(0): Time Offset: -2.83131
2008-03-12 05:11:35.814 DTVSM(0): Time Offset: -2.83131
2008-03-12 05:11:36.802 DTVSM(0): Time Offset: -2.82806
2008-03-12 05:11:37.842 DTVSM(0): Time Offset: -2.82781
2008-03-12 05:11:38.830 DTVSM(0): Time Offset: -2.82756
2008-03-12 05:11:39.818 DTVSM(0): Time Offset: -2.82681
2008-03-12 05:11:40.806 DTVSM(0): Time Offset: -2.82607
2008-03-12 05:11:41.846 DTVSM(0): Time Offset: -2.82856
2008-03-12 05:11:42.834 DTVSM(0): Time Offset: -2.82781
2008-03-12 05:11:43.822 DTVSM(0): Time Offset: -2.82681
2008-03-12 05:11:44.810 DTVSM(0): Time Offset: -2.82556
2008-03-12 05:11:45.798 DTVSM(0): Time Offset: -2.82406
2008-03-12 05:11:46.838 DTVSM(0): Time Offset: -2.82581
2008-03-12 05:11:47.826 DTVSM(0): Time Offset: -2.82431
2008-03-12 05:11:48.814 DTVSM(0): Time Offset: -2.82256
2008-03-12 05:11:49.802 DTVSM(0): Time Offset: -2.82057
2008-03-12 05:11:50.842 DTVSM(0): Time Offset: -2.82156
2008-03-12 05:11:51.830 DTVSM(0): Time Offset: -2.82256
2008-03-12 05:11:52.818 DTVSM(0): Time Offset: -2.82356
2008-03-12 05:11:53.810 DTVSM(0): Time Offset: -2.82156
2008-03-12 05:11:54.850 DTVSM(0): Time Offset: -2.82281
2008-03-12 05:11:55.838 DTVSM(0): Time Offset: -2.82405
2008-03-12 05:11:56.826 DTVSM(0): Time Offset: -2.8253
2008-03-12 05:11:57.814 DTVSM(0): Time Offset: -2.82331
2008-03-12 05:11:58.802 DTVSM(0): Time Offset: -2.82131
2008-03-12 05:11:59.842 DTVSM(0): Time Offset: -2.82256
2008-03-12 05:12:00.830 DTVSM(0): Time Offset: -2.82381
2008-03-12 05:12:01.818 DTVSM(0): Time Offset: -2.82506
2008-03-12 05:12:02.806 DTVSM(0): Time Offset: -2.82307
2008-03-12 05:12:03.850 DTVSM(0): Time Offset: -2.82457
2008-03-12 05:12:04.838 DTVSM(0): Time Offset: -2.82606
2008-03-12 05:12:05.826 DTVSM(0): Time Offset: -2.82756
2008-03-12 05:12:06.814 DTVSM(0): Time Offset: -2.82581
2008-03-12 05:12:07.854 DTVSM(0): Time Offset: -2.82731
2008-03-12 05:12:08.842 DTVSM(0): Time Offset: -2.82881
2008-03-12 05:12:09.830 DTVSM(0): Time Offset: -2.83006
2008-03-12 05:12:10.818 DTVSM(0): Time Offset: -2.82806
2008-03-12 05:12:11.806 DTVSM(0): Time Offset: -2.82607
2008-03-12 05:12:12.846 DTVSM(0): Time Offset: -2.82732
2008-03-12 05:12:13.834 DTVSM(0): Time Offset: -2.82856
2008-03-12 05:12:14.822 DTVSM(0): Time Offset: -2.82981
2008-03-12 05:12:15.810 DTVSM(0): Time Offset: -2.82781
2008-03-12 05:12:16.798 DTVSM(0): Time Offset: -2.82581
2008-03-12 05:12:17.838 DTVSM(0): Time Offset: -2.82706
2008-03-12 05:12:18.826 DTVSM(0): Time Offset: -2.82831
2008-03-12 05:12:19.814 DTVSM(0): Time Offset: -2.82606
2008-03-12 05:12:19.998 EITScanner (1): Added 135 EIT Events
2008-03-12 05:12:20.002 MythEvent: RESCHEDULE_RECORDINGS -1
2008-03-12 05:12:20.003 Reschedule requested for id -1.
2008-03-12 05:12:20.006 EITCache: Wrote 1 modified entries of 358 for 
channel 1005 to database.
Query 0: /record.recordid = :NR0RECID AND program.manualid = 0 AND ( 
program.title = 'Little Britain'
AND program.previouslyshown = 0
AND channel.callsign LIKE 'BBC %'  )
2008-03-12 05:12:20.034 EITCache: Wrote 36 modified entries of 223 for 
channel 1028 to database.
Query 1: /record.recordid = :NR1RECID AND program.manualid = 0 AND ( 
program.title = 'In The Night Garden'
AND program.previouslyshown = 0
AND channel.callsign = 'CBeebies'
AND program.generic = 0  )
2008-03-12 05:12:20.065 EITCache: Wrote 2 modified entries of 52 for 
channel 1712 to database.
Query 2: /record.recordid = :NR2RECID AND program.manualid = 0 AND ( 
program.title = 'Quantum Leap'
AND program.previouslyshown = 0  )
2008-03-12 05:12:20.081 TVRec(1): SetChannel(18) -- beginQuery
3: /record.recordid = :NR3RECID AND program.manualid = 0 AND ( 
program.title = 'Top Gear'
AND program.previouslyshown = 0
AND channel.callsign LIKE 'BBC %'  )
2008-03-12 05:12:20.090 TVRec(1): ClearFlags(RingBufferReady,) -> 
RunMainLoop,SignalMonitorRunning,EITScannerRunning,Query 4
: /record.search = :NRST AND program.manualid = 0 AND program.title = 
record.title
2008-03-12 05:12:20.102 TVRec(1): Request: Program(no) channel(18) 
input() flags(EITScan,)
2008-03-12 05:12:20.106  |-- Start DB Query 0...
2008-03-12 05:12:20.150 TVRec(1): TeardownSignalMonitor() -- begin
2008-03-12 05:12:20.159  |-- -1 results in 0.000831 sec.
2008-03-12 05:12:20.166 DVBSM(0): Stop() -- begin
2008-03-12 05:12:20.170  |-- Start DB Query 1...
2008-03-12 05:12:20.178 SM(0)::Stop: begin
2008-03-12 05:12:20.183  |-- -1 results in 0.000885 sec.
2008-03-12 05:12:20.194  |-- Start DB Query 2...
2008-03-12 05:12:20.195  |-- -1 results in 0.00066 sec.
2008-03-12 05:12:20.196  |-- Start DB Query 3...
2008-03-12 05:12:20.197  |-- -1 results in 0.000781 sec.
2008-03-12 05:12:20.198  |-- Start DB Query 4...
2008-03-12 05:12:20.201  |-- -1 results in 0.002858 sec.
2008-03-12 05:12:20.202  +-- Done.
2008-03-12 05:12:20.202 BuildWorkList...
2008-03-12 05:12:20.203 AddNewRecords...
2008-03-12 05:12:20.209  |-- Start DB Query...
2008-03-12 05:12:20.239 SM(0)::Stop: end
2008-03-12 05:12:20.244  |-- 420 results in 0.032679 sec. Processing...
2008-03-12 05:12:20.247 DVBSH(0): RemoveListener(0x8329728) -- begin
2008-03-12 05:12:20.254 DVBSH(0): RemoveListener(0x8329728) -- locked
2008-03-12 05:12:20.285 DVBSH(0): RunTS(): shutdown
2008-03-12 05:12:20.286 DVBSH(0): RemovePIDFilter(0x0)
2008-03-12 05:12:20.286 PIDInfo(0): Closing filter for pid 0x0
2008-03-12 05:12:20.287 DVBSH(0): RemovePIDFilter(0x10)
2008-03-12 05:12:20.287 PIDInfo(0): Closing filter for pid 0x10
2008-03-12 05:12:20.288 DVBSH(0): RemovePIDFilter(0x11)
2008-03-12 05:12:20.288 PIDInfo(0): Closing filter for pid 0x11
2008-03-12 05:12:20.289 DVBSH(0): RemovePIDFilter(0x12)
2008-03-12 05:12:20.289 PIDInfo(0): Closing filter for pid 0x12
2008-03-12 05:12:20.290 DVBSH(0): RemovePIDFilter(0x14)
2008-03-12 05:12:20.290 PIDInfo(0): Closing filter for pid 0x14
2008-03-12 05:12:20.290 DVBSH(0): RemovePIDFilter(0x1f5)
2008-03-12 05:12:20.291 PIDInfo(0): Closing filter for pid 0x1f5
2008-03-12 05:12:20.291 DVBSH(0): RemovePIDFilter(0x1f6)
2008-03-12 05:12:20.292 PIDInfo(0): Closing filter for pid 0x1f6
2008-03-12 05:12:20.292 DVBSH(0): RemovePIDFilter(0x1f7)
2008-03-12 05:12:20.293 PIDInfo(0): Closing filter for pid 0x1f7
2008-03-12 05:12:20.293 DVBSH(0): RemovePIDFilter(0x1f8)
2008-03-12 05:12:20.293 PIDInfo(0): Closing filter for pid 0x1f8
2008-03-12 05:12:20.294 DVBSH(0): RemovePIDFilter(0x2c1)
2008-03-12 05:12:20.294 PIDInfo(0): Closing filter for pid 0x2c1
2008-03-12 05:12:20.296  +-- Cleanup...
2008-03-12 05:12:20.296 DVBSH(0): RunTS(): end
2008-03-12 05:12:20.298 AddNotListed...
2008-03-12 05:12:20.310 DVBSH(0): RemoveListener(0x8329728) -- end
2008-03-12 05:12:20.318  |-- Start DB Query...
2008-03-12 05:12:20.322 DVBSM(0): Stop() -- end
2008-03-12 05:12:20.327  |-- 0 results in 0.000637 sec. Processing...
2008-03-12 05:12:20.334 DVBSM(0): Stop() -- begin
2008-03-12 05:12:20.342 Sort by time...
2008-03-12 05:12:20.346 SM(0)::Stop: begin
2008-03-12 05:12:20.354 PruneOverlaps...
2008-03-12 05:12:20.358 SM(0)::Stop: end
2008-03-12 05:12:20.364 Sort by priority...
2008-03-12 05:12:20.366 DVBSM(0): Stop() -- end
2008-03-12 05:12:20.374 BuildListMaps...
2008-03-12 05:12:20.378 TVRec(1): TeardownSignalMonitor() -- end
2008-03-12 05:12:20.378 DVBSM(0): Stop() -- begin
2008-03-12 05:12:20.386 SchedNewRecords...
2008-03-12 05:12:20.390 TVRec(1): ClearFlags(SignalMonitorRunning,) -> 
RunMainLoop,EITScannerRunning,
2008-03-12 05:12:20.398 SM(0)::Stop: begin