Normal
Hello Jonas,first of all thanx for your great plugin. Waht would MP be without PowerScheduler?I run MP 1.0 on a Windows Home Server machine. Alltough this platform is not supported it works quite well.Recordings are done as supposed and shutdown of the system also takes place as configured.My system should go to hibernation after 15min of idle. For the server I configured backup time from 18:00 to 20:00 each day. To wakeup the server to perform this tasks, I configured PowerScheduler to pick up EPG each day at 18:00 and prevent the system to go to standby during this time. So far everything works as desired...When I need data from the server or want to use MP, I wake up the server with an WOL call. After MP has started after resume, PowerScheduler shuts down the server right now without waiting to pass the configured idle time (in my case 15min). When I am fast enough to start the MP client, than the server stays on.[CODE]2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3285 , True2009-03-26 07:44:02.687500 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3285 , True2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True2009-03-26 07:44:02.718750 [20]: dvb:abort grabbing epg2009-03-26 07:44:04.656250 [20]: Timeshifting epg grabber stopped.2009-03-26 07:44:06.906250 [EPG Update thread]: EPG: database updated for #0 YOU FM2009-03-26 07:44:06.906250 [EPG Update thread]: user:epg remove2009-03-26 07:44:06.906250 [EPG Update thread]: tvcard:FreeSubChannel:1 #0 keep graph=False2009-03-26 07:44:06.906250 [EPG Update thread]: DVB subch:0 Decompose()2009-03-26 07:44:06.953125 [EPG Update thread]: FreeSubChannel CA: freeing sub channel : 02009-03-26 07:44:06.953125 [EPG Update thread]: tvcard:FreeAllSubChannels:2009-03-26 07:44:06.953125 [EPG Update thread]: dvb:StopGraph2009-03-26 07:44:10.390625 [EPG Update thread]: Stopcard2009-03-26 07:44:10.390625 [EPG Update thread]: tvcard:FreeAllSubChannels:2009-03-26 07:44:10.578125 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical2009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical2009-03-26 07:44:10.578125 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend2009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic2009-03-26 07:44:10.593750 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic2009-03-26 07:44:10.593750 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby2009-03-26 07:44:10.593750 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:44:10.593750 [WinController exit thread]: WindowsController: ExitWindows performed, result: True2009-03-26 07:44:31.562500 [26]: card: Tune 1 to domradio2009-03-26 07:44:31.562500 [26]: card: user: epg:1:-1 tune DVBS:radio:BetaDigital domradio Freq:12460000 ONID:133 TSID:5 SID:171 PMT:0x68 FTA:True LCN:174 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:44:31.562500 [26]: dvbs: Tune:DVBS:radio:BetaDigital domradio Freq:12460000 ONID:133 TSID:5 SID:171 PMT:0x68 FTA:True LCN:174 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:44:31.562500 [26]: dvb:GetNewSubChannel:0 #02009-03-26 07:44:31.578125 [26]: LNB low:9750 hi:10600 switch:117002009-03-26 07:44:31.578125 [26]: Channel modulation is set to ModNotSet2009-03-26 07:44:31.578125 [26]: Channel FECRate is set to Rate3_42009-03-26 07:44:31.687500 [26]: dvb:Submiting tunerequest Channel:domradio subChannel:02009-03-26 07:44:31.687500 [26]: dvb:using existing subchannel:02009-03-26 07:44:31.687500 [26]: dvb:Submit tunerequest size:1 new:02009-03-26 07:44:31.687500 [26]: subch:0 OnBeforeTune2009-03-26 07:44:31.718750 [26]: dvb:Submit tunerequest calling put_TuneRequest2009-03-26 07:44:31.812500 [26]: dvb:Submit tunerequest done calling put_TuneRequest2009-03-26 07:44:31.812500 [26]: subch:0 OnAfterTune2009-03-26 07:44:31.812500 [26]: subch:0 OnGraphStart2009-03-26 07:44:31.812500 [26]: subch:0 Graph already running - skip WaitForPMT2009-03-26 07:44:31.812500 [26]: dvb: RunGraph2009-03-26 07:44:32.328125 [26]: dvb: LockedInOnSignal ok2009-03-26 07:44:32.328125 [26]: subch:0 OnGraphStarted2009-03-26 07:44:32.328125 [26]: subch:0 SetupPmtGrabber:pid 68 sid:AB2009-03-26 07:44:32.328125 [26]: subch:0 set pmt grabber pmt:68 sid:AB2009-03-26 07:44:32.343750 [26]: WaitForPMT: Waiting for PMT.2009-03-26 07:44:32.468750 [13]: subch:0 OnPMTReceived() True2009-03-26 07:44:32.468750 [26]: WaitForPMT: Found PMT after 0,125 seconds.2009-03-26 07:44:32.468750 [26]: subch:0 SendPmt:AB AB FFFFFFFF B2009-03-26 07:44:32.468750 [26]: Decode pmt2009-03-26 07:44:32.468750 [26]: Set descriptor data with length 62009-03-26 07:44:32.468750 [26]: PROBLEM : descriptor lengths dont match 3 92009-03-26 07:44:32.468750 [26]: subch:0 SendPMT version:11 len:27 1712009-03-26 07:44:32.484375 [26]: subch:0 cam flags:True2009-03-26 07:44:32.484375 [26]: subch:0 SetMpegPidMapping2009-03-26 07:44:32.484375 [26]: subch:0 pid:170 pcr2009-03-26 07:44:32.484375 [26]: subch:0 pid:68 pmt2009-03-26 07:44:32.484375 [26]: subch:0 pid:170 audio lang:deu type:MPEG-22009-03-26 07:44:32.484375 [26]: subch:0 map pid:170 audio lang:deu type:MPEG-22009-03-26 07:44:32.500000 [26]: subch:0 stop tif2009-03-26 07:44:32.500000 [26]: WaitForPMT: sending PMT to CAM took 0,03125 seconds.2009-03-26 07:44:32.531250 [26]: card: Tuner locked: True2009-03-26 07:44:32.531250 [26]: **************************************************2009-03-26 07:44:32.531250 [26]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 94 *****2009-03-26 07:44:32.531250 [26]: **************************************************2009-03-26 07:44:32.531250 [26]: card: tuned user: epg subchannel: 02009-03-26 07:44:32.531250 [26]: user:epg add2009-03-26 07:44:32.531250 [DVB EPG timer]: dvb:grab epg...2009-03-26 07:45:01.546875 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:45:01.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:45:01.546875 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: Entering shutdown Hibernate ; forced: True -- kick off shutdown thread2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Shutdown thread is running: Hibernate, force: True2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Informing handlers about UserShutdownNow2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Shutdown is allowed True ; forced: True2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:45:01.578125 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed2009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:45:01.593750 [Powerscheduler Suspender]: PowerScheduler: Set wakeup timer to wakeup system in 253,973697916667 minutes2009-03-26 07:45:01.593750 [Powerscheduler Suspender]: PowerScheduler: Entering shutdown Hibernate ; forced: True2009-03-26 07:45:01.593750 [WinController exit thread]: WindowsController: Performing ExitWindows Hibernate, force: True2009-03-26 07:47:14.531250 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:47:14.531250 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3282 , True2009-03-26 07:47:14.546875 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed2009-03-26 07:47:14.546875 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3285 , True2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True2009-03-26 07:47:23.593750 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:47:23.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic2009-03-26 07:47:23.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic2009-03-26 07:47:23.609375 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby2009-03-26 07:47:23.625000 [WinController exit thread]: WindowsController: ExitWindows performed, result: True2009-03-26 07:47:23.625000 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:47:23.625000 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical2009-03-26 07:47:23.625000 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical2009-03-26 07:47:23.625000 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend2009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3290 , True2009-03-26 07:48:14.500000 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed2009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3290 , True2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: Entering shutdown Hibernate ; forced: True -- kick off shutdown thread2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Shutdown thread is running: Hibernate, force: True2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Informing handlers about UserShutdownNow2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Shutdown is allowed True ; forced: True2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:48:14.531250 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed2009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:48:14.546875 [Powerscheduler Suspender]: PowerScheduler: Set wakeup timer to wakeup system in 250,7578125 minutes2009-03-26 07:48:14.546875 [Powerscheduler Suspender]: PowerScheduler: Entering shutdown Hibernate ; forced: True2009-03-26 07:48:14.546875 [WinController exit thread]: WindowsController: Performing ExitWindows Hibernate, force: True2009-03-26 07:49:55.546875 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:522009-03-26 07:49:55.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:49:55.562500 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:592009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:002009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:002009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True2009-03-26 07:50:04.609375 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:50:04.609375 [PowerEventThread]: TV service PowerEventThread 302009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical2009-03-26 07:50:04.609375 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic2009-03-26 07:50:04.609375 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby2009-03-26 07:50:04.609375 [WinController exit thread]: WindowsController: ExitWindows performed, result: True2009-03-26 07:50:45.359375 [23]: Controller: StartTimeShifting TELE 5 22232009-03-26 07:50:45.406250 [23]: user:epg remove2009-03-26 07:50:45.406250 [23]: tvcard:FreeSubChannel:1 #0 keep graph=False2009-03-26 07:50:45.406250 [23]: DVB subch:0 Decompose()2009-03-26 07:50:46.437500 [23]: FreeSubChannel CA: freeing sub channel : 02009-03-26 07:50:46.437500 [23]: tvcard:FreeAllSubChannels:2009-03-26 07:50:46.437500 [23]: dvb:StopGraph2009-03-26 07:50:46.562500 [23]: Controller: find free card for channel TELE 52009-03-26 07:50:46.562500 [23]: Controller: got 2 tuning details for TELE 52009-03-26 07:50:46.562500 [23]: Controller: channel #1 DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:50:46.562500 [23]: Controller: card:1 type:DvbS is available priority:3 #users:0 same transponder:False2009-03-26 07:50:46.562500 [23]: Controller: card:2 type:DvbS is available priority:2 #users:0 same transponder:False2009-03-26 07:50:46.562500 [23]: Controller: card:3 type:RadioWebStream cannot tune to channel2009-03-26 07:50:46.562500 [23]: Controller: card:4 type:DvbS channel not mapped2009-03-26 07:50:46.562500 [23]: Controller: channel #2 DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:50:46.562500 [23]: Controller: card:3 type:RadioWebStream cannot tune to channel2009-03-26 07:50:46.562500 [23]: Controller: card:4 type:DvbS channel not mapped2009-03-26 07:50:46.562500 [23]: Controller: found 2 available2009-03-26 07:50:46.578125 [23]: card: CardTune 1 TELE 5 Coyote:1:-12009-03-26 07:50:46.578125 [23]: card: Tune 1 to TELE 52009-03-26 07:50:46.578125 [23]: card: user: Coyote:1:-1 tune DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:50:46.578125 [23]: dvbs: Tune:DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet2009-03-26 07:50:46.578125 [23]: dvb:GetNewSubChannel:0 #02009-03-26 07:50:46.593750 [23]: LNB low:9750 hi:10600 switch:117002009-03-26 07:50:46.593750 [23]: Channel modulation is set to ModNotSet2009-03-26 07:50:46.593750 [23]: Channel FECRate is set to Rate3_42009-03-26 07:50:46.703125 [23]: dvb:Submiting tunerequest Channel:TELE 5 subChannel:02009-03-26 07:50:46.703125 [23]: dvb:using existing subchannel:02009-03-26 07:50:46.703125 [23]: dvb:Submit tunerequest size:1 new:02009-03-26 07:50:46.703125 [23]: subch:0 OnBeforeTune2009-03-26 07:50:46.734375 [23]: dvb:Submit tunerequest calling put_TuneRequest2009-03-26 07:50:46.843750 [23]: dvb:Submit tunerequest done calling put_TuneRequest2009-03-26 07:50:46.843750 [23]: subch:0 OnAfterTune2009-03-26 07:50:46.843750 [23]: subch:0 OnGraphStart2009-03-26 07:50:46.843750 [23]: subch:0 Graph already running - skip WaitForPMT2009-03-26 07:50:46.843750 [23]: dvb: RunGraph2009-03-26 07:50:47.390625 [23]: dvb: LockedInOnSignal ok2009-03-26 07:50:47.390625 [23]: subch:0 OnGraphStarted2009-03-26 07:50:47.390625 [23]: subch:0 SetupPmtGrabber:pid 63 sid:332009-03-26 07:50:47.390625 [23]: subch:0 set pmt grabber pmt:63 sid:332009-03-26 07:50:47.421875 [23]: WaitForPMT: Waiting for PMT.2009-03-26 07:50:47.515625 [13]: subch:0 OnPMTReceived() True2009-03-26 07:50:47.515625 [23]: WaitForPMT: Found PMT after 0,09375 seconds.2009-03-26 07:50:47.515625 [23]: subch:0 SendPmt:33 33 FFFFFFFF 122009-03-26 07:50:47.515625 [23]: Decode pmt2009-03-26 07:50:47.515625 [23]: Set descriptor data with length 32009-03-26 07:50:47.515625 [23]: PROBLEM : descriptor lengths dont match 7 102009-03-26 07:50:47.515625 [23]: Set descriptor data with length 32009-03-26 07:50:47.515625 [23]: Set descriptor data with length 62009-03-26 07:50:47.515625 [23]: PROBLEM : descriptor lengths dont match 3 92009-03-26 07:50:47.515625 [23]: subch:0 SendPMT version:18 len:50 512009-03-26 07:50:47.515625 [23]: subch:0 cam flags:True2009-03-26 07:50:47.515625 [23]: subch:0 SetMpegPidMapping2009-03-26 07:50:47.515625 [23]: subch:0 pid:5FF pcr2009-03-26 07:50:47.515625 [23]: subch:0 pid:63 pmt2009-03-26 07:50:47.515625 [23]: subch:0 pid:26 teletext type:62009-03-26 07:50:47.515625 [23]: subch:0 map pid:26 teletext type:62009-03-26 07:50:47.515625 [23]: subch:0 pid:5FF video type:MPEG-22009-03-26 07:50:47.515625 [23]: subch:0 map pid:5FF video type:MPEG-22009-03-26 07:50:47.531250 [23]: subch:0 pid:600 audio lang:deu type:MPEG-12009-03-26 07:50:47.531250 [23]: subch:0 map pid:600 audio lang:deu type:MPEG-12009-03-26 07:50:47.562500 [23]: subch:0 stop tif2009-03-26 07:50:47.562500 [23]: WaitForPMT: sending PMT to CAM took 0,046875 seconds.2009-03-26 07:50:47.593750 [23]: card: Tuner locked: True2009-03-26 07:50:47.593750 [23]: **************************************************2009-03-26 07:50:47.593750 [23]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 94 *****2009-03-26 07:50:47.593750 [23]: **************************************************2009-03-26 07:50:47.593750 [23]: card: tuned user: Coyote subchannel: 02009-03-26 07:50:47.593750 [23]: user:Coyote add2009-03-26 07:50:47.593750 [23]: card2:Coyote 1 02009-03-26 07:50:47.593750 [23]: Controller: Coyote 1 02009-03-26 07:50:47.593750 [23]: control2:Coyote 1 02009-03-26 07:50:47.593750 [23]: Controller: delete timeshift files S:\Recordings\Timeshift\live1-0.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer1.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer2.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer3.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer4.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer5.ts2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer6.ts2009-03-26 07:50:47.640625 [23]: card: StartTimeShifting 1 S:\Recordings\Timeshift\live1-0.ts2009-03-26 07:50:47.640625 [23]: card: CAM enabled : False2009-03-26 07:50:47.640625 [23]: subch:0 SetTimeShiftFileName:S:\Recordings\Timeshift\live1-0.ts2009-03-26 07:50:47.640625 [23]: Set video / audio observer2009-03-26 07:50:47.671875 [23]: subch:0 SetTimeShiftFileName fill in pids2009-03-26 07:50:47.781250 [23]: subch:0-0 tswriter StartTimeshifting...2009-03-26 07:50:47.796875 [23]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo2009-03-26 07:50:47.906250 [13]: PID seen - type = Audio2009-03-26 07:50:47.906250 [13]: audioVideoEventHandler Audio2009-03-26 07:50:48.015625 [13]: PID seen - type = Video2009-03-26 07:50:48.015625 [13]: audioVideoEventHandler Video2009-03-26 07:50:48.015625 [23]: card: WaitForTimeShiftFile - video and audio are seen after 0,21875 seconds2009-03-26 07:50:48.218750 [23]: user:Coyote card:1 sub:0 add stream:S:\Recordings\Timeshift\live1-0.ts.tsbuffer2009-03-26 07:50:48.218750 [23]: RTSP: add stream stream1.0 file:S:\Recordings\Timeshift\live1-0.ts.tsbuffer2009-03-26 07:50:48.359375 [23]: Controller: StartTimeShifting started on card:1 to S:\Recordings\Timeshift\live1-0.ts.tsbuffer2009-03-26 07:50:48.359375 [Channel state thread]: Controller: DoSetChannelStates for 45 channels2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler: System changed from idle state to busy state2009-03-26 07:51:55.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:51:55.562500 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby2009-03-26 07:52:55.578125 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:52:55.578125 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby2009-03-26 07:53:55.593750 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:53:55.593750 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby2009-03-26 07:54:55.609375 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True2009-03-26 07:54:55.609375 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby[/CODE]As you see in the log, I woke up the server at 07:44 and it went to hibernation at 07:45.At 07:47 I woke up the server once again and managed to start the MP client fast enough to prevent an shutdown of the server.Concerning the monitoring of the network traffic the following ideas came to my mind:- To prevent shutdown of the server, check before shutdown if some clients are still running.Just make a list of all the clients that should prevent shutdown (e.g. client1, client2, client4 (client3 should not prevent shutdown)).- If a MP client is started, than it should start the MP server (maybe show a short message "Waiting for TV-server...").CUJens
Hello Jonas,
first of all thanx for your great plugin. Waht would MP be without PowerScheduler?
I run MP 1.0 on a Windows Home Server machine. Alltough this platform is not supported it works quite well.
Recordings are done as supposed and shutdown of the system also takes place as configured.
My system should go to hibernation after 15min of idle. For the server I configured backup time from 18:00 to 20:00 each day. To wakeup the server to perform this tasks, I configured PowerScheduler to pick up EPG each day at 18:00 and prevent the system to go to standby during this time. So far everything works as desired...
When I need data from the server or want to use MP, I wake up the server with an WOL call. After MP has started after resume, PowerScheduler shuts down the server right now without waiting to pass the configured idle time (in my case 15min). When I am fast enough to start the MP client, than the server stays on.
[CODE]
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3285 , True
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by
2009-03-26 07:44:02.687500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True
2009-03-26 07:44:02.718750 [20]: dvb:abort grabbing epg
2009-03-26 07:44:04.656250 [20]: Timeshifting epg grabber stopped.
2009-03-26 07:44:06.906250 [EPG Update thread]: EPG: database updated for #0 YOU FM
2009-03-26 07:44:06.906250 [EPG Update thread]: user:epg remove
2009-03-26 07:44:06.906250 [EPG Update thread]: tvcard:FreeSubChannel:1 #0 keep graph=False
2009-03-26 07:44:06.906250 [EPG Update thread]: DVB subch:0 Decompose()
2009-03-26 07:44:06.953125 [EPG Update thread]: FreeSubChannel CA: freeing sub channel : 0
2009-03-26 07:44:06.953125 [EPG Update thread]: tvcard:FreeAllSubChannels:
2009-03-26 07:44:06.953125 [EPG Update thread]: dvb:StopGraph
2009-03-26 07:44:10.390625 [EPG Update thread]: Stopcard
2009-03-26 07:44:10.390625 [EPG Update thread]: tvcard:FreeAllSubChannels:
2009-03-26 07:44:10.578125 [PowerEventThread]: TV service PowerEventThread 30
2009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical
2009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical
2009-03-26 07:44:10.578125 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend
2009-03-26 07:44:10.578125 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic
2009-03-26 07:44:10.593750 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2009-03-26 07:44:10.593750 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby
2009-03-26 07:44:10.593750 [PowerEventThread]: TV service PowerEventThread 30
2009-03-26 07:44:10.593750 [WinController exit thread]: WindowsController: ExitWindows performed, result: True
2009-03-26 07:44:31.562500 [26]: card: Tune 1 to domradio
2009-03-26 07:44:31.562500 [26]: card: user: epg:1:-1 tune DVBS:radio:BetaDigital domradio Freq:12460000 ONID:133 TSID:5 SID:171 PMT:0x68 FTA:True LCN:174 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:44:31.562500 [26]: dvbs: Tune:DVBS:radio:BetaDigital domradio Freq:12460000 ONID:133 TSID:5 SID:171 PMT:0x68 FTA:True LCN:174 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:44:31.562500 [26]: dvb:GetNewSubChannel:0 #0
2009-03-26 07:44:31.578125 [26]: LNB low:9750 hi:10600 switch:11700
2009-03-26 07:44:31.578125 [26]: Channel modulation is set to ModNotSet
2009-03-26 07:44:31.578125 [26]: Channel FECRate is set to Rate3_4
2009-03-26 07:44:31.687500 [26]: dvb:Submiting tunerequest Channel:domradio subChannel:0
2009-03-26 07:44:31.687500 [26]: dvb:using existing subchannel:0
2009-03-26 07:44:31.687500 [26]: dvb:Submit tunerequest size:1 new:0
2009-03-26 07:44:31.687500 [26]: subch:0 OnBeforeTune
2009-03-26 07:44:31.718750 [26]: dvb:Submit tunerequest calling put_TuneRequest
2009-03-26 07:44:31.812500 [26]: dvb:Submit tunerequest done calling put_TuneRequest
2009-03-26 07:44:31.812500 [26]: subch:0 OnAfterTune
2009-03-26 07:44:31.812500 [26]: subch:0 OnGraphStart
2009-03-26 07:44:31.812500 [26]: subch:0 Graph already running - skip WaitForPMT
2009-03-26 07:44:31.812500 [26]: dvb: RunGraph
2009-03-26 07:44:32.328125 [26]: dvb: LockedInOnSignal ok
2009-03-26 07:44:32.328125 [26]: subch:0 OnGraphStarted
2009-03-26 07:44:32.328125 [26]: subch:0 SetupPmtGrabber:pid 68 sid:AB
2009-03-26 07:44:32.328125 [26]: subch:0 set pmt grabber pmt:68 sid:AB
2009-03-26 07:44:32.343750 [26]: WaitForPMT: Waiting for PMT.
2009-03-26 07:44:32.468750 [13]: subch:0 OnPMTReceived() True
2009-03-26 07:44:32.468750 [26]: WaitForPMT: Found PMT after 0,125 seconds.
2009-03-26 07:44:32.468750 [26]: subch:0 SendPmt:AB AB FFFFFFFF B
2009-03-26 07:44:32.468750 [26]: Decode pmt
2009-03-26 07:44:32.468750 [26]: Set descriptor data with length 6
2009-03-26 07:44:32.468750 [26]: PROBLEM : descriptor lengths dont match 3 9
2009-03-26 07:44:32.468750 [26]: subch:0 SendPMT version:11 len:27 171
2009-03-26 07:44:32.484375 [26]: subch:0 cam flags:True
2009-03-26 07:44:32.484375 [26]: subch:0 SetMpegPidMapping
2009-03-26 07:44:32.484375 [26]: subch:0 pid:170 pcr
2009-03-26 07:44:32.484375 [26]: subch:0 pid:68 pmt
2009-03-26 07:44:32.484375 [26]: subch:0 pid:170 audio lang:deu type:MPEG-2
2009-03-26 07:44:32.484375 [26]: subch:0 map pid:170 audio lang:deu type:MPEG-2
2009-03-26 07:44:32.500000 [26]: subch:0 stop tif
2009-03-26 07:44:32.500000 [26]: WaitForPMT: sending PMT to CAM took 0,03125 seconds.
2009-03-26 07:44:32.531250 [26]: card: Tuner locked: True
2009-03-26 07:44:32.531250 [26]: **************************************************
2009-03-26 07:44:32.531250 [26]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 94 *****
2009-03-26 07:44:32.531250 [26]: card: tuned user: epg subchannel: 0
2009-03-26 07:44:32.531250 [26]: user:epg add
2009-03-26 07:44:32.531250 [DVB EPG timer]: dvb:grab epg...
2009-03-26 07:45:01.546875 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:45:01.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:45:01.546875 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by
2009-03-26 07:45:01.562500 [Powerscheduler Timer]: PowerScheduler: Entering shutdown Hibernate ; forced: True -- kick off shutdown thread
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Shutdown thread is running: Hibernate, force: True
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Informing handlers about UserShutdownNow
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:45:01.562500 [Powerscheduler Suspender]: PowerScheduler: Shutdown is allowed True ; forced: True
2009-03-26 07:45:01.578125 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed
2009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:45:01.578125 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:45:01.593750 [Powerscheduler Suspender]: PowerScheduler: Set wakeup timer to wakeup system in 253,973697916667 minutes
2009-03-26 07:45:01.593750 [Powerscheduler Suspender]: PowerScheduler: Entering shutdown Hibernate ; forced: True
2009-03-26 07:45:01.593750 [WinController exit thread]: WindowsController: Performing ExitWindows Hibernate, force: True
2009-03-26 07:47:14.531250 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:47:14.531250 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3282 , True
2009-03-26 07:47:14.546875 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed
2009-03-26 07:47:14.546875 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3285 , True
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by
2009-03-26 07:47:14.562500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True
2009-03-26 07:47:23.593750 [PowerEventThread]: TV service PowerEventThread 30
2009-03-26 07:47:23.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic
2009-03-26 07:47:23.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2009-03-26 07:47:23.609375 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby
2009-03-26 07:47:23.625000 [WinController exit thread]: WindowsController: ExitWindows performed, result: True
2009-03-26 07:47:23.625000 [PowerEventThread]: TV service PowerEventThread 30
2009-03-26 07:47:23.625000 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical
2009-03-26 07:47:23.625000 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical
2009-03-26 07:47:23.625000 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend
2009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3290 , True
2009-03-26 07:48:14.500000 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed
2009-03-26 07:48:14.500000 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3290 , True
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by
2009-03-26 07:48:14.515625 [Powerscheduler Timer]: PowerScheduler: Entering shutdown Hibernate ; forced: True -- kick off shutdown thread
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Shutdown thread is running: Hibernate, force: True
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Informing handlers about UserShutdownNow
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:48:14.515625 [Powerscheduler Suspender]: PowerScheduler: Shutdown is allowed True ; forced: True
2009-03-26 07:48:14.531250 [Powerscheduler Suspender]: NetworkMonitorHandler: standby allowed
2009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:48:14.531250 [Powerscheduler Suspender]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:48:14.546875 [Powerscheduler Suspender]: PowerScheduler: Set wakeup timer to wakeup system in 250,7578125 minutes
2009-03-26 07:48:14.546875 [Powerscheduler Suspender]: PowerScheduler: Entering shutdown Hibernate ; forced: True
2009-03-26 07:48:14.546875 [WinController exit thread]: WindowsController: Performing ExitWindows Hibernate, force: True
2009-03-26 07:49:55.546875 [Powerscheduler Timer]: PowerScheduler: User input detected at 25.03.2009 19:08:52
2009-03-26 07:49:55.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: NetworkMonitorHandler: standby allowed
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:GenericWakeupHandler time:31.12.9999 23:59:59
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:ScheduledRecordingsHandler time:26.03.2009 12:00:00
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler.NextWakeupTime: inspecting handler:EpgGrabbingHandler time:26.03.2009 18:00:00
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: Manual system suspend requested by
2009-03-26 07:49:55.562500 [Powerscheduler Timer]: PowerScheduler: Concurrent shutdown was ignored: Hibernate ; force: True
2009-03-26 07:50:04.609375 [PowerEventThread]: TV service PowerEventThread 30
2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeCritical
2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeCritical
2009-03-26 07:50:04.609375 [PowerEventThread]: PowerScheduler: System has resumed from standby after a critical suspend
2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEvent: PowerStatus: ResumeAutomatic
2009-03-26 07:50:04.609375 [PowerEventThread]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2009-03-26 07:50:04.609375 [PowerEventThread]: PowerScheduler: System has resumed automatically from standby
2009-03-26 07:50:04.609375 [WinController exit thread]: WindowsController: ExitWindows performed, result: True
2009-03-26 07:50:45.359375 [23]: Controller: StartTimeShifting TELE 5 2223
2009-03-26 07:50:45.406250 [23]: user:epg remove
2009-03-26 07:50:45.406250 [23]: tvcard:FreeSubChannel:1 #0 keep graph=False
2009-03-26 07:50:45.406250 [23]: DVB subch:0 Decompose()
2009-03-26 07:50:46.437500 [23]: FreeSubChannel CA: freeing sub channel : 0
2009-03-26 07:50:46.437500 [23]: tvcard:FreeAllSubChannels:
2009-03-26 07:50:46.437500 [23]: dvb:StopGraph
2009-03-26 07:50:46.562500 [23]: Controller: find free card for channel TELE 5
2009-03-26 07:50:46.562500 [23]: Controller: got 2 tuning details for TELE 5
2009-03-26 07:50:46.562500 [23]: Controller: channel #1 DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:50:46.562500 [23]: Controller: card:1 type:DvbS is available priority:3 #users:0 same transponder:False
2009-03-26 07:50:46.562500 [23]: Controller: card:2 type:DvbS is available priority:2 #users:0 same transponder:False
2009-03-26 07:50:46.562500 [23]: Controller: card:3 type:RadioWebStream cannot tune to channel
2009-03-26 07:50:46.562500 [23]: Controller: card:4 type:DvbS channel not mapped
2009-03-26 07:50:46.562500 [23]: Controller: channel #2 DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:50:46.562500 [23]: Controller: found 2 available
2009-03-26 07:50:46.578125 [23]: card: CardTune 1 TELE 5 Coyote:1:-1
2009-03-26 07:50:46.578125 [23]: card: Tune 1 to TELE 5
2009-03-26 07:50:46.578125 [23]: card: user: Coyote:1:-1 tune DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:50:46.578125 [23]: dvbs: Tune:DVBS:tv:BetaDigital TELE 5 Freq:12480000 ONID:133 TSID:33 SID:51 PMT:0x63 FTA:True LCN:2223 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2009-03-26 07:50:46.578125 [23]: dvb:GetNewSubChannel:0 #0
2009-03-26 07:50:46.593750 [23]: LNB low:9750 hi:10600 switch:11700
2009-03-26 07:50:46.593750 [23]: Channel modulation is set to ModNotSet
2009-03-26 07:50:46.593750 [23]: Channel FECRate is set to Rate3_4
2009-03-26 07:50:46.703125 [23]: dvb:Submiting tunerequest Channel:TELE 5 subChannel:0
2009-03-26 07:50:46.703125 [23]: dvb:using existing subchannel:0
2009-03-26 07:50:46.703125 [23]: dvb:Submit tunerequest size:1 new:0
2009-03-26 07:50:46.703125 [23]: subch:0 OnBeforeTune
2009-03-26 07:50:46.734375 [23]: dvb:Submit tunerequest calling put_TuneRequest
2009-03-26 07:50:46.843750 [23]: dvb:Submit tunerequest done calling put_TuneRequest
2009-03-26 07:50:46.843750 [23]: subch:0 OnAfterTune
2009-03-26 07:50:46.843750 [23]: subch:0 OnGraphStart
2009-03-26 07:50:46.843750 [23]: subch:0 Graph already running - skip WaitForPMT
2009-03-26 07:50:46.843750 [23]: dvb: RunGraph
2009-03-26 07:50:47.390625 [23]: dvb: LockedInOnSignal ok
2009-03-26 07:50:47.390625 [23]: subch:0 OnGraphStarted
2009-03-26 07:50:47.390625 [23]: subch:0 SetupPmtGrabber:pid 63 sid:33
2009-03-26 07:50:47.390625 [23]: subch:0 set pmt grabber pmt:63 sid:33
2009-03-26 07:50:47.421875 [23]: WaitForPMT: Waiting for PMT.
2009-03-26 07:50:47.515625 [13]: subch:0 OnPMTReceived() True
2009-03-26 07:50:47.515625 [23]: WaitForPMT: Found PMT after 0,09375 seconds.
2009-03-26 07:50:47.515625 [23]: subch:0 SendPmt:33 33 FFFFFFFF 12
2009-03-26 07:50:47.515625 [23]: Decode pmt
2009-03-26 07:50:47.515625 [23]: Set descriptor data with length 3
2009-03-26 07:50:47.515625 [23]: PROBLEM : descriptor lengths dont match 7 10
2009-03-26 07:50:47.515625 [23]: Set descriptor data with length 6
2009-03-26 07:50:47.515625 [23]: PROBLEM : descriptor lengths dont match 3 9
2009-03-26 07:50:47.515625 [23]: subch:0 SendPMT version:18 len:50 51
2009-03-26 07:50:47.515625 [23]: subch:0 cam flags:True
2009-03-26 07:50:47.515625 [23]: subch:0 SetMpegPidMapping
2009-03-26 07:50:47.515625 [23]: subch:0 pid:5FF pcr
2009-03-26 07:50:47.515625 [23]: subch:0 pid:63 pmt
2009-03-26 07:50:47.515625 [23]: subch:0 pid:26 teletext type:6
2009-03-26 07:50:47.515625 [23]: subch:0 map pid:26 teletext type:6
2009-03-26 07:50:47.515625 [23]: subch:0 pid:5FF video type:MPEG-2
2009-03-26 07:50:47.515625 [23]: subch:0 map pid:5FF video type:MPEG-2
2009-03-26 07:50:47.531250 [23]: subch:0 pid:600 audio lang:deu type:MPEG-1
2009-03-26 07:50:47.531250 [23]: subch:0 map pid:600 audio lang:deu type:MPEG-1
2009-03-26 07:50:47.562500 [23]: subch:0 stop tif
2009-03-26 07:50:47.562500 [23]: WaitForPMT: sending PMT to CAM took 0,046875 seconds.
2009-03-26 07:50:47.593750 [23]: card: Tuner locked: True
2009-03-26 07:50:47.593750 [23]: **************************************************
2009-03-26 07:50:47.593750 [23]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 94 *****
2009-03-26 07:50:47.593750 [23]: card: tuned user: Coyote subchannel: 0
2009-03-26 07:50:47.593750 [23]: user:Coyote add
2009-03-26 07:50:47.593750 [23]: card2:Coyote 1 0
2009-03-26 07:50:47.593750 [23]: Controller: Coyote 1 0
2009-03-26 07:50:47.593750 [23]: control2:Coyote 1 0
2009-03-26 07:50:47.593750 [23]: Controller: delete timeshift files S:\Recordings\Timeshift\live1-0.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer1.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer2.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer3.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer4.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer5.ts
2009-03-26 07:50:47.640625 [23]: Controller: delete S:\Recordings\Timeshift\live1-0.ts.tsbuffer6.ts
2009-03-26 07:50:47.640625 [23]: card: StartTimeShifting 1 S:\Recordings\Timeshift\live1-0.ts
2009-03-26 07:50:47.640625 [23]: card: CAM enabled : False
2009-03-26 07:50:47.640625 [23]: subch:0 SetTimeShiftFileName:S:\Recordings\Timeshift\live1-0.ts
2009-03-26 07:50:47.640625 [23]: Set video / audio observer
2009-03-26 07:50:47.671875 [23]: subch:0 SetTimeShiftFileName fill in pids
2009-03-26 07:50:47.781250 [23]: subch:0-0 tswriter StartTimeshifting...
2009-03-26 07:50:47.796875 [23]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo
2009-03-26 07:50:47.906250 [13]: PID seen - type = Audio
2009-03-26 07:50:47.906250 [13]: audioVideoEventHandler Audio
2009-03-26 07:50:48.015625 [13]: PID seen - type = Video
2009-03-26 07:50:48.015625 [13]: audioVideoEventHandler Video
2009-03-26 07:50:48.015625 [23]: card: WaitForTimeShiftFile - video and audio are seen after 0,21875 seconds
2009-03-26 07:50:48.218750 [23]: user:Coyote card:1 sub:0 add stream:S:\Recordings\Timeshift\live1-0.ts.tsbuffer
2009-03-26 07:50:48.218750 [23]: RTSP: add stream stream1.0 file:S:\Recordings\Timeshift\live1-0.ts.tsbuffer
2009-03-26 07:50:48.359375 [23]: Controller: StartTimeShifting started on card:1 to S:\Recordings\Timeshift\live1-0.ts.tsbuffer
2009-03-26 07:50:48.359375 [Channel state thread]: Controller: DoSetChannelStates for 45 channels
2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby
2009-03-26 07:50:55.546875 [Powerscheduler Timer]: PowerScheduler: System changed from idle state to busy state
2009-03-26 07:51:55.562500 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:51:55.562500 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby
2009-03-26 07:52:55.578125 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:52:55.578125 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby
2009-03-26 07:53:55.593750 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:53:55.593750 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby
2009-03-26 07:54:55.609375 [Powerscheduler Timer]: PowerScheduler: lastUserTime: 19:08:52.3288 , True
2009-03-26 07:54:55.609375 [Powerscheduler Timer]: PowerScheduler.DisAllowShutdown: handler ActiveStreamsHandler wants to prevent standby
[/CODE]
As you see in the log, I woke up the server at 07:44 and it went to hibernation at 07:45.
At 07:47 I woke up the server once again and managed to start the MP client fast enough to prevent an shutdown of the server.
Concerning the monitoring of the network traffic the following ideas came to my mind:
- To prevent shutdown of the server, check before shutdown if some clients are still running.
Just make a list of all the clients that should prevent shutdown (e.g. client1, client2, client4 (client3 should not prevent shutdown)).
- If a MP client is started, than it should start the MP server (maybe show a short message "Waiting for TV-server...").
CU
Jens