TV Server won't record after wake up event (1 Viewer)

revs · October 13, 2010

MediaPortal Version: 1.1.0 (Disaster Edition)
Windows Version: XP SP3
CPU Type: AMD X2 3800+
Motherboard: Gigabyte GA-K8N-Ultra 9
Video Card: Sapphire ATI HD 3450 512MB
Video Card Driver: Latest
Sound Card:
Sound Card AC3: Coaxial Out / SPDIF
Sound Card Driver:
1. TV Card: Hauppage Nova-T
1. TV Card Type: DVB-T
1. TV Card Driver:
2. TV Card: Hauppage Nova-S2-HD
2. TV Card Type: DVB-S2
2. TV Card Driver:
3. TV Card: Pinnacle 2000i Pro Dual DVB-T
3. TV Card Type: DVB-T (dual)
3. TV Card Driver:
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
[Codecs: SAF

I have successfully got MediaPortal going to sleep, and waking up using PowerScheduler - all good.

However, after waking up for a scheduled recording, MediaPortal is unable to record the program.

If I interact with MediaPortal, or try to watch a channel, then it kicks MediaPortal into gear and it starts recording. But if I'm away, then it just doesnt record. The logs say it cant tune in - but it can. If i manually select the channel then it works fine.

Have taken relevant bit out of the logs (as my logs are huge) - from powerscheduler waking up, and trying to record the program:

tv.log

Code:

2010-10-13 19:22:03.500000 [PowerScheduler Waiter(20)]: PowerScheduler: OnResume
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: TV service PowerEventThread 30
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: TV service PowerEventThread 30
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: OnPowerEvent: PowerStatus: ResumeSuspend
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: OnPowerEventHandler: PowerStatus: ResumeSuspend
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: PowerScheduler: System has resumed from standby
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: PowerScheduler: System changed from idle state to busy state
2010-10-13 19:22:13.875000 [PowerEventThread(7)]: Starting external command: C:\Utils\spinup.bat wakeup
2010-10-13 19:22:13.906250 [WinController exit thread(18)]: WindowsController: ExitWindows performed, result: True
2010-10-13 19:22:44.343750 [PowerEventThread(7)]: External command finished
2010-10-13 19:22:44.343750 [PowerEventThread(7)]: PowerScheduler: ReInit Controller
2010-10-13 19:22:49.343750 [PowerEventThread(7)]: Controller: Initializing TVServer
2010-10-13 19:22:49.343750 [PowerEventThread(7)]: Controller: 1 init attempt
2010-10-13 19:22:49.343750 [PowerEventThread(7)]: C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\gentle.config
2010-10-13 19:22:49.421875 [PowerEventThread(7)]: Controller: using SQLServer database connection: Password
2010-10-13 19:22:49.421875 [PowerEventThread(7)]: ----------------------------
2010-10-13 19:22:49.421875 [PowerEventThread(7)]: Detecting Cards in 30 seconds
2010-10-13 19:23:05.265625 [TvClient-TvHome: HeartBeat transmitter thread(17)]: RemoteControl: RegisterChannel first called in Domain MediaPortal.exe for thread TvClient-TvHome: HeartBeat transmitter thread with id 17
2010-10-13 19:23:05.390625 [TvClient-TvHome: HeartBeat transmitter thread(17)]: TCP connect took : 76
2010-10-13 19:23:05.390625 [TvClient-TvHome: HeartBeat transmitter thread(17)]: RemoteControl - Connected
2010-10-13 19:23:05.390625 [TvClient-TvHome: HeartBeat transmitter thread(17)]: RemoteControl - Connected
2010-10-13 19:23:05.406250 [MPMain(1)]: RemoteControl - Connected
2010-10-13 19:23:06.437500 [MPMain(1)]: RemoteControl - Connected
2010-10-13 19:23:06.593750 [TvClient-TvHome: HeartBeat transmitter thread(17)]: RemoteControl - Connected
2010-10-13 19:23:19.421875 [PowerEventThread(7)]: Detecting Cards
2010-10-13 19:23:19.437500 [PowerEventThread(7)]: OnPowerEvent: PowerStatus: ResumeAutomatic
2010-10-13 19:23:19.437500 [PowerEventThread(7)]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2010-10-13 19:23:19.437500 [PowerEventThread(7)]: PowerScheduler: System has resumed automatically from standby
2010-10-13 19:23:19.453125 [PowerEventThread(7)]: Found card:pinnacle royalts tuner
2010-10-13 19:23:19.468750 [PowerEventThread(7)]: Detected DVB-T card:pinnacle royalts tuner
2010-10-13 19:23:19.468750 [PowerEventThread(7)]: Found card:pinnacle royalts tuner
2010-10-13 19:23:19.484375 [PowerEventThread(7)]: Detected DVB-T card:pinnacle royalts tuner
2010-10-13 19:23:19.484375 [PowerEventThread(7)]: Found card:hauppauge wintv 88x dvb-s/s2 tuner/demod
2010-10-13 19:23:19.484375 [PowerEventThread(7)]: Detected DVB-S card:hauppauge wintv 88x dvb-s/s2 tuner/demod
2010-10-13 19:23:19.500000 [PowerEventThread(7)]: Found card:hauppauge wintv 88x dvb-t tuner/demod
2010-10-13 19:23:19.500000 [PowerEventThread(7)]: Detected DVB-T card:hauppauge wintv 88x dvb-t tuner/demod
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Remove filter from graph: DVBC Network Provider
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Remove filter from graph: ATSC Network Provider
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Remove filter from graph: DVBS Network Provider
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Remove filter from graph: DVBT Network Provider
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Controller: started at mediaportal
2010-10-13 19:23:19.515625 [PowerEventThread(7)]: Controller: local ip address:10.0.1.3
2010-10-13 19:23:19.531250 [PowerEventThread(7)]: Controller: server running on mediaportal
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: Controller: preloading card :Hauppauge WinTV 88x DVB-T Tuner/Demod
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvbt:BuildGraph
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvb:AddNetworkProviderFilter
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvb:Add DVBT Network Provider
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvbt:CreateTuningSpace()
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvbt:found correct tuningspace MediaPortal DVBT TuningSpace
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvb:Add MPEG2 Demultiplexer filter
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvb:add Inf Tee filter
2010-10-13 19:23:19.546875 [PowerEventThread(7)]: dvb:AddAndConnectBDABoardFilters
2010-10-13 19:23:19.562500 [PowerEventThread(7)]: dvb: find bda tuner
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  using [Tuner]: Hauppauge WinTV 88x DVB-T Tuner/Demod
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  Render [Network provider]->[Tuner] OK
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  Find BDA receiver
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  match Capture by Tuner device path
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:19.671875 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  Match by device path failed - trying alternative method
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  match Capture filter by Tuner device connection
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:19.687500 [PowerEventThread(7)]: dvb:  Looking for another bda receiver...
2010-10-13 19:23:19.703125 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:19.703125 [PowerEventThread(7)]: dvb:  Looking for another bda receiver...
2010-10-13 19:23:19.703125 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:19.703125 [PowerEventThread(7)]: dvb:  Looking for another bda receiver...
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  Render [Tuner]->[Capture] AOK
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  WinTv CI module not detected.
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  Render [capture]->[inftee]
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  OK
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  Render [inftee]->[demux]
2010-10-13 19:23:19.718750 [PowerEventThread(7)]: dvb:  Add Mediaportal TsWriter filter
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: dvb:  Render [InfTee]->[TsWriter]
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: CAM is Default model
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for KNC
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: KNC: IsKNC False
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: KNC: Disable CI
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: KNC: Disposing CI handler
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for Digital Everywhere
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for Twinhan
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Twinhan: CI detection finished
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for TechnoTrend
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: TechnoTrend: Closing hardware
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for Hauppauge
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Hauppauge: DVB-S card NOT found!
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for ProfRed
2010-10-13 19:23:19.734375 [PowerEventThread(7)]: Check for TeVii
2010-10-13 19:23:20.187500 [PowerEventThread(7)]: Check for Conexant based card
2010-10-13 19:23:20.187500 [PowerEventThread(7)]: Conexant BDA: DVB-S card NOT found!
2010-10-13 19:23:20.187500 [PowerEventThread(7)]: Check for GenPix BDA based card
2010-10-13 19:23:20.187500 [PowerEventThread(7)]: GenPix tuner filter name: Hauppauge WinTV 88x DVB-T Tuner/Demod
2010-10-13 19:23:20.203125 [PowerEventThread(7)]: GenPix tuner filter pin name: MPEG2 Transport
2010-10-13 19:23:20.203125 [PowerEventThread(7)]: GenPix BDA: DVB-S card NOT found!
2010-10-13 19:23:20.203125 [PowerEventThread(7)]: Check for Generic DVB-S card
2010-10-13 19:23:20.203125 [PowerEventThread(7)]: Check for Hauppauge WinTV CI
2010-10-13 19:23:20.203125 [PowerEventThread(7)]: dvb:  AddTransportStreamFiltersToGraph
2010-10-13 19:23:20.203125 [PowerEventThread(7)]:     add BDA MPEG2 Transport Information Filter filter
2010-10-13 19:23:20.218750 [PowerEventThread(7)]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2010-10-13 19:23:20.218750 [PowerEventThread(7)]:     Connect tif and mpeg2 sections and tables
2010-10-13 19:23:20.218750 [PowerEventThread(7)]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2010-10-13 19:23:20.234375 [PowerEventThread(7)]:     tif connected
2010-10-13 19:23:20.562500 [PowerEventThread(7)]: dvb: GetTunerSignalStatistics()
2010-10-13 19:23:20.562500 [PowerEventThread(7)]: dvb:  RunGraph
2010-10-13 19:23:22.875000 [PowerEventThread(7)]: Controller: preloading card :Hauppauge WinTV 88x DVB-S/S2 Tuner/Demod
2010-10-13 19:23:22.875000 [PowerEventThread(7)]: dvbs:BuildGraph
2010-10-13 19:23:22.875000 [PowerEventThread(7)]: dvb:AddNetworkProviderFilter
2010-10-13 19:23:22.875000 [PowerEventThread(7)]: dvb:Add DVBS Network Provider
2010-10-13 19:23:22.875000 [PowerEventThread(7)]: dvbs:CreateTuningSpace()
2010-10-13 19:23:22.890625 [PowerEventThread(7)]: dvbs:found correct tuningspace MediaPortal DVBS TuningSpace
2010-10-13 19:23:22.890625 [PowerEventThread(7)]: dvb:Add MPEG2 Demultiplexer filter
2010-10-13 19:23:22.890625 [PowerEventThread(7)]: dvb:add Inf Tee filter
2010-10-13 19:23:22.890625 [PowerEventThread(7)]: dvb:AddAndConnectBDABoardFilters
2010-10-13 19:23:22.890625 [PowerEventThread(7)]: dvb: find bda tuner
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  using [Tuner]: Hauppauge WinTV 88x DVB-S/S2 Tuner/Demod
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  Render [Network provider]->[Tuner] OK
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  Find BDA receiver
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  match Capture by Tuner device path
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:22.953125 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:22.968750 [PowerEventThread(7)]: dvb:  Match by device path failed - trying alternative method
2010-10-13 19:23:22.968750 [PowerEventThread(7)]: dvb:  match Capture filter by Tuner device connection
2010-10-13 19:23:22.968750 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:22.968750 [PowerEventThread(7)]: dvb:  Looking for another bda receiver...
2010-10-13 19:23:22.968750 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:22.984375 [PowerEventThread(7)]: dvb:  Looking for another bda receiver...
2010-10-13 19:23:22.984375 [PowerEventThread(7)]: dvb:  -Hauppauge WinTV 88x TS Capture
2010-10-13 19:23:22.984375 [PowerEventThread(7)]: dvb:  Render [Tuner]->[Capture] AOK
2010-10-13 19:23:22.984375 [PowerEventThread(7)]: dvb:  WinTv CI module not detected.
2010-10-13 19:23:22.984375 [PowerEventThread(7)]: dvb:  Render [capture]->[inftee]
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: dvb:  OK
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: dvb:  Render [inftee]->[demux]
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: dvb:  Add Mediaportal TsWriter filter
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: dvb:  Render [InfTee]->[TsWriter]
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: CAM is Default model
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Check for KNC
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: KNC: IsKNC False
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: KNC: Disable CI
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: KNC: Disposing CI handler
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Check for Digital Everywhere
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Check for Twinhan
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Twinhan: CI detection finished
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Check for TechnoTrend
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: TechnoTrend: Closing hardware
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Check for Hauppauge
2010-10-13 19:23:23.000000 [PowerEventThread(7)]: Hauppauge: DVB-S card found!
2010-10-13 19:23:23.015625 [PowerEventThread(7)]: Hauppauge card detected
2010-10-13 19:23:23.015625 [PowerEventThread(7)]: Check for Hauppauge WinTV CI
2010-10-13 19:23:23.015625 [PowerEventThread(7)]: dvb:  AddTransportStreamFiltersToGraph
2010-10-13 19:23:23.015625 [PowerEventThread(7)]:     add BDA MPEG2 Transport Information Filter filter
2010-10-13 19:23:23.015625 [PowerEventThread(7)]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2010-10-13 19:23:23.015625 [PowerEventThread(7)]:     Connect tif and mpeg2 sections and tables
2010-10-13 19:23:23.015625 [PowerEventThread(7)]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2010-10-13 19:23:23.015625 [PowerEventThread(7)]:     tif connected
2010-10-13 19:23:23.156250 [PowerEventThread(7)]: dvb: GetTunerSignalStatistics()
2010-10-13 19:23:23.156250 [PowerEventThread(7)]: dvb:  RunGraph
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: Controller: preloading card :Pinnacle RoyalTS Tuner
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvbt:BuildGraph
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvb:AddNetworkProviderFilter
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvb:Add DVBT Network Provider
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvbt:CreateTuningSpace()
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvbt:found correct tuningspace MediaPortal DVBT TuningSpace
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvb:Add MPEG2 Demultiplexer filter
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvb:add Inf Tee filter
2010-10-13 19:23:27.718750 [PowerEventThread(7)]: dvb:AddAndConnectBDABoardFilters
2010-10-13 19:23:27.734375 [PowerEventThread(7)]: dvb: find bda tuner
2010-10-13 19:23:27.734375 [PowerEventThread(7)]: dvb:  using [Tuner]: Pinnacle RoyalTS Tuner
2010-10-13 19:23:27.734375 [PowerEventThread(7)]: dvb:  Render [Network provider]->[Tuner] OK
2010-10-13 19:23:27.734375 [PowerEventThread(7)]: dvb:  Find BDA receiver
2010-10-13 19:23:27.734375 [PowerEventThread(7)]: dvb:  match Capture by Tuner device path
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  Render [Tuner]->[Capture] AOK
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  WinTv CI module not detected.
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  Render [capture]->[inftee]
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  OK
2010-10-13 19:23:27.750000 [PowerEventThread(7)]: dvb:  Render [inftee]->[demux]
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: dvb:  Add Mediaportal TsWriter filter
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: dvb:  Render [InfTee]->[TsWriter]
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: CAM is Default model
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for KNC
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: KNC: IsKNC False
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: KNC: Disable CI
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: KNC: Disposing CI handler
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for Digital Everywhere
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for Twinhan
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Twinhan: CI detection finished
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for TechnoTrend
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: TechnoTrend: Closing hardware
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for Hauppauge
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Hauppauge: DVB-S card NOT found!
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for ProfRed
2010-10-13 19:23:27.765625 [PowerEventThread(7)]: Check for TeVii
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Check for Conexant based card
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Conexant BDA: DVB-S card NOT found!
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Check for GenPix BDA based card
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: GenPix tuner filter name: Pinnacle RoyalTS Tuner
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: GenPix tuner filter pin name: MPEG2 Transport
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: GenPix BDA: DVB-S card NOT found!
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Check for Generic DVB-S card
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: GenericBDAS: DiSEqC capable card found!
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Generic BDA card detected
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: Check for Hauppauge WinTV CI
2010-10-13 19:23:28.203125 [PowerEventThread(7)]: dvb:  AddTransportStreamFiltersToGraph
2010-10-13 19:23:28.203125 [PowerEventThread(7)]:     add BDA MPEG2 Transport Information Filter filter
2010-10-13 19:23:28.218750 [PowerEventThread(7)]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2010-10-13 19:23:28.218750 [PowerEventThread(7)]:     Connect tif and mpeg2 sections and tables
2010-10-13 19:23:28.218750 [PowerEventThread(7)]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2010-10-13 19:23:28.218750 [PowerEventThread(7)]:     tif connected
2010-10-13 19:23:28.625000 [PowerEventThread(7)]: dvb: GetTunerSignalStatistics()
2010-10-13 19:23:28.625000 [PowerEventThread(7)]: dvb:  RunGraph
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: Controller: preloading card :Pinnacle RoyalTS Tuner
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvbt:BuildGraph
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvb:AddNetworkProviderFilter
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvb:Add DVBT Network Provider
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvbt:CreateTuningSpace()
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvbt:found correct tuningspace MediaPortal DVBT TuningSpace
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvb:Add MPEG2 Demultiplexer filter
2010-10-13 19:23:29.250000 [PowerEventThread(7)]: dvb:add Inf Tee filter
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb:AddAndConnectBDABoardFilters
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb: find bda tuner
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb:  using [Tuner]: Pinnacle RoyalTS Tuner
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb:  Render [Network provider]->[Tuner] OK
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb:  Find BDA receiver
2010-10-13 19:23:29.265625 [PowerEventThread(7)]: dvb:  match Capture by Tuner device path
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  -Pinnacle RoyalTS Capture
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  Render [Tuner]->[Capture] AOK
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  WinTv CI module not detected.
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  Render [capture]->[inftee]
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  OK
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  Render [inftee]->[demux]
2010-10-13 19:23:29.281250 [PowerEventThread(7)]: dvb:  Add Mediaportal TsWriter filter
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: dvb:  Render [InfTee]->[TsWriter]
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: CAM is Default model
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for KNC
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: KNC: IsKNC False
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: KNC: Disable CI
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: KNC: Disposing CI handler
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for Digital Everywhere
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for Twinhan
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Twinhan: CI detection finished
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for TechnoTrend
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: TechnoTrend: Closing hardware
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for Hauppauge
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Hauppauge: DVB-S card NOT found!
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for ProfRed
2010-10-13 19:23:29.296875 [PowerEventThread(7)]: Check for TeVii
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Check for Conexant based card
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Conexant BDA: DVB-S card NOT found!
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Check for GenPix BDA based card
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: GenPix tuner filter name: Pinnacle RoyalTS Tuner
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: GenPix tuner filter pin name: MPEG2 Transport
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: GenPix BDA: DVB-S card NOT found!
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Check for Generic DVB-S card
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: GenericBDAS: DiSEqC capable card found!
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Generic BDA card detected
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: Check for Hauppauge WinTV CI
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: dvb:  AddTransportStreamFiltersToGraph
2010-10-13 19:23:29.734375 [PowerEventThread(7)]:     add BDA MPEG2 Transport Information Filter filter
2010-10-13 19:23:29.734375 [PowerEventThread(7)]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2010-10-13 19:23:29.734375 [PowerEventThread(7)]:     Connect tif and mpeg2 sections and tables
2010-10-13 19:23:29.734375 [PowerEventThread(7)]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2010-10-13 19:23:29.734375 [PowerEventThread(7)]:     tif connected
2010-10-13 19:23:29.843750 [PowerEventThread(7)]: dvb: GetTunerSignalStatistics()
2010-10-13 19:23:29.843750 [PowerEventThread(7)]: dvb:  RunGraph
2010-10-13 19:23:30.453125 [PowerEventThread(7)]: Controller: NOT preloading card :RadioWebStream Card (builtin)
2010-10-13 19:23:30.453125 [PowerEventThread(7)]: Controller: setup hybrid cards
2010-10-13 19:23:30.453125 [PowerEventThread(7)]: Controller: card Hauppauge WinTV 88x DVB-T Tuner/Demod: current timeshiftpath = D:\Timeshifting
2010-10-13 19:23:30.468750 [PowerEventThread(7)]: Controller: card Hauppauge WinTV 88x DVB-S/S2 Tuner/Demod: current timeshiftpath = D:\Timeshifting
2010-10-13 19:23:30.468750 [PowerEventThread(7)]: Controller: card Pinnacle RoyalTS Tuner: current timeshiftpath = D:\Timeshifting
2010-10-13 19:23:30.468750 [PowerEventThread(7)]: Controller: card Pinnacle RoyalTS Tuner: current timeshiftpath = D:\Timeshifting
2010-10-13 19:23:30.468750 [PowerEventThread(7)]: Controller: card RadioWebStream Card (builtin): current timeshiftpath = D:\Timeshifting
2010-10-13 19:23:30.468750 [PowerEventThread(7)]: Controller: setup streaming
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: Scheduler: started
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: Scheduler: loaded 4 schedules
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: Scheduler: thread started.
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: DiskManagement: started
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: Controller: setup HeartBeat Monitor
2010-10-13 19:23:30.796875 [HeartBeatMonitor(22)]: Controller: Heartbeat Monitor initiated, max timeout allowed is 30 sec.
2010-10-13 19:23:30.796875 [PowerEventThread(7)]: Controller: recalculating program states
2010-10-13 19:23:30.875000 [PowerEventThread(7)]: schedule.SynchProgramStatesForAll
2010-10-13 19:23:30.890625 [PowerEventThread(7)]: Controller: initalized
2010-10-13 19:23:30.890625 [PowerEventThread(7)]: Controller: TVServer initialized okay
2010-10-13 19:23:30.890625 [(19)]: PowerScheduler: RegisterRemote tag: 5, uris: http://localhost:31458/f275c9e7_b729_417e_885b_5209fcbdd08d/nlbdt2wlxcqp1reqtu9ar5yy_1.rem, http://localhost:31458/f275c9e7_b729_417e_885b_5209fcbdd08d/nlbdt2wlxcqp1reqtu9ar5yy_1.rem
2010-10-13 19:23:30.890625 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:23:55.890625 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:23:55.890625 [(17)]: PowerScheduler: User input detected at 13/10/2010 19:23:30
2010-10-13 19:24:20.890625 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler: lastUserTime: 19:23:30.8906 , True
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler: System is now unattended: False
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:GenericStandbyHandler DisAllowShutdown:False
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveStreamsHandler DisAllowShutdown:False
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ControllerActiveHandler DisAllowShutdown:False
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ProcessActiveHandler: DisAllowShutdown:False
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: NetworkMonitorHandler: standby prevented: Intel[R] Gigabit CT Desktop Adapter - Packet Scheduler Miniport
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: NetworkMonitorHandler: ulSpeed: 3
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: NetworkMonitorHandler: dlSpeed: 33
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler: User input detected at 13/10/2010 19:24:30
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:NetworkMonitorHandler DisAllowShutdown:True
2010-10-13 19:24:30.906250 [Powerscheduler Timer(5)]: NetworkMonitorHandler: idle limit in KB/s: 25
2010-10-13 19:24:45.890625 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:25:10.890625 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:25:30.906250 [Powerscheduler Timer(5)]: PowerScheduler: lastUserTime: 19:24:30.9062 , False
2010-10-13 19:25:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:GenericStandbyHandler DisAllowShutdown:False
2010-10-13 19:25:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveStreamsHandler DisAllowShutdown:False
2010-10-13 19:25:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ControllerActiveHandler DisAllowShutdown:False
2010-10-13 19:25:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ProcessActiveHandler: DisAllowShutdown:False
2010-10-13 19:25:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:NetworkMonitorHandler DisAllowShutdown:False
2010-10-13 19:25:31.000000 [Powerscheduler Timer(5)]: ActiveSharesHandler: have not found any matching connections - will allow standby
2010-10-13 19:25:31.000000 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveSharesHandler DisAllowShutdown:False
2010-10-13 19:25:31.203125 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:<dead#5> DisAllowShutdown:False
2010-10-13 19:25:31.203125 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:PowerSchedulerClientPlugin DisAllowShutdown:False
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:GenericWakeupHandler time:31/12/9999 23:59:59
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:ScheduledRecordingsHandler time:13/10/2010 19:27:00
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:XmlTvImportWakeupHandler time:31/12/9999 23:59:59
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:EpgGrabbingHandler time:14/10/2010 01:30:00
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:<dead#5> time:31/12/9999 23:59:59
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:PowerSchedulerClientPlugin time:31/12/9999 23:59:59
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: some event is almost due
2010-10-13 19:25:31.218750 [Powerscheduler Timer(5)]: NetworkMonitorHandler: idle limit in KB/s: 25
2010-10-13 19:25:35.906250 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:26:00.906250 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:26:25.921875 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler: lastUserTime: 19:24:30.9062 , False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:GenericStandbyHandler DisAllowShutdown:False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveStreamsHandler DisAllowShutdown:False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ControllerActiveHandler DisAllowShutdown:False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ProcessActiveHandler: DisAllowShutdown:False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:NetworkMonitorHandler DisAllowShutdown:False
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: ActiveSharesHandler: have not found any matching connections - will allow standby
2010-10-13 19:26:30.906250 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveSharesHandler DisAllowShutdown:False
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:<dead#5> DisAllowShutdown:False
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:PowerSchedulerClientPlugin DisAllowShutdown:False
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:GenericWakeupHandler time:31/12/9999 23:59:59
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:ScheduledRecordingsHandler time:13/10/2010 19:27:00
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:XmlTvImportWakeupHandler time:31/12/9999 23:59:59
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:EpgGrabbingHandler time:14/10/2010 01:30:00
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:<dead#5> time:31/12/9999 23:59:59
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.NextWakeupTime: inspecting IWakeupHandler:PowerSchedulerClientPlugin time:31/12/9999 23:59:59
2010-10-13 19:26:30.921875 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: some event is almost due
2010-10-13 19:26:30.937500 [Powerscheduler Timer(5)]: NetworkMonitorHandler: idle limit in KB/s: 25
2010-10-13 19:26:50.921875 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:27:00.859375 [scheduler thread(16)]: Scheduler: Time to record BBC ONE 19:27-20:00 Bang Goes the Theory
2010-10-13 19:27:00.859375 [scheduler thread(16)]: Controller: find free card for channel BBC ONE
2010-10-13 19:27:00.859375 [scheduler thread(16)]: Controller: find card for channel BBC ONE
2010-10-13 19:27:00.859375 [scheduler thread(16)]: Controller:   got 2 tuning details for BBC ONE
2010-10-13 19:27:00.859375 [scheduler thread(16)]: Controller:   channel #1 DVBS:tv:BSkyB BBC ONE Freq:10788000 ONID:2 TSID:2046 SID:10301 PMT:0x100 FTA:True LCN:2427 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:10 type:DvbT cannot tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:9 type:DvbS can tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:9 type:DvbS users: 0
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:8 type:DvbT cannot tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:7 type:DvbT cannot tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:   channel #2 DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8 
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:10 type:DvbT can tune to channel
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:10 type:DvbT users: 0
2010-10-13 19:27:00.875000 [scheduler thread(16)]: Controller:    card:9 type:DvbS cannot tune to channel
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:8 type:DvbT can tune to channel
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:8 type:DvbT users: 0
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:7 type:DvbT can tune to channel
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:7 type:DvbT users: 0
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller: found 4 card(s) for channel
2010-10-13 19:27:00.890625 [scheduler thread(16)]: AdvancedCardAllocation.GetCardsForChannel took 31 msec
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:10 type:DvbT is available
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:9 type:DvbS is available
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:8 type:DvbT is available
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller:    card:7 type:DvbT is available
2010-10-13 19:27:00.890625 [scheduler thread(16)]: Controller: found 4 free card(s)
2010-10-13 19:27:00.890625 [scheduler thread(16)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 34 msec
2010-10-13 19:27:00.906250 [scheduler thread(16)]: Scheduler : record on recommended card:10 priority:5
2010-10-13 19:27:00.906250 [scheduler thread(16)]: user:epg remove
2010-10-13 19:27:00.906250 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 0 subch#0 keep graph=False
2010-10-13 19:27:00.906250 [scheduler thread(16)]: tvcard:FreeSubChannel :0 - sub channel not found
2010-10-13 19:27:00.906250 [scheduler thread(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2010-10-13 19:27:00.906250 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 19:27:00.906250 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 19:27:00.906250 [scheduler thread(16)]: dvb:PauseGraph
2010-10-13 19:27:00.906250 [scheduler thread(16)]: Controller: epg stop
2010-10-13 19:27:00.906250 [scheduler thread(16)]: Scheduler : record, first tune to channel
2010-10-13 19:27:00.906250 [scheduler thread(16)]: card: Tune 10 to BBC ONE
2010-10-13 19:27:00.906250 [scheduler thread(16)]: card: user: scheduler32:10:-1 tune DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 19:27:00.921875 [scheduler thread(16)]: dvbt: Tune:DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 19:27:00.921875 [scheduler thread(16)]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2010-10-13 19:27:00.921875 [scheduler thread(16)]: dvb:Getting new subchannel
2010-10-13 19:27:00.921875 [scheduler thread(16)]: dvb:GetNewSubChannel:0 #0
2010-10-13 19:27:00.921875 [scheduler thread(16)]: TvDvbChannel ctor new subchIndex:0
2010-10-13 19:27:00.921875 [scheduler thread(16)]: dvb:Submit tunerequest size:1 new:0
2010-10-13 19:27:00.921875 [scheduler thread(16)]: subch:0 OnBeforeTune
2010-10-13 19:27:00.937500 [scheduler thread(16)]: dvb:Submit tunerequest calling put_TuneRequest
2010-10-13 19:27:01.796875 [scheduler thread(16)]: dvb:Submit tunerequest done calling put_TuneRequest
2010-10-13 19:27:01.796875 [scheduler thread(16)]: subch:0 OnAfterTune
2010-10-13 19:27:01.796875 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:01.828125 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:01.843750 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:01.875000 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:01.890625 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:03.828125 [scheduler thread(16)]: dvb:  LockedInOnSignal could not lock onto channel - no signal or bad signal
2010-10-13 19:27:03.828125 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2010-10-13 19:27:03.828125 [scheduler thread(16)]: DVB subch:0 Decompose()
2010-10-13 19:27:03.828125 [scheduler thread(16)]: FreeSubChannel CA: freeing sub channel : 0
2010-10-13 19:27:03.828125 [scheduler thread(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2010-10-13 19:27:03.828125 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 19:27:03.828125 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 19:27:03.828125 [scheduler thread(16)]: dvb:PauseGraph
2010-10-13 19:27:03.828125 [scheduler thread(16)]: Controller: epg start
2010-10-13 19:27:15.937500 [(17)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Scheduler: Time to record BBC ONE 19:27-20:00 Bang Goes the Theory
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller: find free card for channel BBC ONE
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller: find card for channel BBC ONE
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:   got 2 tuning details for BBC ONE
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:   channel #1 DVBS:tv:BSkyB BBC ONE Freq:10788000 ONID:2 TSID:2046 SID:10301 PMT:0x100 FTA:True LCN:2427 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:10 type:DvbT cannot tune to channel
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:9 type:DvbS can tune to channel
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:9 type:DvbS users: 0
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:8 type:DvbT cannot tune to channel
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:7 type:DvbT cannot tune to channel
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 19:27:18.968750 [scheduler thread(16)]: Controller:   channel #2 DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8 
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:10 type:DvbT can tune to channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:10 type:DvbT users: 0
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:9 type:DvbS cannot tune to channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:8 type:DvbT can tune to channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:8 type:DvbT users: 0
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:7 type:DvbT can tune to channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:7 type:DvbT users: 0
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: Controller: found 4 card(s) for channel
2010-10-13 19:27:18.984375 [scheduler thread(16)]: AdvancedCardAllocation.GetCardsForChannel took 29 msec
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller:    card:10 type:DvbT is available
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller:    card:9 type:DvbS is available
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller:    card:8 type:DvbT is available
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller:    card:7 type:DvbT is available
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller: found 4 free card(s)
2010-10-13 19:27:19.000000 [scheduler thread(16)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 33 msec
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Scheduler : record on recommended card:10 priority:5
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Controller: epg stop
2010-10-13 19:27:19.000000 [scheduler thread(16)]: Scheduler : record, first tune to channel
2010-10-13 19:27:19.000000 [scheduler thread(16)]: card: Tune 10 to BBC ONE
2010-10-13 19:27:19.000000 [scheduler thread(16)]: card: user: scheduler32:10:-1 tune DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvbt: Tune:DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:Getting new subchannel
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:GetNewSubChannel:0 #0
2010-10-13 19:27:19.015625 [scheduler thread(16)]: TvDvbChannel ctor new subchIndex:0
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:Submit tunerequest size:1 new:0
2010-10-13 19:27:19.015625 [scheduler thread(16)]: subch:0 OnBeforeTune
2010-10-13 19:27:19.015625 [scheduler thread(16)]: subch:0 OnAfterTune
2010-10-13 19:27:19.015625 [scheduler thread(16)]: subch:0 OnGraphStart
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:  RunGraph
2010-10-13 19:27:19.015625 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:19.046875 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:19.062500 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:19.093750 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:19.109375 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 19:27:21.031250 [scheduler thread(16)]: dvb:  LockedInOnSignal could not lock onto channel - no signal or bad signal
2010-10-13 19:27:21.031250 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2010-10-13 19:27:21.031250 [scheduler thread(16)]: DVB subch:0 Decompose()
2010-10-13 19:27:21.031250 [scheduler thread(16)]: FreeSubChannel CA: freeing sub channel : 0
2010-10-13 19:27:21.031250 [scheduler thread(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2010-10-13 19:27:21.031250 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 19:27:21.031250 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 19:27:21.031250 [scheduler thread(16)]: dvb:PauseGraph
2010-10-13 19:27:21.046875 [scheduler thread(16)]: Controller: epg start
2010-10-13 19:27:30.890625 [Powerscheduler Timer(5)]: PowerScheduler: lastUserTime: 19:24:30.9062 , False
2010-10-13 19:27:30.890625 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:GenericStandbyHandler DisAllowShutdown:False
2010-10-13 19:27:30.890625 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveStreamsHandler DisAllowShutdown:False
2010-10-13 19:27:30.890625 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ControllerActiveHandler DisAllowShutdown:True
2010-10-13 19:27:30.906250 [Powerscheduler Timer(5)]: NetworkMonitorHandler: idle limit in KB/s: 25
2010-10-13 19:27:36.171875 [scheduler thread(16)]: Scheduler: Time to record BBC ONE 19:27-20:00 Bang Goes the Theory

It then loops, trying to record over and over

about 20 min later I interacted with the tv - and the recording started.. logs for that time below

Code:

010-10-13 20:02:20.968750 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 20:02:20.984375 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 20:02:21.015625 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 20:02:21.046875 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 20:02:21.062500 [scheduler thread(16)]: dvb:  LockedInOnSignal waiting 20ms
2010-10-13 20:02:23.000000 [scheduler thread(16)]: dvb:  LockedInOnSignal could not lock onto channel - no signal or bad signal
2010-10-13 20:02:23.000000 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2010-10-13 20:02:23.000000 [scheduler thread(16)]: DVB subch:0 Decompose()
2010-10-13 20:02:23.000000 [scheduler thread(16)]: FreeSubChannel CA: freeing sub channel : 0
2010-10-13 20:02:23.000000 [scheduler thread(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2010-10-13 20:02:23.000000 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 20:02:23.000000 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 20:02:23.000000 [scheduler thread(16)]: dvb:PauseGraph
2010-10-13 20:02:23.015625 [scheduler thread(16)]: Controller: epg start
2010-10-13 20:02:30.953125 [Powerscheduler Timer(5)]: PowerScheduler: lastUserTime: 20:01:30.9375 , False
2010-10-13 20:02:30.953125 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:GenericStandbyHandler DisAllowShutdown:False
2010-10-13 20:02:30.953125 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ActiveStreamsHandler DisAllowShutdown:False
2010-10-13 20:02:30.953125 [Powerscheduler Timer(5)]: PowerScheduler.DisAllowShutdown: inspecting handler:ControllerActiveHandler DisAllowShutdown:True
2010-10-13 20:02:30.953125 [Powerscheduler Timer(5)]: NetworkMonitorHandler: idle limit in KB/s: 25
2010-10-13 20:02:31.109375 [PowerEventThread(7)]: TV service PowerEventThread 537
2010-10-13 20:02:31.109375 [PowerEventThread(7)]: TV service PowerEventThread 537
2010-10-13 20:02:31.171875 [PowerEventThread(7)]: TV service PowerEventThread 537
2010-10-13 20:02:31.171875 [PowerEventThread(7)]: TV service PowerEventThread 537
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Scheduler: Time to record BBC ONE 20:02-20:00 Bang Goes the Theory
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller: find free card for channel BBC ONE
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller: find card for channel BBC ONE
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:   got 2 tuning details for BBC ONE
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:   channel #1 DVBS:tv:BSkyB BBC ONE Freq:10788000 ONID:2 TSID:2046 SID:10301 PMT:0x100 FTA:True LCN:2427 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:    card:10 type:DvbT cannot tune to channel
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:    card:9 type:DvbS can tune to channel
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:    card:9 type:DvbS users: 0
2010-10-13 20:02:38.140625 [scheduler thread(16)]: Controller:    card:8 type:DvbT cannot tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:7 type:DvbT cannot tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:   channel #2 DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8 
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:10 type:DvbT can tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:10 type:DvbT users: 0
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:9 type:DvbS cannot tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:8 type:DvbT can tune to channel
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:8 type:DvbT users: 0
2010-10-13 20:02:38.156250 [scheduler thread(16)]: Controller:    card:7 type:DvbT can tune to channel
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:7 type:DvbT users: 0
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:11 type:RadioWebStream cannot tune to channel
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller: found 4 card(s) for channel
2010-10-13 20:02:38.171875 [scheduler thread(16)]: AdvancedCardAllocation.GetCardsForChannel took 29 msec
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:10 type:DvbT is available
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:9 type:DvbS is available
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:8 type:DvbT is available
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller:    card:7 type:DvbT is available
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller: found 4 free card(s)
2010-10-13 20:02:38.171875 [scheduler thread(16)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 33 msec
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Scheduler : record on recommended card:10 priority:5
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Controller: epg stop
2010-10-13 20:02:38.171875 [scheduler thread(16)]: Scheduler : record, first tune to channel
2010-10-13 20:02:38.171875 [scheduler thread(16)]: card: Tune 10 to BBC ONE
2010-10-13 20:02:38.171875 [scheduler thread(16)]: card: user: scheduler32:10:-1 tune DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 20:02:38.187500 [scheduler thread(16)]: dvbt: Tune:DVBT:tv:BBC BBC ONE Freq:578000 ONID:9018 TSID:4101 SID:4165 PMT:0x1045 FTA:True LCN:1 BandWidth:8
2010-10-13 20:02:38.187500 [scheduler thread(16)]: dvb:Submiting tunerequest Channel:BBC ONE subChannel:-1 
2010-10-13 20:02:38.187500 [scheduler thread(16)]: dvb:Getting new subchannel
2010-10-13 20:02:38.187500 [scheduler thread(16)]: dvb:GetNewSubChannel:0 #0
2010-10-13 20:02:38.203125 [scheduler thread(16)]: TvDvbChannel ctor new subchIndex:0
2010-10-13 20:02:38.203125 [scheduler thread(16)]: dvb:Submit tunerequest size:1 new:0
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 OnBeforeTune
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 OnAfterTune
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 OnGraphStart
2010-10-13 20:02:38.203125 [scheduler thread(16)]: dvb:  RunGraph
2010-10-13 20:02:38.203125 [scheduler thread(16)]: dvb:  LockedInOnSignal ok
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 OnGraphStarted
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 SetupPmtGrabber:pid 1045 sid:1045
2010-10-13 20:02:38.203125 [scheduler thread(16)]: subch:0 set pmt grabber pmt:1045 sid:1045
2010-10-13 20:02:38.203125 [scheduler thread(16)]: WaitForPMT: Waiting for PMT 1045
2010-10-13 20:02:38.250000 [(13)]: subch:0 OnPMTReceived() pmt:1045 ran:True dynamic:False
2010-10-13 20:02:38.250000 [scheduler thread(16)]: WaitForPMT: Found PMT after 0.046875 seconds.
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0 SendPmt:1045 1045 FFFFFFFF 1
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Decode pmt
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: PROBLEM : descriptor lengths dont match 6 9
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: PROBLEM : descriptor lengths dont match 6 9
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: PROBLEM : descriptor lengths dont match 10 13
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: Set descriptor data with length 3
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0 SendPMT: Channel is FTA, nothing to do.
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0 SetMpegPidMapping
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0  pid:258 pcr
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0  pid:1045 pmt
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0  pid:258 video type:MPEG-2
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0    map pid:258 video type:MPEG-2
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0  pid:259 audio lang: type:MPEG-1
2010-10-13 20:02:38.250000 [scheduler thread(16)]: subch:0    map pid:259 audio lang: type:MPEG-1
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:25A audio lang: type:MPEG-1
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:25D subtitle type:6
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0    map pid:25D subtitle type:6
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:261 type:5
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:3ED type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:3EE type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:3EF type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:28A type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:28B type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0  pid:28C type:B
2010-10-13 20:02:38.265625 [scheduler thread(16)]: subch:0 stop tif
2010-10-13 20:02:38.265625 [scheduler thread(16)]: WaitForPmt: PMT handling took 0.015625 seconds.
2010-10-13 20:02:38.265625 [scheduler thread(16)]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2010-10-13 20:02:39.281250 [scheduler thread(16)]: card: Tuner locked: True
2010-10-13 20:02:39.281250 [scheduler thread(16)]: **************************************************
2010-10-13 20:02:39.281250 [scheduler thread(16)]: ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 100 *****
2010-10-13 20:02:39.281250 [scheduler thread(16)]: **************************************************
2010-10-13 20:02:39.281250 [scheduler thread(16)]: card: tuned user: scheduler32 subchannel: 0
2010-10-13 20:02:39.281250 [scheduler thread(16)]: user:scheduler32 add
2010-10-13 20:02:39.296875 [scheduler thread(16)]: Scheduler: MakeFileName() using "moviesformat" (_isSerie=False)
2010-10-13 20:02:39.312500 [scheduler thread(16)]: Scheduler : record to F:\\Bang Goes the Theory\Bang Goes the Theory - 13-10-2010 19_30.ts
2010-10-13 20:02:39.328125 [scheduler thread(16)]: Recorder.start add audioVideoEventHandler
2010-10-13 20:02:39.328125 [scheduler thread(16)]: card: StartRecording 10 F:\\Bang Goes the Theory\Bang Goes the Theory - 13-10-2010 19_30.ts
2010-10-13 20:02:39.328125 [scheduler thread(16)]: StartRecording to F:\\Bang Goes the Theory\Bang Goes the Theory - 13-10-2010 19_30.ts
2010-10-13 20:02:39.328125 [scheduler thread(16)]: subch:0 StartRecord(F:\\Bang Goes the Theory\Bang Goes the Theory - 13-10-2010 19_30.ts)
2010-10-13 20:02:39.328125 [scheduler thread(16)]: subch:0-0 tswriter StartRecording...
2010-10-13 20:02:39.328125 [scheduler thread(16)]: SetRecorderPids
2010-10-13 20:02:39.328125 [scheduler thread(16)]: Set video / audio observer
2010-10-13 20:02:39.328125 [scheduler thread(16)]: DVB subch:0 Started recording
2010-10-13 20:02:39.343750 [scheduler thread(16)]: card: WaitForRecordingFile - waiting _eventAudio & _eventVideo
2010-10-13 20:02:39.343750 [(13)]: PID seen - type = Video
2010-10-13 20:02:39.343750 [(13)]: Recorder audioVideoEventHandler Video
2010-10-13 20:02:39.359375 [(13)]: PID seen - type = Audio
2010-10-13 20:02:39.359375 [(13)]: Recorder audioVideoEventHandler Audio
2010-10-13 20:02:39.359375 [scheduler thread(16)]: card: WaitForRecordingFile - video and audio are seen after 0.015625 seconds
2010-10-13 20:02:39.359375 [scheduler thread(16)]: Timeshifting epg grabber started.
2010-10-13 20:02:39.359375 [scheduler thread(16)]: dvb:grab epg...
2010-10-13 20:02:39.375000 [Channel state thread(11)]: Controller: DoSetChannelStates for 33 channels
2010-10-13 20:02:39.375000 [Channel state thread(11)]: ChannelStates.DoSetChannelStates took 0 msec
2010-10-13 20:02:39.375000 [scheduler thread(16)]: Scheduler: adding new row in db for title="Bang Goes the Theory" of type="0"
2010-10-13 20:02:39.453125 [scheduler thread(16)]: Scheduler: recList: count: 1 add scheduleid: 32 card: Hauppauge WinTV 88x DVB-T Tuner/Demod
2010-10-13 20:02:39.562500 [(13)]: PID seen - type = Audio
2010-10-13 20:02:41.671875 [(21)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 20:02:41.671875 [(21)]: PowerScheduler: User input detected at 13/10/2010 20:02:31
2010-10-13 20:03:06.687500 [(21)]: PowerScheduler: RegisterRemote tag: 6, uris: http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem, http://localhost:31458/b3462cb5_1819_4a5f_ac15_790c4cfdde04/bwanclwl+h0_kbc5njhlkr_f_1.rem
2010-10-13 20:03:06.687500 [(21)]: PowerScheduler: User input detected at 13/10/2010 20:03:06
2010-10-13 20:03:09.468750 [scheduler thread(16)]: Scheduler: stop record BBC ONE 13/10/2010 20:02:39-13/10/2010 20:00:00 Bang Goes the Theory
2010-10-13 20:03:09.468750 [scheduler thread(16)]: card: StopRecording card=10, user=scheduler32
2010-10-13 20:03:09.468750 [scheduler thread(16)]: card: StopRecording for card:10
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvdvbchannel.OnStopRecording subch=0, subch index=0
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvdvbchannel.OnStopRecording subch:0-0 tswriter StopRecording...
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2010-10-13 20:03:09.500000 [scheduler thread(16)]: DVB subch:0 Decompose()
2010-10-13 20:03:09.500000 [scheduler thread(16)]: FreeSubChannel CA: freeing sub channel : 0
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2010-10-13 20:03:09.500000 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 20:03:09.500000 [scheduler thread(16)]: dvb:PauseGraph
2010-10-13 20:03:09.500000 [scheduler thread(16)]: user:scheduler32 remove
2010-10-13 20:03:09.500000 [scheduler thread(16)]: dvb:PauseGraph called
2010-10-13 20:03:09.500000 [scheduler thread(16)]: tvcard:FreeAllSubChannels
2010-10-13 20:03:09.500000 [scheduler thread(16)]: dvb:StopGraph filterstate already paused, returning.
2010-10-13 20:03:09.515625 [Channel state thread(11)]: Controller: DoSetChannelStates for 33 channels
2010-10-13 20:03:09.515625 [Channel state thread(11)]: ChannelStates.DoSetChannelStates took 0 msec

Any ideas?

I've tried initializing tv service on resume, setting delays of 30sec on the tv server card detection, turning all sorts of things off and on

Just odd that it tunes in instantly from the client, but wont get a signal when client is idle.

Thanks!

glenn 1990 · October 13, 2010

Try the official 1.1.1.0 release first.

revs · October 13, 2010

I plan on going to 1.2 - i need the home/basic home chooser. So may skip 1.1.1

well..Maybe i'll try 1.1.1 and if it works ill make my own 1.1.1 with the home mod.

Depends how much free time I have to update the system.

robbo100 · October 14, 2010

There are options within the TV server config to reinitialise the tuner on recovery from standby, and also to delay activation of MP for X seconds. I am at work now, and I can't remember exactly what they are called, but they are easy to find.

Have you tried different settings?

Robbo100

revs · October 14, 2010

Thanks for the reply - yes have tried all the reinitialise and delay options, but still no luck

I've tried initializing tv service on resume, setting delays of 30sec on the tv server card detection, turning all sorts of things off and on

RobNorthcott · October 19, 2010

Are you using DVB EPG? I'm having a similar sounding issue and it only happens when the EPG grab is enabled. If I turn off the EPG grabbing then everything is happy.

My symptoms aren't _quite_ the same as yours (after returning from standby, attempting to tune any channel either manually or for a scheduled recording causes a hang), but could be related.

For now I'm just turning on EPG grab manually every few days to let it update, then turning it off again. EPG was working fine on my system until recently and at the moment I've got no idea what changed to break it.

Rob

revs · October 20, 2010

Thanks Rob. Will give it a go and see if it helps.

Just disabled all the EPG stuff. Still didn't record! Nevermind. Will have to try 1.2!

ced007 · October 21, 2010

Revs,
Do you have the problem for any channels (HD / SD)? Any of your 3 TV cards?
Did you check your "Power settings" on windows? It looks like the TV cards are not waking-up by themselves.

madcart · February 4, 2011

revs did you figure this out?

I am seeing something very similar - the tv server cannot tune the tv card when woken up from sleep by the scheduler.
I am using 1.1.0.0 also.

revs · February 5, 2011

No, I am going to look into the issue again after installing 1.2. 1.2 has changed the way the tv card is selected for recording, so hoping that sorts it!

TV Server won't record after wake up event (1 Viewer)

revs

MP Donator

glenn 1990

Portal Pro

revs

MP Donator

robbo100

Retired Team Member

revs

MP Donator

RobNorthcott

MP Donator

revs

MP Donator

ced007

Portal Pro

madcart

Portal Member

revs

MP Donator

Users who are viewing this thread