TV-Server Version: 1.0
MediaPortal Version: 1.0
MediaPortal Skin: BlueThree Wide
Windows Version: Vista Ultimate SP1
CPU Type: Pentium M 760 (2.0GHz/533/2Mb)
HDD: Seagate 300Gb 7200RPM SATA 150
Memory: 2GB PC2-4200
Motherboard: MSI Speedster-FA4 (MS-9625)
Video Card: ASUS EAH3450 512MB
Video Card Driver:
Sound Card: Intel ICH6 HD Audio / Realtek ALC880
Sound Card AC3:
Sound Card Driver:
1. TV Card: BlackGold BGT3540
1. TV Card Type: 2xDVB-T, 2xDVB-S
1. TV Card Driver: 6.0.0.39
2. TV Card:
2. TV Card Type:
2. TV Card Driver:
3. TV Card:
3. TV Card Type:
3. TV Card Driver:
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
MPEG2 Video Codec: PowerDVD
MPEG2 Audio Codec: PowerDVD
h.264 Video Codec: PowerDVD
Satelite/CableTV Provider: Astra 28.2°E & Eurobird 28.5°E
HTPC Case: Antec Aria
Cooling: Stock
Power Supply: Stock
Remote: MCE (mark 2)
TV: Samsung 26in LCD LW26A33W
TV - HTPC Connection: Sub-D
UPDATE
This problem has been pinned down to systems that have either the Vista Media Center TV Pack 2008 or Windows 7 installed.
Overlapping recordings on the same fail because of a problem with subchannels.
Use case
* The TV Service is configured to pre-record for 5 minutes and post record for 15 minutes.
* Two records are scheduled back to back on the same channel:
Recording A BBC1 22:00 - 22:30 [Effective 21:55 - 22:45]
Recording B BBC1 22:30 - 23:00 [Effective 22:25 - 23:15]
Timeline
21:55 Time to record Recording A
21:55 TvService finds a free card and tunes to BBC1 - Recording A begins
22:25 Time to record Recording B
22:25 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:25 Tune request fails (SubmitTuneRequest returns 0x01)
22:26 Time to record Recording B
22:26 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:26 Tune request fails (SubmitTuneRequest returns 0x01)
...
... Repeated continuously until
...
22:44 Time to record Recording B
22:44 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:44 Tune request fails (SubmitTuneRequest returns 0x01)
22:45 End Recording A
22:45 TvService fully releases the tuner (FreeAllSubchannels) and Recording A ends
22:45 Time to record Recording B
22:45 TvService finds a free card and tunes to BBC1 - Recording B begins
23:15 End Recording B
23:15 TvService fully releases the tuner (FreeAllSubchannels) and Recording B ends
Additional use case
Update: A very similar issue seems to break manual recording. With manual recordings the TvServer attempts to re-use a subchannel instead of create a new one but the result is the same: SubmitTuneRequest: returns 0x1). I've attached some additional logs for this use case.
If you need any more information please let me know
Cheers
Mark
Log
The following snippet is an example of what happens when recording B starts. A more complete log is attached
2009-01-09 23:50:10.282134 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:10-10/01/2009 00:20:00 Family Guy
2009-01-09 23:50:10.283110 [Scheduler timer]: Controller: find free card for channel BBC THREE
2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.315335 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:10.333888 [Scheduler timer]: Controller: card:8 typevbT is tuned to different transponder
2009-01-09 23:50:10.334865 [Scheduler timer]: Controller: card:8 typevbT is available priority:-93 #users:1 same transponder:False
2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 typevbT is tuned to same transponder no CA present
2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 typevbT is available priority:6 #users:1 same transponder:True
2009-01-09 23:50:10.341700 [Scheduler timer]: Controller: card:10 typevbS cannot tune to channel
2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:11 typevbS cannot tune to channel
2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-01-09 23:50:10.348536 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:10.353418 [Scheduler timer]: Controller: card:10 typevbS is available priority:5 #users:0 same transponder:False
2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:11 typevbS is available priority:4 #users:0 same transponder:False
2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: found 4 available
2009-01-09 23:50:10.358301 [Scheduler timer]: Scheduler : record on free card:9 priority:6
2009-01-09 23:50:10.360254 [Scheduler timer]: Scheduler : record, first tune to channel
2009-01-09 23:50:10.374901 [28]: card: Tune 9 to BBC THREE
2009-01-09 23:50:10.375878 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.381737 [28]: dvbt: TuneVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.382713 [28]: dvbt: tune: Assigning oldChannel
2009-01-09 23:50:10.382713 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
2009-01-09 23:50:10.383690 [28]: dvbt: tune: Graph is tunning
2009-01-09 23:50:10.384666 [28]: dvbt: tune: Getting default locator
2009-01-09 23:50:10.384666 [28]: dvbt: tune: Putting bandwidth 8
2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_ONID 9018
2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_SID 4351
2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_TSID 4108
2009-01-09 23:50:10.386619 [28]: dvbt: tune: Carrier frequency 778000
2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_Locator
2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: System.__ComObject
2009-01-09 23:50:10.389549 [28]: dvbubmiting tunerequest Channel:BBC THREE subChannel:-1
2009-01-09 23:50:10.390525 [28]: dvb:Getting new subchannel
2009-01-09 23:50:10.390525 [28]: dvb:GetNewSubChannel:1 #2
2009-01-09 23:50:10.391502 [28]: dvbubmit tunerequest size:2 new:2
2009-01-09 23:50:10.392478 [28]: subch:2 OnBeforeTune
2009-01-09 23:50:10.394431 [28]: dvbubmit tunerequest calling put_TuneRequest
2009-01-09 23:50:10.395408 [28]: dvbubmit tunerequest done calling put_TuneRequest
2009-01-09 23:50:10.395408 [28]: dvbubmitTuneRequest returns:0x1
2009-01-09 23:50:10.395408 [28]: dvbubmitTuneRequest failed - removing subchannel: 2
2009-01-09 23:50:26.018431 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
2009-01-09 23:50:26.018431 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:26-10/01/2009 00:20:00 Family Guy
2009-01-09 23:50:26.019408 [Scheduler timer]: Controller: find free card for channel BBC THREE
2009-01-09 23:50:26.022337 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
2009-01-09 23:50:26.023314 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.080927 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:26.084833 [Scheduler timer]: Controller: card:8 typevbT is tuned to different transponder
2009-01-09 23:50:26.085810 [Scheduler timer]: Controller: card:8 typevbT is available priority:-93 #users:1 same transponder:False
2009-01-09 23:50:26.088739 [Scheduler timer]: Controller: card:9 typevbT is tuned to same transponder no CA present
2009-01-09 23:50:26.089716 [Scheduler timer]: Controller: card:9 typevbT is available priority:6 #users:1 same transponder:True
2009-01-09 23:50:26.091669 [Scheduler timer]: Controller: card:10 typevbS cannot tune to channel
2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:11 typevbS cannot tune to channel
2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-01-09 23:50:26.097528 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:26.102410 [Scheduler timer]: Controller: card:10 typevbS is available priority:5 #users:0 same transponder:False
2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:11 typevbS is available priority:4 #users:0 same transponder:False
2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:26.109246 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:26.110222 [Scheduler timer]: Controller: found 4 available
2009-01-09 23:50:26.110222 [Scheduler timer]: Scheduler : record on free card:9 priority:6
2009-01-09 23:50:26.111199 [Scheduler timer]: Scheduler : record, first tune to channel
2009-01-09 23:50:26.114128 [28]: card: Tune 9 to BBC THREE
2009-01-09 23:50:26.115105 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.121940 [28]: dvbt: TuneVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.122917 [28]: dvbt: tune: Assigning oldChannel
2009-01-09 23:50:26.124870 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
2009-01-09 23:50:26.124870 [28]: dvbt: tune: Graph is tunning
2009-01-09 23:50:26.125846 [28]: dvbt: tune: Getting default locator
2009-01-09 23:50:26.125846 [28]: dvbt: tune: Putting bandwidth 8
2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_ONID 9018
2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_SID 4351
2009-01-09 23:50:26.127799 [28]: dvbt: tune: put_TSID 4108
2009-01-09 23:50:26.127799 [28]: dvbt: tune: Carrier frequency 778000
2009-01-09 23:50:26.128776 [28]: dvbt: tune: put_Locator
2009-01-09 23:50:26.128776 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.129752 [28]: dvbt: tune: Submitting tune request: System.__ComObject
2009-01-09 23:50:26.129752 [28]: dvbubmiting tunerequest Channel:BBC THREE subChannel:-1
2009-01-09 23:50:26.130729 [28]: dvb:Getting new subchannel
2009-01-09 23:50:26.130729 [28]: dvb:GetNewSubChannel:1 #3
2009-01-09 23:50:26.189319 [28]: dvbubmit tunerequest size:2 new:3
2009-01-09 23:50:26.189319 [28]: subch:3 OnBeforeTune
2009-01-09 23:50:26.190295 [28]: dvbubmit tunerequest calling put_TuneRequest
2009-01-09 23:50:26.191272 [28]: dvbubmit tunerequest done calling put_TuneRequest
2009-01-09 23:50:26.191272 [28]: dvbubmitTuneRequest returns:0x1
2009-01-09 23:50:26.192248 [28]: dvbubmitTuneRequest failed - removing subchannel: 3
2009-01-09 23:50:28.172590 [28]: PowerScheduler: User input detected at 09/01/2009 23:50:28
2009-01-09 23:50:42.034984 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
2009-01-09 23:50:42.034984 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:42-10/01/2009 00:20:00 Family Guy
MediaPortal Version: 1.0
MediaPortal Skin: BlueThree Wide
Windows Version: Vista Ultimate SP1
CPU Type: Pentium M 760 (2.0GHz/533/2Mb)
HDD: Seagate 300Gb 7200RPM SATA 150
Memory: 2GB PC2-4200
Motherboard: MSI Speedster-FA4 (MS-9625)
Video Card: ASUS EAH3450 512MB
Video Card Driver:
Sound Card: Intel ICH6 HD Audio / Realtek ALC880
Sound Card AC3:
Sound Card Driver:
1. TV Card: BlackGold BGT3540
1. TV Card Type: 2xDVB-T, 2xDVB-S
1. TV Card Driver: 6.0.0.39
2. TV Card:
2. TV Card Type:
2. TV Card Driver:
3. TV Card:
3. TV Card Type:
3. TV Card Driver:
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
MPEG2 Video Codec: PowerDVD
MPEG2 Audio Codec: PowerDVD
h.264 Video Codec: PowerDVD
Satelite/CableTV Provider: Astra 28.2°E & Eurobird 28.5°E
HTPC Case: Antec Aria
Cooling: Stock
Power Supply: Stock
Remote: MCE (mark 2)
TV: Samsung 26in LCD LW26A33W
TV - HTPC Connection: Sub-D
UPDATE
This problem has been pinned down to systems that have either the Vista Media Center TV Pack 2008 or Windows 7 installed.
Overlapping recordings on the same fail because of a problem with subchannels.
Use case
* The TV Service is configured to pre-record for 5 minutes and post record for 15 minutes.
* Two records are scheduled back to back on the same channel:
Recording A BBC1 22:00 - 22:30 [Effective 21:55 - 22:45]
Recording B BBC1 22:30 - 23:00 [Effective 22:25 - 23:15]
Timeline
21:55 Time to record Recording A
21:55 TvService finds a free card and tunes to BBC1 - Recording A begins
22:25 Time to record Recording B
22:25 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:25 Tune request fails (SubmitTuneRequest returns 0x01)
22:26 Time to record Recording B
22:26 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:26 Tune request fails (SubmitTuneRequest returns 0x01)
...
... Repeated continuously until
...
22:44 Time to record Recording B
22:44 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
22:44 Tune request fails (SubmitTuneRequest returns 0x01)
22:45 End Recording A
22:45 TvService fully releases the tuner (FreeAllSubchannels) and Recording A ends
22:45 Time to record Recording B
22:45 TvService finds a free card and tunes to BBC1 - Recording B begins
23:15 End Recording B
23:15 TvService fully releases the tuner (FreeAllSubchannels) and Recording B ends
Additional use case
Update: A very similar issue seems to break manual recording. With manual recordings the TvServer attempts to re-use a subchannel instead of create a new one but the result is the same: SubmitTuneRequest: returns 0x1). I've attached some additional logs for this use case.
If you need any more information please let me know
Cheers
Mark
Log
The following snippet is an example of what happens when recording B starts. A more complete log is attached
2009-01-09 23:50:10.282134 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:10-10/01/2009 00:20:00 Family Guy
2009-01-09 23:50:10.283110 [Scheduler timer]: Controller: find free card for channel BBC THREE
2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.315335 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:10.333888 [Scheduler timer]: Controller: card:8 typevbT is tuned to different transponder
2009-01-09 23:50:10.334865 [Scheduler timer]: Controller: card:8 typevbT is available priority:-93 #users:1 same transponder:False
2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 typevbT is tuned to same transponder no CA present
2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 typevbT is available priority:6 #users:1 same transponder:True
2009-01-09 23:50:10.341700 [Scheduler timer]: Controller: card:10 typevbS cannot tune to channel
2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:11 typevbS cannot tune to channel
2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-01-09 23:50:10.348536 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:10.353418 [Scheduler timer]: Controller: card:10 typevbS is available priority:5 #users:0 same transponder:False
2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:11 typevbS is available priority:4 #users:0 same transponder:False
2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: found 4 available
2009-01-09 23:50:10.358301 [Scheduler timer]: Scheduler : record on free card:9 priority:6
2009-01-09 23:50:10.360254 [Scheduler timer]: Scheduler : record, first tune to channel
2009-01-09 23:50:10.374901 [28]: card: Tune 9 to BBC THREE
2009-01-09 23:50:10.375878 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.381737 [28]: dvbt: TuneVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.382713 [28]: dvbt: tune: Assigning oldChannel
2009-01-09 23:50:10.382713 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
2009-01-09 23:50:10.383690 [28]: dvbt: tune: Graph is tunning
2009-01-09 23:50:10.384666 [28]: dvbt: tune: Getting default locator
2009-01-09 23:50:10.384666 [28]: dvbt: tune: Putting bandwidth 8
2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_ONID 9018
2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_SID 4351
2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_TSID 4108
2009-01-09 23:50:10.386619 [28]: dvbt: tune: Carrier frequency 778000
2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_Locator
2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: System.__ComObject
2009-01-09 23:50:10.389549 [28]: dvbubmiting tunerequest Channel:BBC THREE subChannel:-1
2009-01-09 23:50:10.390525 [28]: dvb:Getting new subchannel
2009-01-09 23:50:10.390525 [28]: dvb:GetNewSubChannel:1 #2
2009-01-09 23:50:10.391502 [28]: dvbubmit tunerequest size:2 new:2
2009-01-09 23:50:10.392478 [28]: subch:2 OnBeforeTune
2009-01-09 23:50:10.394431 [28]: dvbubmit tunerequest calling put_TuneRequest
2009-01-09 23:50:10.395408 [28]: dvbubmit tunerequest done calling put_TuneRequest
2009-01-09 23:50:10.395408 [28]: dvbubmitTuneRequest returns:0x1
2009-01-09 23:50:10.395408 [28]: dvbubmitTuneRequest failed - removing subchannel: 2
2009-01-09 23:50:26.018431 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
2009-01-09 23:50:26.018431 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:26-10/01/2009 00:20:00 Family Guy
2009-01-09 23:50:26.019408 [Scheduler timer]: Controller: find free card for channel BBC THREE
2009-01-09 23:50:26.022337 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
2009-01-09 23:50:26.023314 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.080927 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:26.084833 [Scheduler timer]: Controller: card:8 typevbT is tuned to different transponder
2009-01-09 23:50:26.085810 [Scheduler timer]: Controller: card:8 typevbT is available priority:-93 #users:1 same transponder:False
2009-01-09 23:50:26.088739 [Scheduler timer]: Controller: card:9 typevbT is tuned to same transponder no CA present
2009-01-09 23:50:26.089716 [Scheduler timer]: Controller: card:9 typevbT is available priority:6 #users:1 same transponder:True
2009-01-09 23:50:26.091669 [Scheduler timer]: Controller: card:10 typevbS cannot tune to channel
2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:11 typevbS cannot tune to channel
2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-01-09 23:50:26.097528 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
2009-01-09 23:50:26.102410 [Scheduler timer]: Controller: card:10 typevbS is available priority:5 #users:0 same transponder:False
2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:11 typevbS is available priority:4 #users:0 same transponder:False
2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:12 type:Analog is disabled
2009-01-09 23:50:26.109246 [Scheduler timer]: Controller: card:13 type:Analog is disabled
2009-01-09 23:50:26.110222 [Scheduler timer]: Controller: found 4 available
2009-01-09 23:50:26.110222 [Scheduler timer]: Scheduler : record on free card:9 priority:6
2009-01-09 23:50:26.111199 [Scheduler timer]: Scheduler : record, first tune to channel
2009-01-09 23:50:26.114128 [28]: card: Tune 9 to BBC THREE
2009-01-09 23:50:26.115105 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.121940 [28]: dvbt: TuneVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.122917 [28]: dvbt: tune: Assigning oldChannel
2009-01-09 23:50:26.124870 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
2009-01-09 23:50:26.124870 [28]: dvbt: tune: Graph is tunning
2009-01-09 23:50:26.125846 [28]: dvbt: tune: Getting default locator
2009-01-09 23:50:26.125846 [28]: dvbt: tune: Putting bandwidth 8
2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_ONID 9018
2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_SID 4351
2009-01-09 23:50:26.127799 [28]: dvbt: tune: put_TSID 4108
2009-01-09 23:50:26.127799 [28]: dvbt: tune: Carrier frequency 778000
2009-01-09 23:50:26.128776 [28]: dvbt: tune: put_Locator
2009-01-09 23:50:26.128776 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
2009-01-09 23:50:26.129752 [28]: dvbt: tune: Submitting tune request: System.__ComObject
2009-01-09 23:50:26.129752 [28]: dvbubmiting tunerequest Channel:BBC THREE subChannel:-1
2009-01-09 23:50:26.130729 [28]: dvb:Getting new subchannel
2009-01-09 23:50:26.130729 [28]: dvb:GetNewSubChannel:1 #3
2009-01-09 23:50:26.189319 [28]: dvbubmit tunerequest size:2 new:3
2009-01-09 23:50:26.189319 [28]: subch:3 OnBeforeTune
2009-01-09 23:50:26.190295 [28]: dvbubmit tunerequest calling put_TuneRequest
2009-01-09 23:50:26.191272 [28]: dvbubmit tunerequest done calling put_TuneRequest
2009-01-09 23:50:26.191272 [28]: dvbubmitTuneRequest returns:0x1
2009-01-09 23:50:26.192248 [28]: dvbubmitTuneRequest failed - removing subchannel: 3
2009-01-09 23:50:28.172590 [28]: PowerScheduler: User input detected at 09/01/2009 23:50:28
2009-01-09 23:50:42.034984 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
2009-01-09 23:50:42.034984 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:42-10/01/2009 00:20:00 Family Guy