Recordings don't stop, end up 40+gb! (1 Viewer)

[HappyTalk]

startTime = endTime for the bad recording

It happened again the other day, when recording 2 programs one after the other on the same channel where the 1st prog "QuestionTime" (22:32-23:38) failed to stop, but the 2nd program "ThisWeek" (23:32-00:23) started/stopped correctly. Both recordings are repeat recordings in the schedule table. I was watching the first recording (time lapsed) as it was recording.

I looked in the database at the 'Recording' table and startTime = endTime for the bad recording! so somehow the entry is being altered in the recording table (or wrongly generated in first place)!. Looking at tv.bak however it seems to indicate that it does get added properly and does try at 23:38 to stop it. There are no entries in the tswriter.log re stopping the recording though! After it ends the second recording, it then detects an orphaned recording (the earlier one that should have closed 45 mins before) and keeps trying to close it, to no avail. A dirty quickfix here would be when it generates this message if there are no other (non-orphaned) recordings to forcibly restart the tv service which would drop it.

There is clearly a logic flaw here, which I would have thought not to hard to spot.


recording table entry.

124	1	06/11/2008 22:32:12	06/11/2008 22:32:12	Question Time	David Dimbley chairs a political debate from Tottenham in London. The panel includes Ed Miliband, Nigel Farage, Pauline Neville Jones and Brian Eno. [S]	news/current affairs (general)	E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts	0	01/01/2000 00:00:00	4	1	3945

schedule table entries

84	1	3	Question Time	30/10/2008 22:35:00	30/10/2008 23:35:00	2147483647	0		73	0	01/01/2000 00:00:00	3	3	01/01/2000 00:00:00	-1
87	1	3	This Week	30/10/2008 23:35:00	31/10/2008 00:20:00	2147483647	0		73	0	01/01/2000 00:00:00	3	3	01/01/2000 00:00:00	-1

Here's the critical log parts
TSWriter.Log

06-11-2008 22:32:09.875 analyzer: reset
06-11-2008 22:32:09.875 cagrabber: reset
06-11-2008 22:32:09.875 epg: reset
06-11-2008 22:32:09.875 epg:ResetEPG()
06-11-2008 22:32:09.875 mhw reset
06-11-2008 22:32:10.328 CMpTsFilter::Pause()
06-11-2008 22:32:11.15 CMpTsFilter::Run()
06-11-2008 22:32:11.15 pmtgrabber: set callback:d6f238
06-11-2008 22:32:11.15 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 22:32:11.46 service_id=4163
06-11-2008 22:32:11.46 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 22:32:11.46 pmtgrabber: got pmt version:21 -1
06-11-2008 22:32:11.46 pmtgrabber: do calback
06-11-2008 22:32:11.46 analyzer: set video pid:258
06-11-2008 22:32:11.46 analyzer: reset
06-11-2008 22:32:11.46 analyzer: set audio pid:259
06-11-2008 22:32:11.46 analyzer: reset
06-11-2008 22:32:12.109 Recorder: RECORD    transport stream mode
06-11-2008 22:32:12.109 Recorder: RECORD    set filename:E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts
06-11-2008 22:32:12.109 Recorder: RECORD    Received from TvService: pmt pid:0x1043 serviceId: 0x1043 pmtlength:139
06-11-2008 22:32:12.109 Recorder: RECORD    Old pids cleared
06-11-2008 22:32:12.109 Recorder: RECORD    got pmt - tableid: 0x2 section_length: 139 sid: 0x1043
06-11-2008 22:32:12.109 Recorder: RECORD    PMT parsed  - Pid 0x1043 ServiceId 0x1043 stream count: 10
06-11-2008 22:32:12.109 Recorder: RECORD    pcr pid:0x258
06-11-2008 22:32:12.109 Recorder: RECORD    SetPcrPid clear old PIDs
06-11-2008 22:32:12.109 Recorder: RECORD    add video stream pid: 0x258 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 12
06-11-2008 22:32:12.109 Recorder: RECORD    add audio stream pid: 0x259 fake pid: 0x40 stream type: 0x3 logical type: 0x3 descriptor length: 9
06-11-2008 22:32:12.109 Recorder: RECORD    add audio stream pid: 0x25a fake pid: 0x41 stream type: 0x3 logical type: 0x3 descriptor length: 9
06-11-2008 22:32:12.109 Recorder: RECORD    add subtitle stream pid: 0x25b fake pid: 0x50 stream type: 0x6 logical type: 0x6 descriptor length: 13
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x3ed stream type: 0xb logical type: 0xb descriptor length: 18
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x3ee stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x3ef stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x28a stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x28b stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 22:32:12.109 Recorder: RECORD    stream rejected - pid: 0x28c stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 22:32:12.109 Recorder: RECORD    Start 'E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts'
06-11-2008 22:32:12.140 Recorder: RECORD    start of audio detected
06-11-2008 22:32:12.234 Recorder: RECORD    start of video detected
06-11-2008 22:32:12.265 Recorder: RECORD    clear TS packet queue
06-11-2008 22:32:12.265 Recorder: RECORD    Info : Next broadcaster program clock reference rollover : 0 days 00:04:35 525
06-11-2008 22:36:47.828 Recorder: RECORD    Info : Normal broadcaster program clock reference rollover passed !
06-11-2008 22:38:03.968 Recorder: RECORD    Stop 'E:\tv\8 Out of 10 Cats - Channel 4\8 Out of 10 Cats -  - 2008-11-06 - 22_00.ts'
06-11-2008 22:38:03.968 Recorder: RECORD    Reset
06-11-2008 22:38:03.968 del m_pVideoAnalyzer
06-11-2008 22:38:03.968 analyzer: reset
06-11-2008 22:38:03.968 del m_pPmtGrabber
06-11-2008 22:38:03.968 del m_pRecorder
06-11-2008 22:38:03.968 del m_pTimeShifting
06-11-2008 22:38:03.968 del m_pTeletextGrabber
06-11-2008 22:38:03.968 del m_pCaGrabber
06-11-2008 22:38:03.968 del done...
06-11-2008 22:38:03.968 CMpTsFilter::Pause()
06-11-2008 22:38:03.968 CMpTsFilter::Stop()
06-11-2008 23:15:19.390 Recorder:Pid 258 Continuity error... 5 ( prev f )
06-11-2008 23:32:11.906 analyzer: reset
06-11-2008 23:32:11.906 cagrabber: reset
06-11-2008 23:32:11.906 epg: reset
06-11-2008 23:32:11.906 epg:ResetEPG()
06-11-2008 23:32:11.906 mhw reset
06-11-2008 23:32:12.156 pmtgrabber: set callback:d6f1f8
06-11-2008 23:32:12.156 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:32:12.328 service_id=4163
06-11-2008 23:32:12.328 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:32:12.328 pmtgrabber: got pmt version:22 -1
06-11-2008 23:32:12.328 pmtgrabber: do calback
06-11-2008 23:32:12.328 analyzer: set video pid:258
06-11-2008 23:32:12.328 analyzer: reset
06-11-2008 23:32:12.328 analyzer: set audio pid:259
06-11-2008 23:32:12.328 analyzer: reset
06-11-2008 23:32:28.890 analyzer: reset
06-11-2008 23:32:28.890 cagrabber: reset
06-11-2008 23:32:28.890 epg: reset
06-11-2008 23:32:28.890 epg:ResetEPG()
06-11-2008 23:32:28.890 mhw reset
06-11-2008 23:32:29.140 pmtgrabber: set callback:d6f1b8
06-11-2008 23:32:29.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:32:29.281 service_id=4163
06-11-2008 23:32:29.281 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:32:29.281 pmtgrabber: got pmt version:22 -1
06-11-2008 23:32:29.281 pmtgrabber: do calback
06-11-2008 23:32:29.296 analyzer: set video pid:258
06-11-2008 23:32:29.296 analyzer: reset
06-11-2008 23:32:29.296 analyzer: set audio pid:259
06-11-2008 23:32:29.296 analyzer: reset
06-11-2008 23:32:45.890 analyzer: reset
06-11-2008 23:32:45.890 cagrabber: reset
06-11-2008 23:32:45.890 epg: reset
06-11-2008 23:32:45.890 epg:ResetEPG()
06-11-2008 23:32:45.890 mhw reset
06-11-2008 23:32:46.140 pmtgrabber: set callback:d6f178
06-11-2008 23:32:46.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:32:46.312 service_id=4163
06-11-2008 23:32:46.312 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:32:46.312 pmtgrabber: got pmt version:22 -1
06-11-2008 23:32:46.312 pmtgrabber: do calback
06-11-2008 23:32:46.328 analyzer: set video pid:258
06-11-2008 23:32:46.328 analyzer: reset
06-11-2008 23:32:46.328 analyzer: set audio pid:259
06-11-2008 23:32:46.328 analyzer: reset
06-11-2008 23:33:02.875 analyzer: reset
06-11-2008 23:33:02.875 cagrabber: reset
06-11-2008 23:33:02.890 epg: reset
06-11-2008 23:33:02.890 epg:ResetEPG()
06-11-2008 23:33:02.890 mhw reset
06-11-2008 23:33:03.140 pmtgrabber: set callback:d6f138
06-11-2008 23:33:03.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:33:03.156 service_id=4163
06-11-2008 23:33:03.156 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:33:03.156 pmtgrabber: got pmt version:22 -1
06-11-2008 23:33:03.156 pmtgrabber: do calback
06-11-2008 23:33:03.171 analyzer: set video pid:258
06-11-2008 23:33:03.171 analyzer: reset
06-11-2008 23:33:03.171 analyzer: set audio pid:259
06-11-2008 23:33:03.171 analyzer: reset
06-11-2008 23:33:19.890 analyzer: reset
06-11-2008 23:33:19.890 cagrabber: reset
06-11-2008 23:33:19.890 epg: reset
06-11-2008 23:33:19.890 epg:ResetEPG()
06-11-2008 23:33:19.890 mhw reset
06-11-2008 23:33:20.140 pmtgrabber: set callback:d6f0f8
06-11-2008 23:33:20.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:33:20.203 service_id=4163
06-11-2008 23:33:20.203 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:33:20.203 pmtgrabber: got pmt version:22 -1
06-11-2008 23:33:20.203 pmtgrabber: do calback
06-11-2008 23:33:20.218 analyzer: set video pid:258
06-11-2008 23:33:20.218 analyzer: reset
06-11-2008 23:33:20.218 analyzer: set audio pid:259
06-11-2008 23:33:20.218 analyzer: reset
06-11-2008 23:33:36.890 analyzer: reset
06-11-2008 23:33:36.890 cagrabber: reset
06-11-2008 23:33:36.890 epg: reset
06-11-2008 23:33:36.890 epg:ResetEPG()
06-11-2008 23:33:36.890 mhw reset
06-11-2008 23:33:37.140 pmtgrabber: set callback:d6f0b8
06-11-2008 23:33:37.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:33:37.218 service_id=4163
06-11-2008 23:33:37.218 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:33:37.218 pmtgrabber: got pmt version:22 -1
06-11-2008 23:33:37.218 pmtgrabber: do calback
06-11-2008 23:33:37.218 analyzer: set video pid:258
06-11-2008 23:33:37.234 analyzer: reset
06-11-2008 23:33:37.234 analyzer: set audio pid:259
06-11-2008 23:33:37.234 analyzer: reset
06-11-2008 23:33:53.906 analyzer: reset
06-11-2008 23:33:53.906 cagrabber: reset
06-11-2008 23:33:53.906 epg: reset
06-11-2008 23:33:53.906 epg:ResetEPG()
06-11-2008 23:33:53.906 mhw reset
06-11-2008 23:33:54.156 pmtgrabber: set callback:d6f078
06-11-2008 23:33:54.156 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:33:54.250 service_id=4163
06-11-2008 23:33:54.250 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:33:54.250 pmtgrabber: got pmt version:22 -1
06-11-2008 23:33:54.250 pmtgrabber: do calback
06-11-2008 23:33:54.250 analyzer: set video pid:258
06-11-2008 23:33:54.250 analyzer: reset
06-11-2008 23:33:54.265 analyzer: set audio pid:259
06-11-2008 23:33:54.265 analyzer: reset
06-11-2008 23:34:10.890 analyzer: reset
06-11-2008 23:34:10.890 cagrabber: reset
06-11-2008 23:34:10.890 epg: reset
06-11-2008 23:34:10.890 epg:ResetEPG()
06-11-2008 23:34:10.890 mhw reset
06-11-2008 23:34:11.140 pmtgrabber: set callback:d6f038
06-11-2008 23:34:11.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:34:11.265 service_id=4163
06-11-2008 23:34:11.265 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:34:11.265 pmtgrabber: got pmt version:22 -1
06-11-2008 23:34:11.265 pmtgrabber: do calback
06-11-2008 23:34:11.281 analyzer: set video pid:258
06-11-2008 23:34:11.281 analyzer: reset
06-11-2008 23:34:11.281 analyzer: set audio pid:259
06-11-2008 23:34:11.281 analyzer: reset
06-11-2008 23:34:27.890 analyzer: reset
06-11-2008 23:34:27.890 cagrabber: reset
06-11-2008 23:34:27.890 epg: reset
06-11-2008 23:34:27.890 epg:ResetEPG()
06-11-2008 23:34:27.890 mhw reset
06-11-2008 23:34:28.140 pmtgrabber: set callback:d6eff8
06-11-2008 23:34:28.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:34:28.312 service_id=4163
06-11-2008 23:34:28.312 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:34:28.312 pmtgrabber: got pmt version:22 -1
06-11-2008 23:34:28.312 pmtgrabber: do calback
06-11-2008 23:34:28.312 analyzer: set video pid:258
06-11-2008 23:34:28.312 analyzer: reset
06-11-2008 23:34:28.312 analyzer: set audio pid:259
06-11-2008 23:34:28.312 analyzer: reset
06-11-2008 23:34:44.875 analyzer: reset
06-11-2008 23:34:44.875 cagrabber: reset
06-11-2008 23:34:44.875 epg: reset
06-11-2008 23:34:44.875 epg:ResetEPG()
06-11-2008 23:34:44.875 mhw reset
06-11-2008 23:34:45.140 pmtgrabber: set callback:d6efb8
06-11-2008 23:34:45.140 pmtgrabber: grab pmt:1043 sid:1043
06-11-2008 23:34:45.140 service_id=4163
06-11-2008 23:34:45.140 pmtgrabber: got pmt 1043 sid:1043
06-11-2008 23:34:45.140 pmtgrabber: got pmt version:22 -1
06-11-2008 23:34:45.140 pmtgrabber: do calback
06-11-2008 23:34:45.156 analyzer: set video pid:258
06-11-2008 23:34:45.156 analyzer: reset
06-11-2008 23:34:45.156 analyzer: set audio pid:259
06-11-2008 23:34:45.156 analyzer: reset
06-11-2008 23:34:46.187 Recorder: RECORD    transport stream mode
06-11-2008 23:34:46.187 Recorder: RECORD    set filename:E:\tv\This Week - BBC ONE - 2008-11-06.ts
06-11-2008 23:34:46.187 Recorder: RECORD    Received from TvService: pmt pid:0x1043 serviceId: 0x1043 pmtlength:139
06-11-2008 23:34:46.187 Recorder: RECORD    Old pids cleared
06-11-2008 23:34:46.187 Recorder: RECORD    got pmt - tableid: 0x2 section_length: 139 sid: 0x1043
06-11-2008 23:34:46.187 Recorder: RECORD    PMT parsed  - Pid 0x1043 ServiceId 0x1043 stream count: 10
06-11-2008 23:34:46.187 Recorder: RECORD    pcr pid:0x258
06-11-2008 23:34:46.187 Recorder: RECORD    SetPcrPid clear old PIDs
06-11-2008 23:34:46.187 Recorder: RECORD    add video stream pid: 0x258 fake pid: 0x30 stream type: 0x2 logical type: 0x2 descriptor length: 12
06-11-2008 23:34:46.187 Recorder: RECORD    add audio stream pid: 0x259 fake pid: 0x40 stream type: 0x3 logical type: 0x3 descriptor length: 9
06-11-2008 23:34:46.187 Recorder: RECORD    add audio stream pid: 0x25a fake pid: 0x41 stream type: 0x3 logical type: 0x3 descriptor length: 9
06-11-2008 23:34:46.187 Recorder: RECORD    add subtitle stream pid: 0x25b fake pid: 0x50 stream type: 0x6 logical type: 0x6 descriptor length: 13
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x3ed stream type: 0xb logical type: 0xb descriptor length: 18
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x3ee stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x3ef stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x28a stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x28b stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 23:34:46.187 Recorder: RECORD    stream rejected - pid: 0x28c stream type: 0xb logical type: 0xb descriptor length: 3
06-11-2008 23:34:46.187 Recorder: RECORD    Start 'E:\tv\This Week - BBC ONE - 2008-11-06.ts'
06-11-2008 23:34:46.234 Recorder: RECORD    start of video detected
06-11-2008 23:34:46.250 Recorder: RECORD    clear TS packet queue
06-11-2008 23:34:46.250 Recorder: RECORD    Info : Next broadcaster program clock reference rollover : 1 days 01:32:45 473
06-11-2008 23:34:46.312 Recorder: RECORD    start of audio detected
07-11-2008 00:06:33.562 Recorder:Pid 258 Continuity error... a ( prev 5 )
07-11-2008 00:06:33.562 Recorder:Pid 258 Continuity error... a ( prev 5 )
07-11-2008 00:06:33.562 Recorder:Pid 259 Continuity error... a ( prev 8 )
07-11-2008 00:06:33.562 Recorder:Pid 259 Continuity error... a ( prev 8 )
07-11-2008 00:23:02.109 Recorder: RECORD    Stop 'E:\tv\This Week - BBC ONE - 2008-11-06.ts'
07-11-2008 00:23:02.109 Recorder: RECORD    Reset
07-11-2008 00:23:02.109 del m_pVideoAnalyzer
07-11-2008 00:23:02.109 analyzer: reset
07-11-2008 00:23:02.109 del m_pPmtGrabber
07-11-2008 00:23:02.109 del m_pRecorder
07-11-2008 00:23:02.109 del m_pTimeShifting
07-11-2008 00:23:02.109 del m_pTeletextGrabber
07-11-2008 00:23:02.109 del m_pCaGrabber
07-11-2008 00:23:02.109 del done...

tv.bak

2008-11-06 22:32:09.859375 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 22:32:09-06/11/2008 23:35:00 Question Time
2008-11-06 22:32:09.859375 [10]: Controller: find free card for channel BBC ONE
2008-11-06 22:32:09.859375 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 22:32:09.859375 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 22:32:09.859375 [10]: Controller:    card:1 type:DvbT is tuned to different transponder
2008-11-06 22:32:09.859375 [10]: Controller:    card:1 type:DvbT is available priority:-97 #users:1 same transponder:False
2008-11-06 22:32:09.859375 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:0 same transponder:False
2008-11-06 22:32:09.859375 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 22:32:09.859375 [10]: Controller: found 2 available
2008-11-06 22:32:09.859375 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 22:32:09.859375 [10]: Scheduler : record, first tune to channel
2008-11-06 22:32:09.859375 [17]: card: Tune 2 to BBC ONE
2008-11-06 22:32:09.859375 [17]: card: user: scheduler117:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 22:32:09.875000 [17]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Assigning oldChannel
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Current channel is null
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Graph is tunning
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Getting default locator
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Putting bandwidth 8
2008-11-06 22:32:09.875000 [17]: dvbt: tune: put_ONID 9018
2008-11-06 22:32:09.875000 [17]: dvbt: tune: put_SID 4163
2008-11-06 22:32:09.875000 [17]: dvbt: tune: put_TSID 4099
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Carrier frequency 834000
2008-11-06 22:32:09.875000 [17]: dvbt: tune: put_Locator
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 22:32:09.875000 [17]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 22:32:09.875000 [17]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 22:32:09.875000 [17]: dvb:Getting new subchannel
2008-11-06 22:32:09.875000 [17]: dvb:GetNewSubChannel:0 #0
2008-11-06 22:32:09.875000 [17]: dvb:Submit tunerequest size:1 new:0
2008-11-06 22:32:09.875000 [17]: subch:0 OnBeforeTune
2008-11-06 22:32:09.875000 [17]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 22:32:10.328125 [17]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 22:32:10.328125 [17]: subch:0 OnAfterTune
2008-11-06 22:32:10.328125 [17]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 22:32:10.328125 [17]: dvbt: tune: SubChannel 0
2008-11-06 22:32:10.328125 [17]: subch:0 OnGraphStart
2008-11-06 22:32:10.328125 [17]: subch0:RunGraph
2008-11-06 22:32:10.328125 [17]: dvb:  RunGraph
2008-11-06 22:32:11.015625 [17]: subch:0 OnGraphStarted
2008-11-06 22:32:11.015625 [17]: subch:0 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 22:32:11.015625 [17]: subch:0 set pmt grabber pmt:1043 sid:1043
2008-11-06 22:32:11.015625 [17]: subch:0 wait for pmt:1043
2008-11-06 22:32:11.031250 [17]: subch:0 wait for pmt:1043
2008-11-06 22:32:11.046875 [11]: subch:0 OnPMTReceived() True
2008-11-06 22:32:11.046875 [11]: subch:0 SendPmt:1043 1043 FFFFFFFF 15
2008-11-06 22:32:11.046875 [11]: Decode pmt
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 6
2008-11-06 22:32:11.046875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 6
2008-11-06 22:32:11.046875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 10
2008-11-06 22:32:11.046875 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: Set descriptor data with length 3
2008-11-06 22:32:11.046875 [11]: subch:0 SendPMT version:21 len:139 4163
2008-11-06 22:32:11.046875 [11]: subch:0 cam flags:True
2008-11-06 22:32:11.046875 [11]: subch:0 SetMpegPidMapping
2008-11-06 22:32:11.046875 [11]: subch:0  pid:0 pcr
2008-11-06 22:32:11.046875 [11]: subch:0  pid:1043 pmt
2008-11-06 22:32:11.046875 [11]: subch:0  pid:258 video type:MPEG-2
2008-11-06 22:32:11.046875 [11]: subch:0    map pid:258 video type:MPEG-2
2008-11-06 22:32:11.046875 [11]: subch:0  pid:259 audio lang:eng type:MPEG-1
2008-11-06 22:32:11.046875 [11]: subch:0    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 22:32:11.046875 [11]: subch:0  pid:25A audio lang:eng type:MPEG-1
2008-11-06 22:32:11.046875 [11]: subch:0  pid:25B subtitle type:6
2008-11-06 22:32:11.046875 [11]: subch:0  pid:3ED type:B
2008-11-06 22:32:11.046875 [11]: subch:0  pid:3EE type:B
2008-11-06 22:32:11.046875 [11]: subch:0  pid:3EF type:B
2008-11-06 22:32:11.046875 [11]: subch:0  pid:28A type:B
2008-11-06 22:32:11.046875 [11]: subch:0  pid:28B type:B
2008-11-06 22:32:11.046875 [11]: subch:0  pid:28C type:B
2008-11-06 22:32:11.046875 [11]: subch:0 stop tif
2008-11-06 22:32:11.062500 [17]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 22:32:11.062500 [17]: card: tuned user: scheduler117 subchannel: 0
2008-11-06 22:32:11.062500 [17]: user:scheduler117 add
2008-11-06 22:32:12.078125 [17]: card: Tuner locked: True
2008-11-06 22:32:12.078125 [17]: **************************************************
2008-11-06 22:32:12.078125 [17]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 22:32:12.078125 [17]: **************************************************
2008-11-06 22:32:12.109375 [10]: Scheduler : record to E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.mpg
2008-11-06 22:32:12.109375 [17]: card: StartRecording 2 E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts
2008-11-06 22:32:12.109375 [17]: StartRecording to E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts
2008-11-06 22:32:12.109375 [17]: subch:0 StartRecord(E:\tv\Question Time - BBC ONE\Question Time -  - 2008-11-06 - 22_35.ts)
2008-11-06 22:32:12.109375 [17]: subch:0 record transport stream mode
2008-11-06 22:32:12.109375 [17]: subch:0-0 tswriter StartRecording...
2008-11-06 22:32:12.109375 [17]: SetRecorderPids
2008-11-06 22:32:12.109375 [17]: DVB subch:0 Started recording
2008-11-06 22:32:12.109375 [10]: Scheduler: recList: count: 2 add scheduleid: 117 card: Hauppauge WinTV 88x DVB-T Tuner/Demod
2008-11-06 22:38:03.875000 [8]: diskmanagement: recording 8 Out of 10 Cats ended. type:Once max episodes:2147483647
2008-11-06 22:38:03.890625 [8]: ConflictManager: Updating conflicts list
2008-11-06 22:38:03.921875 [8]: Schedules List built 31.25 ms
2008-11-06 22:38:03.968750 [8]: ConflictManager: Update done within 78.125 ms
2008-11-06 22:38:03.968750 [8]: Scheduler: stop record Channel 4 06/11/2008 21:57:14-06/11/2008 22:35:00 8 Out of 10 Cats
2008-11-06 22:38:03.968750 [12]: card: StopRecording 1
2008-11-06 22:38:03.968750 [12]: card: StopRecording for card:1
2008-11-06 22:38:03.968750 [12]: subch:0-0 tswriter StopRecording...
2008-11-06 22:38:03.968750 [12]: tvcard:FreeSubChannel:1 #0
2008-11-06 22:38:03.968750 [12]: DVB subch:0 Decompose()
2008-11-06 22:38:03.968750 [12]: FreeSubChannel CA: freeing sub channel : 0
2008-11-06 22:38:03.968750 [12]: tvcard:FreeAllSubChannels:
2008-11-06 22:38:03.968750 [12]: dvb:StopGraph
2008-11-06 22:38:03.968750 [12]: card: remove user:scheduler116 sub:0
2008-11-06 22:38:03.968750 [12]: user:scheduler116 remove
2008-11-06 22:38:03.968750 [12]: card: free subchannel sub:0
2008-11-06 22:38:03.968750 [12]: tvcard:FreeSubChannel:0 #0
2008-11-06 22:38:03.968750 [12]: tvcard:FreeSubChannel :0 - sub channel not found
2008-11-06 22:38:03.968750 [12]: tvcard:FreeAllSubChannels:
2008-11-06 22:38:03.968750 [12]: tvcard:FreeAllSubChannels:
2008-11-06 22:41:08.031250 [PowerEventThread]: TV service PowerEventThread 537
2008-11-06 22:41:08.031250 [PowerEventThread]: TV service PowerEventThread 537
2008-11-06 22:42:00.828125 [PowerEventThread]: TV service PowerEventThread 537
2008-11-06 22:42:00.828125 [PowerEventThread]: TV service PowerEventThread 537
2008-11-06 22:42:01.312500 [PowerEventThread]: TV service PowerEventThread 537
2008-11-06 23:32:11.859375 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:32:11-07/11/2008 00:20:00 This Week
2008-11-06 23:32:11.890625 [10]: Controller: find free card for channel BBC ONE
2008-11-06 23:32:11.890625 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:32:11.890625 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:32:11.890625 [10]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:32:11.890625 [10]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:32:11.890625 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:32:11.890625 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:32:11.890625 [10]: Controller: found 2 available
2008-11-06 23:32:11.890625 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 23:32:11.890625 [10]: Scheduler : record, first tune to channel
2008-11-06 23:32:11.906250 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:32:11.906250 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:11.906250 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Getting default locator
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:32:11.906250 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:32:11.906250 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:32:11.906250 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:32:11.906250 [18]: dvbt: tune: put_Locator
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:11.906250 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:32:11.906250 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:32:11.906250 [18]: dvb:Getting new subchannel
2008-11-06 23:32:11.906250 [18]: dvb:GetNewSubChannel:1 #1
2008-11-06 23:32:11.906250 [18]: dvb:Submit tunerequest size:2 new:1
2008-11-06 23:32:11.906250 [18]: subch:1 OnBeforeTune
2008-11-06 23:32:11.906250 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:32:12.156250 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:32:12.156250 [18]: subch:1 OnAfterTune
2008-11-06 23:32:12.156250 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:12.156250 [18]: dvbt: tune: SubChannel 1
2008-11-06 23:32:12.156250 [18]: subch:1 OnGraphStart
2008-11-06 23:32:12.156250 [18]: subch:1 RunGraph: already running
2008-11-06 23:32:12.156250 [18]: subch:1 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:32:12.156250 [18]: subch:1 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:32:12.156250 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.187500 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.203125 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.218750 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.250000 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.328125 [11]: subch:1 OnPMTReceived() True
2008-11-06 23:32:12.328125 [11]: subch:1 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:32:12.328125 [11]: Decode pmt
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 6
2008-11-06 23:32:12.328125 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 6
2008-11-06 23:32:12.328125 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 10
2008-11-06 23:32:12.328125 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:12.328125 [11]: subch:1 SendPMT version:22 len:139 4163
2008-11-06 23:32:12.328125 [11]: subch:1 cam flags:True
2008-11-06 23:32:12.328125 [11]: subch:1 SetMpegPidMapping
2008-11-06 23:32:12.328125 [11]: subch:1  pid:0 pcr
2008-11-06 23:32:12.328125 [18]: subch:1 wait for pmt 1043
2008-11-06 23:32:12.328125 [11]: subch:1  pid:1043 pmt
2008-11-06 23:32:12.328125 [11]: subch:1  pid:258 video type:MPEG-2
2008-11-06 23:32:12.328125 [11]: subch:1    map pid:258 video type:MPEG-2
2008-11-06 23:32:12.328125 [11]: subch:1  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:12.328125 [11]: subch:1    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:12.328125 [11]: subch:1  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:32:12.328125 [11]: subch:1  pid:25B subtitle type:6
2008-11-06 23:32:12.328125 [11]: subch:1  pid:3ED type:B
2008-11-06 23:32:12.328125 [11]: subch:1  pid:3EE type:B
2008-11-06 23:32:12.328125 [11]: subch:1  pid:3EF type:B
2008-11-06 23:32:12.328125 [11]: subch:1  pid:28A type:B
2008-11-06 23:32:12.328125 [11]: subch:1  pid:28B type:B
2008-11-06 23:32:12.328125 [11]: subch:1  pid:28C type:B
2008-11-06 23:32:12.328125 [11]: subch:1 stop tif
2008-11-06 23:32:12.343750 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:12.343750 [18]: card: tuned user: scheduler118 subchannel: 1
2008-11-06 23:32:12.359375 [18]: user:scheduler118 add
2008-11-06 23:32:13.375000 [18]: card: Tuner locked: True
2008-11-06 23:32:13.375000 [18]: **************************************************
2008-11-06 23:32:13.375000 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:32:13.375000 [18]: **************************************************
2008-11-06 23:32:13.375000 [10]: Scheduler : record to E:\tv\This Week - BBC ONE\This Week -  - 2008-11-06 - 23_35.mpg
2008-11-06 23:32:28.875000 [8]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:32:28.875000 [8]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:32:28-07/11/2008 00:20:00 This Week
2008-11-06 23:32:28.875000 [8]: Controller: find free card for channel BBC ONE
2008-11-06 23:32:28.875000 [8]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:32:28.875000 [8]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:32:28.875000 [8]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:32:28.875000 [8]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:32:28.875000 [8]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:32:28.875000 [8]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:32:28.875000 [8]: Controller: found 2 available
2008-11-06 23:32:28.875000 [8]: Scheduler : record on free card:2 priority:2
2008-11-06 23:32:28.875000 [8]: Scheduler : record, first tune to channel
2008-11-06 23:32:28.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:32:28.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:28.890625 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Getting default locator
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:32:28.890625 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:32:28.890625 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:32:28.890625 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:32:28.890625 [18]: dvbt: tune: put_Locator
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:28.890625 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:32:28.890625 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:32:28.890625 [18]: dvb:Getting new subchannel
2008-11-06 23:32:28.890625 [18]: dvb:GetNewSubChannel:2 #2
2008-11-06 23:32:28.890625 [18]: dvb:Submit tunerequest size:3 new:2
2008-11-06 23:32:28.890625 [18]: subch:2 OnBeforeTune
2008-11-06 23:32:28.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:32:29.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:32:29.140625 [18]: subch:2 OnAfterTune
2008-11-06 23:32:29.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:29.140625 [18]: dvbt: tune: SubChannel 2
2008-11-06 23:32:29.140625 [18]: subch:2 OnGraphStart
2008-11-06 23:32:29.140625 [18]: subch:2 RunGraph: already running
2008-11-06 23:32:29.140625 [18]: subch:2 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:32:29.140625 [18]: subch:2 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:32:29.140625 [18]: subch:2 wait for pmt 1043
2008-11-06 23:32:29.156250 [18]: subch:2 wait for pmt 1043
2008-11-06 23:32:29.187500 [18]: subch:2 wait for pmt 1043
2008-11-06 23:32:29.203125 [18]: subch:2 wait for pmt 1043
2008-11-06 23:32:29.218750 [18]: subch:2 wait for pmt 1043
2008-11-06 23:32:29.281250 [11]: subch:2 OnPMTReceived() True
2008-11-06 23:32:29.281250 [11]: subch:2 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:32:29.281250 [11]: Decode pmt
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 6
2008-11-06 23:32:29.296875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 6
2008-11-06 23:32:29.296875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 10
2008-11-06 23:32:29.296875 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: Set descriptor data with length 3
2008-11-06 23:32:29.296875 [11]: subch:2 SendPMT version:22 len:139 4163
2008-11-06 23:32:29.296875 [11]: subch:2 cam flags:True
2008-11-06 23:32:29.296875 [11]: subch:2 SetMpegPidMapping
2008-11-06 23:32:29.296875 [11]: subch:2  pid:0 pcr
2008-11-06 23:32:29.296875 [11]: subch:2  pid:1043 pmt
2008-11-06 23:32:29.296875 [11]: subch:2  pid:258 video type:MPEG-2
2008-11-06 23:32:29.296875 [11]: subch:2    map pid:258 video type:MPEG-2
2008-11-06 23:32:29.296875 [11]: subch:2  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:29.296875 [11]: subch:2    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:29.296875 [11]: subch:2  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:32:29.296875 [11]: subch:2  pid:25B subtitle type:6
2008-11-06 23:32:29.296875 [11]: subch:2  pid:3ED type:B
2008-11-06 23:32:29.296875 [11]: subch:2  pid:3EE type:B
2008-11-06 23:32:29.296875 [11]: subch:2  pid:3EF type:B
2008-11-06 23:32:29.296875 [11]: subch:2  pid:28A type:B
2008-11-06 23:32:29.296875 [11]: subch:2  pid:28B type:B
2008-11-06 23:32:29.296875 [11]: subch:2  pid:28C type:B
2008-11-06 23:32:29.296875 [11]: subch:2 stop tif
2008-11-06 23:32:29.312500 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:29.312500 [18]: card: tuned user: scheduler118 subchannel: 2
2008-11-06 23:32:29.312500 [18]: user:scheduler118 add
2008-11-06 23:32:30.328125 [18]: card: Tuner locked: True
2008-11-06 23:32:30.328125 [18]: **************************************************
2008-11-06 23:32:30.328125 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:32:30.328125 [18]: **************************************************
2008-11-06 23:32:30.328125 [8]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:32:45.875000 [10]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:32:45.875000 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:32:45-07/11/2008 00:20:00 This Week
2008-11-06 23:32:45.875000 [10]: Controller: find free card for channel BBC ONE
2008-11-06 23:32:45.875000 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:32:45.875000 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:32:45.875000 [10]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:32:45.875000 [10]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:32:45.875000 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:32:45.875000 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:32:45.875000 [10]: Controller: found 2 available
2008-11-06 23:32:45.875000 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 23:32:45.875000 [10]: Scheduler : record, first tune to channel
2008-11-06 23:32:45.890625 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:32:45.890625 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:45.890625 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Getting default locator
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:32:45.890625 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:32:45.890625 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:32:45.890625 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:32:45.890625 [18]: dvbt: tune: put_Locator
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:32:45.890625 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:32:45.890625 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:32:45.890625 [18]: dvb:Getting new subchannel
2008-11-06 23:32:45.890625 [18]: dvb:GetNewSubChannel:3 #3
2008-11-06 23:32:45.890625 [18]: dvb:Submit tunerequest size:4 new:3
2008-11-06 23:32:45.890625 [18]: subch:3 OnBeforeTune
2008-11-06 23:32:45.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:32:46.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:32:46.140625 [18]: subch:3 OnAfterTune
2008-11-06 23:32:46.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:46.140625 [18]: dvbt: tune: SubChannel 3
2008-11-06 23:32:46.140625 [18]: subch:3 OnGraphStart
2008-11-06 23:32:46.140625 [18]: subch:3 RunGraph: already running
2008-11-06 23:32:46.140625 [18]: subch:3 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:32:46.140625 [18]: subch:3 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:32:46.156250 [18]: subch:3 wait for pmt 1043
2008-11-06 23:32:46.171875 [18]: subch:3 wait for pmt 1043
2008-11-06 23:32:46.187500 [18]: subch:3 wait for pmt 1043
2008-11-06 23:32:46.203125 [18]: subch:3 wait for pmt 1043
2008-11-06 23:32:46.234375 [18]: subch:3 wait for pmt 1043
2008-11-06 23:32:46.312500 [11]: subch:3 OnPMTReceived() True
2008-11-06 23:32:46.312500 [11]: subch:3 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:32:46.312500 [11]: Decode pmt
2008-11-06 23:32:46.312500 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.312500 [11]: Set descriptor data with length 6
2008-11-06 23:32:46.312500 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 6
2008-11-06 23:32:46.328125 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 10
2008-11-06 23:32:46.328125 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.328125 [11]: Set descriptor data with length 3
2008-11-06 23:32:46.328125 [11]: subch:3 SendPMT version:22 len:139 4163
2008-11-06 23:32:46.328125 [11]: subch:3 cam flags:True
2008-11-06 23:32:46.328125 [11]: subch:3 SetMpegPidMapping
2008-11-06 23:32:46.328125 [11]: subch:3  pid:0 pcr
2008-11-06 23:32:46.328125 [11]: subch:3  pid:1043 pmt
2008-11-06 23:32:46.328125 [11]: subch:3  pid:258 video type:MPEG-2
2008-11-06 23:32:46.328125 [11]: subch:3    map pid:258 video type:MPEG-2
2008-11-06 23:32:46.328125 [11]: subch:3  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:46.328125 [11]: subch:3    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:32:46.328125 [11]: subch:3  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:32:46.328125 [11]: subch:3  pid:25B subtitle type:6
2008-11-06 23:32:46.328125 [11]: subch:3  pid:3ED type:B
2008-11-06 23:32:46.328125 [11]: subch:3  pid:3EE type:B
2008-11-06 23:32:46.328125 [11]: subch:3  pid:3EF type:B
2008-11-06 23:32:46.328125 [11]: subch:3  pid:28A type:B
2008-11-06 23:32:46.328125 [11]: subch:3  pid:28B type:B
2008-11-06 23:32:46.328125 [11]: subch:3  pid:28C type:B
2008-11-06 23:32:46.328125 [11]: subch:3 stop tif
2008-11-06 23:32:46.328125 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:32:46.328125 [18]: card: tuned user: scheduler118 subchannel: 3
2008-11-06 23:32:46.328125 [18]: user:scheduler118 add
2008-11-06 23:32:47.359375 [18]: card: Tuner locked: True
2008-11-06 23:32:47.359375 [18]: **************************************************
2008-11-06 23:32:47.359375 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:32:47.359375 [18]: **************************************************
2008-11-06 23:32:47.359375 [10]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:33:02.859375 [15]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:33:02.859375 [15]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:33:02-07/11/2008 00:20:00 This Week
2008-11-06 23:33:02.859375 [15]: Controller: find free card for channel BBC ONE
2008-11-06 23:33:02.859375 [15]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:33:02.859375 [15]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:33:02.875000 [15]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:33:02.875000 [15]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:33:02.875000 [15]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:33:02.875000 [15]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:33:02.875000 [15]: Controller: found 2 available
2008-11-06 23:33:02.875000 [15]: Scheduler : record on free card:2 priority:2
2008-11-06 23:33:02.875000 [15]: Scheduler : record, first tune to channel
2008-11-06 23:33:02.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:33:02.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:02.875000 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Getting default locator
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:33:02.875000 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:33:02.875000 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:33:02.875000 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:33:02.875000 [18]: dvbt: tune: put_Locator
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:02.875000 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:33:02.875000 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:33:02.875000 [18]: dvb:Getting new subchannel
2008-11-06 23:33:02.875000 [18]: dvb:GetNewSubChannel:4 #4
2008-11-06 23:33:02.875000 [18]: dvb:Submit tunerequest size:5 new:4
2008-11-06 23:33:02.875000 [18]: subch:4 OnBeforeTune
2008-11-06 23:33:02.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:33:03.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:33:03.140625 [18]: subch:4 OnAfterTune
2008-11-06 23:33:03.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:03.140625 [18]: dvbt: tune: SubChannel 4
2008-11-06 23:33:03.140625 [18]: subch:4 OnGraphStart
2008-11-06 23:33:03.140625 [18]: subch:4 RunGraph: already running
2008-11-06 23:33:03.140625 [18]: subch:4 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:33:03.140625 [18]: subch:4 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:33:03.140625 [18]: subch:4 wait for pmt 1043
2008-11-06 23:33:03.156250 [18]: subch:4 wait for pmt 1043
2008-11-06 23:33:03.156250 [11]: subch:4 OnPMTReceived() True
2008-11-06 23:33:03.156250 [11]: subch:4 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:33:03.156250 [11]: Decode pmt
2008-11-06 23:33:03.156250 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 6
2008-11-06 23:33:03.171875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 6
2008-11-06 23:33:03.171875 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 10
2008-11-06 23:33:03.171875 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: Set descriptor data with length 3
2008-11-06 23:33:03.171875 [11]: subch:4 SendPMT version:22 len:139 4163
2008-11-06 23:33:03.171875 [11]: subch:4 cam flags:True
2008-11-06 23:33:03.171875 [11]: subch:4 SetMpegPidMapping
2008-11-06 23:33:03.171875 [11]: subch:4  pid:0 pcr
2008-11-06 23:33:03.171875 [11]: subch:4  pid:1043 pmt
2008-11-06 23:33:03.171875 [11]: subch:4  pid:258 video type:MPEG-2
2008-11-06 23:33:03.171875 [11]: subch:4    map pid:258 video type:MPEG-2
2008-11-06 23:33:03.171875 [11]: subch:4  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:03.171875 [11]: subch:4    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:03.171875 [11]: subch:4  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:33:03.171875 [11]: subch:4  pid:25B subtitle type:6
2008-11-06 23:33:03.171875 [11]: subch:4  pid:3ED type:B
2008-11-06 23:33:03.171875 [11]: subch:4  pid:3EE type:B
2008-11-06 23:33:03.171875 [11]: subch:4  pid:3EF type:B
2008-11-06 23:33:03.171875 [11]: subch:4  pid:28A type:B
2008-11-06 23:33:03.171875 [11]: subch:4  pid:28B type:B
2008-11-06 23:33:03.171875 [11]: subch:4  pid:28C type:B
2008-11-06 23:33:03.171875 [11]: subch:4 stop tif
2008-11-06 23:33:03.171875 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:03.171875 [18]: card: tuned user: scheduler118 subchannel: 4
2008-11-06 23:33:03.171875 [18]: user:scheduler118 add
2008-11-06 23:33:04.203125 [18]: card: Tuner locked: True
2008-11-06 23:33:04.203125 [18]: **************************************************
2008-11-06 23:33:04.203125 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:33:04.203125 [18]: **************************************************
2008-11-06 23:33:04.203125 [15]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:33:19.875000 [10]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:33:19.875000 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:33:19-07/11/2008 00:20:00 This Week
2008-11-06 23:33:19.875000 [10]: Controller: find free card for channel BBC ONE
2008-11-06 23:33:19.875000 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:33:19.875000 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:33:19.875000 [10]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:33:19.875000 [10]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:33:19.875000 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:33:19.875000 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:33:19.875000 [10]: Controller: found 2 available
2008-11-06 23:33:19.875000 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 23:33:19.875000 [10]: Scheduler : record, first tune to channel
2008-11-06 23:33:19.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:33:19.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:19.875000 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:19.875000 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:33:19.875000 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:33:19.875000 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:33:19.890625 [18]: dvbt: tune: Getting default locator
2008-11-06 23:33:19.890625 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:33:19.890625 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:33:19.890625 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:33:19.890625 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:33:19.890625 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:33:19.890625 [18]: dvbt: tune: put_Locator
2008-11-06 23:33:19.890625 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:19.890625 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:33:19.890625 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:33:19.890625 [18]: dvb:Getting new subchannel
2008-11-06 23:33:19.890625 [18]: dvb:GetNewSubChannel:5 #5
2008-11-06 23:33:19.890625 [18]: dvb:Submit tunerequest size:6 new:5
2008-11-06 23:33:19.890625 [18]: subch:5 OnBeforeTune
2008-11-06 23:33:19.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:33:20.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:33:20.140625 [18]: subch:5 OnAfterTune
2008-11-06 23:33:20.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:20.140625 [18]: dvbt: tune: SubChannel 5
2008-11-06 23:33:20.140625 [18]: subch:5 OnGraphStart
2008-11-06 23:33:20.140625 [18]: subch:5 RunGraph: already running
2008-11-06 23:33:20.140625 [18]: subch:5 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:33:20.140625 [18]: subch:5 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:33:20.140625 [18]: subch:5 wait for pmt 1043
2008-11-06 23:33:20.156250 [18]: subch:5 wait for pmt 1043
2008-11-06 23:33:20.187500 [18]: subch:5 wait for pmt 1043
2008-11-06 23:33:20.203125 [18]: subch:5 wait for pmt 1043
2008-11-06 23:33:20.203125 [11]: subch:5 OnPMTReceived() True
2008-11-06 23:33:20.203125 [11]: subch:5 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:33:20.203125 [11]: Decode pmt
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 6
2008-11-06 23:33:20.203125 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 6
2008-11-06 23:33:20.203125 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 10
2008-11-06 23:33:20.203125 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: Set descriptor data with length 3
2008-11-06 23:33:20.203125 [11]: subch:5 SendPMT version:22 len:139 4163
2008-11-06 23:33:20.203125 [11]: subch:5 cam flags:True
2008-11-06 23:33:20.203125 [11]: subch:5 SetMpegPidMapping
2008-11-06 23:33:20.218750 [11]: subch:5  pid:0 pcr
2008-11-06 23:33:20.218750 [11]: subch:5  pid:1043 pmt
2008-11-06 23:33:20.218750 [11]: subch:5  pid:258 video type:MPEG-2
2008-11-06 23:33:20.218750 [11]: subch:5    map pid:258 video type:MPEG-2
2008-11-06 23:33:20.218750 [11]: subch:5  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:20.218750 [11]: subch:5    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:20.218750 [11]: subch:5  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:33:20.218750 [11]: subch:5  pid:25B subtitle type:6
2008-11-06 23:33:20.218750 [11]: subch:5  pid:3ED type:B
2008-11-06 23:33:20.218750 [11]: subch:5  pid:3EE type:B
2008-11-06 23:33:20.218750 [11]: subch:5  pid:3EF type:B
2008-11-06 23:33:20.218750 [11]: subch:5  pid:28A type:B
2008-11-06 23:33:20.218750 [11]: subch:5  pid:28B type:B
2008-11-06 23:33:20.218750 [11]: subch:5  pid:28C type:B
2008-11-06 23:33:20.218750 [11]: subch:5 stop tif
2008-11-06 23:33:20.218750 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:20.218750 [18]: card: tuned user: scheduler118 subchannel: 5
2008-11-06 23:33:20.218750 [18]: user:scheduler118 add
2008-11-06 23:33:21.250000 [18]: card: Tuner locked: True
2008-11-06 23:33:21.250000 [18]: **************************************************
2008-11-06 23:33:21.250000 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:33:21.250000 [18]: **************************************************
2008-11-06 23:33:21.250000 [10]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:33:36.875000 [10]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:33:36.875000 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:33:36-07/11/2008 00:20:00 This Week
2008-11-06 23:33:36.875000 [10]: Controller: find free card for channel BBC ONE
2008-11-06 23:33:36.875000 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:33:36.875000 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:33:36.875000 [10]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:33:36.875000 [10]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:33:36.875000 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:33:36.875000 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:33:36.875000 [10]: Controller: found 2 available
2008-11-06 23:33:36.875000 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 23:33:36.875000 [10]: Scheduler : record, first tune to channel
2008-11-06 23:33:36.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:33:36.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:36.890625 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Getting default locator
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:33:36.890625 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:33:36.890625 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:33:36.890625 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:33:36.890625 [18]: dvbt: tune: put_Locator
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:36.890625 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:33:36.890625 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:33:36.890625 [18]: dvb:Getting new subchannel
2008-11-06 23:33:36.890625 [18]: dvb:GetNewSubChannel:6 #6
2008-11-06 23:33:36.890625 [18]: dvb:Submit tunerequest size:7 new:6
2008-11-06 23:33:36.890625 [18]: subch:6 OnBeforeTune
2008-11-06 23:33:36.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:33:37.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:33:37.140625 [18]: subch:6 OnAfterTune
2008-11-06 23:33:37.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:37.140625 [18]: dvbt: tune: SubChannel 6
2008-11-06 23:33:37.140625 [18]: subch:6 OnGraphStart
2008-11-06 23:33:37.140625 [18]: subch:6 RunGraph: already running
2008-11-06 23:33:37.140625 [18]: subch:6 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:33:37.140625 [18]: subch:6 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:33:37.140625 [18]: subch:6 wait for pmt 1043
2008-11-06 23:33:37.171875 [18]: subch:6 wait for pmt 1043
2008-11-06 23:33:37.187500 [18]: subch:6 wait for pmt 1043
2008-11-06 23:33:37.203125 [18]: subch:6 wait for pmt 1043
2008-11-06 23:33:37.218750 [11]: subch:6 OnPMTReceived() True
2008-11-06 23:33:37.218750 [11]: subch:6 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:33:37.218750 [11]: Decode pmt
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 6
2008-11-06 23:33:37.218750 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 6
2008-11-06 23:33:37.218750 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 10
2008-11-06 23:33:37.218750 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: Set descriptor data with length 3
2008-11-06 23:33:37.218750 [11]: subch:6 SendPMT version:22 len:139 4163
2008-11-06 23:33:37.218750 [11]: subch:6 cam flags:True
2008-11-06 23:33:37.218750 [11]: subch:6 SetMpegPidMapping
2008-11-06 23:33:37.218750 [11]: subch:6  pid:0 pcr
2008-11-06 23:33:37.218750 [11]: subch:6  pid:1043 pmt
2008-11-06 23:33:37.218750 [11]: subch:6  pid:258 video type:MPEG-2
2008-11-06 23:33:37.218750 [11]: subch:6    map pid:258 video type:MPEG-2
2008-11-06 23:33:37.234375 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:37.234375 [18]: card: tuned user: scheduler118 subchannel: 6
2008-11-06 23:33:37.234375 [18]: user:scheduler118 add
2008-11-06 23:33:37.234375 [11]: subch:6  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:37.234375 [11]: subch:6    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:37.234375 [11]: subch:6  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:33:37.234375 [11]: subch:6  pid:25B subtitle type:6
2008-11-06 23:33:37.234375 [11]: subch:6  pid:3ED type:B
2008-11-06 23:33:37.234375 [11]: subch:6  pid:3EE type:B
2008-11-06 23:33:37.234375 [11]: subch:6  pid:3EF type:B
2008-11-06 23:33:37.234375 [11]: subch:6  pid:28A type:B
2008-11-06 23:33:37.234375 [11]: subch:6  pid:28B type:B
2008-11-06 23:33:37.234375 [11]: subch:6  pid:28C type:B
2008-11-06 23:33:37.234375 [11]: subch:6 stop tif
2008-11-06 23:33:38.250000 [18]: card: Tuner locked: True
2008-11-06 23:33:38.250000 [18]: **************************************************
2008-11-06 23:33:38.250000 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:33:38.250000 [18]: **************************************************
2008-11-06 23:33:38.250000 [10]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:33:53.875000 [15]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:33:53.875000 [15]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:33:53-07/11/2008 00:20:00 This Week
2008-11-06 23:33:53.875000 [15]: Controller: find free card for channel BBC ONE
2008-11-06 23:33:53.875000 [15]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:33:53.875000 [15]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:33:53.890625 [15]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:33:53.890625 [15]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:33:53.890625 [15]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:33:53.890625 [15]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:33:53.890625 [15]: Controller: found 2 available
2008-11-06 23:33:53.890625 [15]: Scheduler : record on free card:2 priority:2
2008-11-06 23:33:53.890625 [15]: Scheduler : record, first tune to channel
2008-11-06 23:33:53.890625 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:33:53.890625 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:53.890625 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Getting default locator
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:33:53.890625 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:33:53.890625 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:33:53.890625 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:33:53.890625 [18]: dvbt: tune: put_Locator
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:33:53.890625 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:33:53.890625 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:33:53.906250 [18]: dvb:Getting new subchannel
2008-11-06 23:33:53.906250 [18]: dvb:GetNewSubChannel:7 #7
2008-11-06 23:33:53.906250 [18]: dvb:Submit tunerequest size:8 new:7
2008-11-06 23:33:53.906250 [18]: subch:7 OnBeforeTune
2008-11-06 23:33:53.906250 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:33:54.156250 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:33:54.156250 [18]: subch:7 OnAfterTune
2008-11-06 23:33:54.156250 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:54.156250 [18]: dvbt: tune: SubChannel 7
2008-11-06 23:33:54.156250 [18]: subch:7 OnGraphStart
2008-11-06 23:33:54.156250 [18]: subch:7 RunGraph: already running
2008-11-06 23:33:54.156250 [18]: subch:7 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:33:54.156250 [18]: subch:7 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:33:54.156250 [18]: subch:7 wait for pmt 1043
2008-11-06 23:33:54.171875 [18]: subch:7 wait for pmt 1043
2008-11-06 23:33:54.187500 [18]: subch:7 wait for pmt 1043
2008-11-06 23:33:54.218750 [18]: subch:7 wait for pmt 1043
2008-11-06 23:33:54.234375 [18]: subch:7 wait for pmt 1043
2008-11-06 23:33:54.250000 [11]: subch:7 OnPMTReceived() True
2008-11-06 23:33:54.250000 [11]: subch:7 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:33:54.250000 [11]: Decode pmt
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 6
2008-11-06 23:33:54.250000 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 6
2008-11-06 23:33:54.250000 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 10
2008-11-06 23:33:54.250000 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: Set descriptor data with length 3
2008-11-06 23:33:54.250000 [11]: subch:7 SendPMT version:22 len:139 4163
2008-11-06 23:33:54.250000 [11]: subch:7 cam flags:True
2008-11-06 23:33:54.250000 [11]: subch:7 SetMpegPidMapping
2008-11-06 23:33:54.250000 [11]: subch:7  pid:0 pcr
2008-11-06 23:33:54.250000 [11]: subch:7  pid:1043 pmt
2008-11-06 23:33:54.250000 [11]: subch:7  pid:258 video type:MPEG-2
2008-11-06 23:33:54.250000 [11]: subch:7    map pid:258 video type:MPEG-2
2008-11-06 23:33:54.250000 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:33:54.250000 [18]: card: tuned user: scheduler118 subchannel: 7
2008-11-06 23:33:54.250000 [11]: subch:7  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:54.265625 [11]: subch:7    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:33:54.265625 [18]: user:scheduler118 add
2008-11-06 23:33:54.265625 [11]: subch:7  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:33:54.265625 [11]: subch:7  pid:25B subtitle type:6
2008-11-06 23:33:54.265625 [11]: subch:7  pid:3ED type:B
2008-11-06 23:33:54.265625 [11]: subch:7  pid:3EE type:B
2008-11-06 23:33:54.265625 [11]: subch:7  pid:3EF type:B
2008-11-06 23:33:54.265625 [11]: subch:7  pid:28A type:B
2008-11-06 23:33:54.265625 [11]: subch:7  pid:28B type:B
2008-11-06 23:33:54.265625 [11]: subch:7  pid:28C type:B
2008-11-06 23:33:54.265625 [11]: subch:7 stop tif
2008-11-06 23:33:55.281250 [18]: card: Tuner locked: True
2008-11-06 23:33:55.281250 [18]: **************************************************
2008-11-06 23:33:55.281250 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:33:55.281250 [18]: **************************************************
2008-11-06 23:33:55.281250 [15]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:34:10.859375 [15]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:34:10.859375 [15]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:34:10-07/11/2008 00:20:00 This Week
2008-11-06 23:34:10.859375 [15]: Controller: find free card for channel BBC ONE
2008-11-06 23:34:10.859375 [15]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:34:10.875000 [15]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:34:10.875000 [15]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:34:10.875000 [15]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:34:10.875000 [15]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:34:10.875000 [15]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:34:10.875000 [15]: Controller: found 2 available
2008-11-06 23:34:10.875000 [15]: Scheduler : record on free card:2 priority:2
2008-11-06 23:34:10.875000 [15]: Scheduler : record, first tune to channel
2008-11-06 23:34:10.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:34:10.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:10.875000 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Getting default locator
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:34:10.875000 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:34:10.875000 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:34:10.875000 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:34:10.875000 [18]: dvbt: tune: put_Locator
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:10.875000 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:34:10.875000 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:34:10.875000 [18]: dvb:Getting new subchannel
2008-11-06 23:34:10.890625 [18]: dvb:GetNewSubChannel:8 #8
2008-11-06 23:34:10.890625 [18]: dvb:Submit tunerequest size:9 new:8
2008-11-06 23:34:10.890625 [18]: subch:8 OnBeforeTune
2008-11-06 23:34:10.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:34:11.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:34:11.140625 [18]: subch:8 OnAfterTune
2008-11-06 23:34:11.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:11.140625 [18]: dvbt: tune: SubChannel 8
2008-11-06 23:34:11.140625 [18]: subch:8 OnGraphStart
2008-11-06 23:34:11.140625 [18]: subch:8 RunGraph: already running
2008-11-06 23:34:11.140625 [18]: subch:8 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:34:11.140625 [18]: subch:8 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:34:11.140625 [18]: subch:8 wait for pmt 1043
2008-11-06 23:34:11.156250 [18]: subch:8 wait for pmt 1043
2008-11-06 23:34:11.171875 [18]: subch:8 wait for pmt 1043
2008-11-06 23:34:11.203125 [18]: subch:8 wait for pmt 1043
2008-11-06 23:34:11.218750 [18]: subch:8 wait for pmt 1043
2008-11-06 23:34:11.265625 [11]: subch:8 OnPMTReceived() True
2008-11-06 23:34:11.265625 [11]: subch:8 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:34:11.265625 [11]: Decode pmt
2008-11-06 23:34:11.265625 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.265625 [11]: Set descriptor data with length 6
2008-11-06 23:34:11.265625 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:11.265625 [11]: Set descriptor data with length 6
2008-11-06 23:34:11.265625 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:11.265625 [11]: Set descriptor data with length 10
2008-11-06 23:34:11.265625 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:34:11.281250 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.281250 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.281250 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.281250 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.281250 [11]: Set descriptor data with length 3
2008-11-06 23:34:11.281250 [11]: subch:8 SendPMT version:22 len:139 4163
2008-11-06 23:34:11.281250 [11]: subch:8 cam flags:True
2008-11-06 23:34:11.281250 [11]: subch:8 SetMpegPidMapping
2008-11-06 23:34:11.281250 [11]: subch:8  pid:0 pcr
2008-11-06 23:34:11.281250 [11]: subch:8  pid:1043 pmt
2008-11-06 23:34:11.281250 [11]: subch:8  pid:258 video type:MPEG-2
2008-11-06 23:34:11.281250 [11]: subch:8    map pid:258 video type:MPEG-2
2008-11-06 23:34:11.281250 [11]: subch:8  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:11.281250 [11]: subch:8    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:11.281250 [11]: subch:8  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:34:11.281250 [11]: subch:8  pid:25B subtitle type:6
2008-11-06 23:34:11.281250 [11]: subch:8  pid:3ED type:B
2008-11-06 23:34:11.281250 [11]: subch:8  pid:3EE type:B
2008-11-06 23:34:11.281250 [11]: subch:8  pid:3EF type:B
2008-11-06 23:34:11.281250 [11]: subch:8  pid:28A type:B
2008-11-06 23:34:11.281250 [11]: subch:8  pid:28B type:B
2008-11-06 23:34:11.281250 [11]: subch:8  pid:28C type:B
2008-11-06 23:34:11.281250 [11]: subch:8 stop tif
2008-11-06 23:34:11.281250 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:11.281250 [18]: card: tuned user: scheduler118 subchannel: 8
2008-11-06 23:34:11.281250 [18]: user:scheduler118 add
2008-11-06 23:34:12.296875 [18]: card: Tuner locked: True
2008-11-06 23:34:12.296875 [18]: **************************************************
2008-11-06 23:34:12.296875 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:34:12.296875 [18]: **************************************************
2008-11-06 23:34:12.312500 [15]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:34:27.859375 [8]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:34:27.859375 [8]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:34:27-07/11/2008 00:20:00 This Week
2008-11-06 23:34:27.859375 [8]: Controller: find free card for channel BBC ONE
2008-11-06 23:34:27.859375 [8]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:34:27.859375 [8]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:34:27.875000 [8]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:34:27.875000 [8]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:34:27.875000 [8]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:34:27.875000 [8]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:34:27.875000 [8]: Controller: found 2 available
2008-11-06 23:34:27.875000 [8]: Scheduler : record on free card:2 priority:2
2008-11-06 23:34:27.875000 [8]: Scheduler : record, first tune to channel
2008-11-06 23:34:27.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:34:27.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:27.875000 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Getting default locator
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:34:27.875000 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:34:27.875000 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:34:27.875000 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:34:27.875000 [18]: dvbt: tune: put_Locator
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:27.875000 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:34:27.875000 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:34:27.875000 [18]: dvb:Getting new subchannel
2008-11-06 23:34:27.875000 [18]: dvb:GetNewSubChannel:9 #9
2008-11-06 23:34:27.890625 [18]: dvb:Submit tunerequest size:10 new:9
2008-11-06 23:34:27.890625 [18]: subch:9 OnBeforeTune
2008-11-06 23:34:27.890625 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:34:28.140625 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:34:28.140625 [18]: subch:9 OnAfterTune
2008-11-06 23:34:28.140625 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:28.140625 [18]: dvbt: tune: SubChannel 9
2008-11-06 23:34:28.140625 [18]: subch:9 OnGraphStart
2008-11-06 23:34:28.140625 [18]: subch:9 RunGraph: already running
2008-11-06 23:34:28.140625 [18]: subch:9 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:34:28.140625 [18]: subch:9 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:34:28.140625 [18]: subch:9 wait for pmt 1043
2008-11-06 23:34:28.156250 [18]: subch:9 wait for pmt 1043
2008-11-06 23:34:28.171875 [18]: subch:9 wait for pmt 1043
2008-11-06 23:34:28.203125 [18]: subch:9 wait for pmt 1043
2008-11-06 23:34:28.218750 [18]: subch:9 wait for pmt 1043
2008-11-06 23:34:28.312500 [11]: subch:9 OnPMTReceived() True
2008-11-06 23:34:28.312500 [11]: subch:9 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:34:28.312500 [11]: Decode pmt
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 6
2008-11-06 23:34:28.312500 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 6
2008-11-06 23:34:28.312500 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 10
2008-11-06 23:34:28.312500 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: Set descriptor data with length 3
2008-11-06 23:34:28.312500 [11]: subch:9 SendPMT version:22 len:139 4163
2008-11-06 23:34:28.312500 [11]: subch:9 cam flags:True
2008-11-06 23:34:28.312500 [11]: subch:9 SetMpegPidMapping
2008-11-06 23:34:28.312500 [11]: subch:9  pid:0 pcr
2008-11-06 23:34:28.312500 [11]: subch:9  pid:1043 pmt
2008-11-06 23:34:28.312500 [11]: subch:9  pid:258 video type:MPEG-2
2008-11-06 23:34:28.312500 [11]: subch:9    map pid:258 video type:MPEG-2
2008-11-06 23:34:28.312500 [11]: subch:9  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:28.312500 [11]: subch:9    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:28.328125 [11]: subch:9  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:34:28.328125 [11]: subch:9  pid:25B subtitle type:6
2008-11-06 23:34:28.328125 [11]: subch:9  pid:3ED type:B
2008-11-06 23:34:28.328125 [11]: subch:9  pid:3EE type:B
2008-11-06 23:34:28.328125 [11]: subch:9  pid:3EF type:B
2008-11-06 23:34:28.328125 [11]: subch:9  pid:28A type:B
2008-11-06 23:34:28.328125 [11]: subch:9  pid:28B type:B
2008-11-06 23:34:28.328125 [11]: subch:9  pid:28C type:B
2008-11-06 23:34:28.328125 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:28.328125 [18]: card: tuned user: scheduler118 subchannel: 9
2008-11-06 23:34:28.328125 [18]: user:scheduler118 add
2008-11-06 23:34:28.328125 [11]: subch:9 stop tif
2008-11-06 23:34:29.343750 [18]: card: Tuner locked: True
2008-11-06 23:34:29.343750 [18]: **************************************************
2008-11-06 23:34:29.343750 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:34:29.343750 [18]: **************************************************
2008-11-06 23:34:29.343750 [8]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:34:44.859375 [10]: Recording This Week  added in _recordingsInProgressList
2008-11-06 23:34:44.859375 [10]: Scheduler : time to record TvDatabase.Channel 06/11/2008 23:34:44-07/11/2008 00:20:00 This Week
2008-11-06 23:34:44.859375 [10]: Controller: find free card for channel BBC ONE
2008-11-06 23:34:44.859375 [10]: Controller:   got 1 tuning details for BBC ONE
2008-11-06 23:34:44.859375 [10]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8 
2008-11-06 23:34:44.859375 [10]: Controller:    card:1 type:DvbT is available priority:3 #users:0 same transponder:False
2008-11-06 23:34:44.859375 [10]: Controller:    card:2 type:DvbT is tuned to same transponder no CA present
2008-11-06 23:34:44.859375 [10]: Controller:    card:2 type:DvbT is available priority:2 #users:1 same transponder:True
2008-11-06 23:34:44.875000 [10]: Controller:    card:3 type:RadioWebStream cannot tune to channel
2008-11-06 23:34:44.875000 [10]: Controller: found 2 available
2008-11-06 23:34:44.875000 [10]: Scheduler : record on free card:2 priority:2
2008-11-06 23:34:44.875000 [10]: Scheduler : record, first tune to channel
2008-11-06 23:34:44.875000 [18]: card: Tune 2 to BBC ONE
2008-11-06 23:34:44.875000 [18]: card: user: scheduler118:2:-1 tune DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:44.875000 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Assigning oldChannel
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:False LCN:1 BandWidth:8
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Graph is tunning
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Getting default locator
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Putting bandwidth 8
2008-11-06 23:34:44.875000 [18]: dvbt: tune: put_ONID 9018
2008-11-06 23:34:44.875000 [18]: dvbt: tune: put_SID 4163
2008-11-06 23:34:44.875000 [18]: dvbt: tune: put_TSID 4099
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Carrier frequency 834000
2008-11-06 23:34:44.875000 [18]: dvbt: tune: put_Locator
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Freq:834000 ONID:9018 TSID:4099 SID:4163 PMT:1043 FTA:True LCN:1 BandWidth:8
2008-11-06 23:34:44.875000 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2008-11-06 23:34:44.875000 [18]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2008-11-06 23:34:44.875000 [18]: dvb:Getting new subchannel
2008-11-06 23:34:44.875000 [18]: dvb:GetNewSubChannel:10 #10
2008-11-06 23:34:44.875000 [18]: dvb:Submit tunerequest size:11 new:10
2008-11-06 23:34:44.875000 [18]: subch:10 OnBeforeTune
2008-11-06 23:34:44.875000 [18]: dvb:Submit tunerequest calling put_TuneRequest
2008-11-06 23:34:45.125000 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2008-11-06 23:34:45.125000 [18]: subch:10 OnAfterTune
2008-11-06 23:34:45.125000 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:45.125000 [18]: dvbt: tune: SubChannel 10
2008-11-06 23:34:45.125000 [18]: subch:10 OnGraphStart
2008-11-06 23:34:45.140625 [18]: subch:10 RunGraph: already running
2008-11-06 23:34:45.140625 [18]: subch:10 SetupPmtGrabber:pid 1043 sid:1043
2008-11-06 23:34:45.140625 [18]: subch:10 set pmt grabber pmt:1043 sid:1043
2008-11-06 23:34:45.140625 [18]: subch:10 wait for pmt 1043
2008-11-06 23:34:45.140625 [11]: subch:10 OnPMTReceived() True
2008-11-06 23:34:45.140625 [11]: subch:10 SendPmt:1043 1043 FFFFFFFF 16
2008-11-06 23:34:45.140625 [11]: Decode pmt
2008-11-06 23:34:45.140625 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.140625 [11]: Set descriptor data with length 6
2008-11-06 23:34:45.140625 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 6
2008-11-06 23:34:45.156250 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 10
2008-11-06 23:34:45.156250 [11]: PROBLEM : descriptor lengths dont match 3 13
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.156250 [11]: Set descriptor data with length 3
2008-11-06 23:34:45.156250 [11]: subch:10 SendPMT version:22 len:139 4163
2008-11-06 23:34:45.156250 [11]: subch:10 cam flags:True
2008-11-06 23:34:45.156250 [11]: subch:10 SetMpegPidMapping
2008-11-06 23:34:45.156250 [11]: subch:10  pid:0 pcr
2008-11-06 23:34:45.156250 [11]: subch:10  pid:1043 pmt
2008-11-06 23:34:45.156250 [11]: subch:10  pid:258 video type:MPEG-2
2008-11-06 23:34:45.156250 [11]: subch:10    map pid:258 video type:MPEG-2
2008-11-06 23:34:45.156250 [11]: subch:10  pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:45.156250 [11]: subch:10    map pid:259 audio lang:eng type:MPEG-1
2008-11-06 23:34:45.156250 [11]: subch:10  pid:25A audio lang:eng type:MPEG-1
2008-11-06 23:34:45.156250 [11]: subch:10  pid:25B subtitle type:6
2008-11-06 23:34:45.156250 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-11-06 23:34:45.156250 [18]: card: tuned user: scheduler118 subchannel: 10
2008-11-06 23:34:45.156250 [18]: user:scheduler118 add
2008-11-06 23:34:45.156250 [11]: subch:10  pid:3ED type:B
2008-11-06 23:34:45.156250 [11]: subch:10  pid:3EE type:B
2008-11-06 23:34:45.156250 [11]: subch:10  pid:3EF type:B
2008-11-06 23:34:45.156250 [11]: subch:10  pid:28A type:B
2008-11-06 23:34:45.156250 [11]: subch:10  pid:28B type:B
2008-11-06 23:34:45.156250 [11]: subch:10  pid:28C type:B
2008-11-06 23:34:45.156250 [11]: subch:10 stop tif
2008-11-06 23:34:46.171875 [18]: card: Tuner locked: True
2008-11-06 23:34:46.171875 [18]: **************************************************
2008-11-06 23:34:46.171875 [18]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2008-11-06 23:34:46.171875 [18]: **************************************************
2008-11-06 23:34:46.187500 [10]: Scheduler : record to E:\tv\This Week - BBC ONE - 2008-11-06.mpg
2008-11-06 23:34:46.187500 [18]: card: StartRecording 2 E:\tv\This Week - BBC ONE - 2008-11-06.ts
2008-11-06 23:34:46.187500 [18]: StartRecording to E:\tv\This Week - BBC ONE - 2008-11-06.ts
2008-11-06 23:34:46.187500 [18]: subch:10 StartRecord(E:\tv\This Week - BBC ONE - 2008-11-06.ts)
2008-11-06 23:34:46.187500 [18]: subch:10 record transport stream mode
2008-11-06 23:34:46.187500 [18]: subch:10-10 tswriter StartRecording...
2008-11-06 23:34:46.187500 [18]: SetRecorderPids
2008-11-06 23:34:46.187500 [18]: DVB subch:10 Started recording
2008-11-06 23:34:46.203125 [10]: Scheduler: recList: count: 2 add scheduleid: 118 card: Hauppauge WinTV 88x DVB-T Tuner/Demod
2008-11-06 23:38:13.875000 [5]: diskmanagement: recording Question Time ended. type:Once max episodes:2147483647
2008-11-06 23:38:13.890625 [5]: ConflictManager: Updating conflicts list
2008-11-06 23:38:13.921875 [5]: Schedules List built 31.25 ms
2008-11-06 23:38:13.953125 [5]: ConflictManager: Update done within 62.5 ms
2008-11-06 23:38:13.953125 [5]: Scheduler: stop record BBC ONE 06/11/2008 22:32:12-06/11/2008 23:35:00 Question Time
2008-11-06 23:38:13.953125 [17]: card: StopRecording 2
2008-11-07 00:23:01.890625 [15]: ConflictManager: Updating conflicts list
2008-11-07 00:23:01.953125 [15]: Schedules List built 31.25 ms
2008-11-07 00:23:01.984375 [15]: ConflictManager: Update done within 62.5 ms
2008-11-07 00:23:01.984375 [15]: Scheduler: stop record BBC ONE 06/11/2008 23:34:46-07/11/2008 00:20:00 This Week
2008-11-07 00:23:01.984375 [12]: card: StopRecording 2
2008-11-07 00:23:02.109375 [12]: card: StopRecording for card:2
2008-11-07 00:23:02.109375 [12]: subch:10-10 tswriter StopRecording...
2008-11-07 00:23:02.109375 [12]: tvcard:FreeSubChannel:11 #10
2008-11-07 00:23:02.109375 [12]: DVB subch:10 Decompose()
2008-11-07 00:23:02.109375 [12]: FreeSubChannel CA: freeing sub channel : 10
2008-11-07 00:23:02.109375 [12]: card: remove user:scheduler118 sub:10
2008-11-07 00:23:02.109375 [12]: user:scheduler118 remove
2008-11-07 00:23:02.109375 [12]: card: free subchannel sub:10
2008-11-07 00:23:02.109375 [12]: tvcard:FreeSubChannel:10 #10
2008-11-07 00:23:02.109375 [12]: tvcard:FreeSubChannel :10 - sub channel not found
2008-11-07 00:23:17.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:17.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:17.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:33.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:33.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.843750 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.843750 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:23:49.859375 [15]: Orphaned Recording found -1 - removing
2008-11-07 00:23:49.859375 [15]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.843750 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.843750 [10]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.843750 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.843750 [10]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.843750 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.843750 [10]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.859375 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.859375 [10]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.859375 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.859375 [10]: recList:StopRecordingSchedule -1
2008-11-07 00:24:05.859375 [10]: Orphaned Recording found -1 - removing
2008-11-07 00:24:05.859375 [10]: recList:StopRecordingSchedule -1