2.5.5 - Not waking up on time

[Nistrod]

I set NPVR to record A Bugs Life @ 1900, 15th August.
However, it missed the first hour.
Looking at the log, it didn't set a valid wakeup.
Any suggestions as to why?

The current list of wakeups looks fine - it matches the pending recordings.

Code:

2012-08-15 02:08:31.234    [DEBUG][7]    Cancelling wake up request for for 'RecordingOID3947'
2012-08-15 02:08:31.234    [DEBUG][7]    Cancelling wake up request for for 'RecordingOID3961'
2012-08-15 02:08:31.234    [DEBUG][7]    Cancelling wake up request for for 'RecordingOID3958'
2012-08-15 02:08:31.234    [DEBUG][7]    Cancelling wake up request for for 'RecordingOID3957'
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3959' at 15/08/2012 6:57 PM
[B]2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3964' at 15/08/2012 7:56 PM[/B]
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3976' at 15/08/2012 8:30 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3980' at 15/08/2012 8:32 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3977' at 15/08/2012 9:43 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3960' at 16/08/2012 6:57 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3979' at 16/08/2012 8:32 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3970' at 16/08/2012 11:16 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3973' at 17/08/2012 11:43 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3967' at 18/08/2012 7:28 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3971' at 18/08/2012 8:26 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3969' at 18/08/2012 10:00 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3963' at 18/08/2012 10:31 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3968' at 19/08/2012 1:56 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3981' at 19/08/2012 8:27 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3975' at 20/08/2012 9:29 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3972' at 21/08/2012 5:58 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3982' at 21/08/2012 8:27 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3965' at 21/08/2012 9:26 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3974' at 21/08/2012 9:28 PM
2012-08-15 02:08:31.235    [DEBUG][7]    Requesting wake up for 'RecordingOID3966' at 22/08/2012 7:56 PM
2012-08-15 02:08:31.236    [DEBUG][7]    Requesting wake up for 'RecordingOID3978' at 22/08/2012 9:42 PM
2012-08-15 07:08:45.264    [DEBUG][6]    Resuming...
2012-08-15 07:08:45.306    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 07:08:45.306    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 07:08:45.483    [DEBUG][6]    Resuming...
2012-08-15 07:08:45.606    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 07:08:45.606    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 07:08:46.514    [DEBUG][5]    cycling MVP servers
2012-08-15 09:00:01.131    [DEBUG][15]    Resuming...
2012-08-15 09:00:01.322    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 09:00:02.522    [DEBUG][5]    cycling MVP servers
2012-08-15 09:00:04.603    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 09:23:21.155    [DEBUG][12]    Resuming...
2012-08-15 09:23:21.209    [DEBUG][12]    Resuming...
2012-08-15 09:23:21.407    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 09:23:21.407    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 09:23:22.516    [DEBUG][5]    cycling MVP servers
2012-08-15 16:31:44.488    [DEBUG][3]    Resuming...
2012-08-15 16:31:44.488    [DEBUG][3]    Resuming...
2012-08-15 16:31:44.847    [DEBUG][18]    Resuming...
2012-08-15 16:31:46.402    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 16:31:46.527    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 16:31:47.513    [DEBUG][5]    cycling MVP servers
[B]2012-08-15 19:57:21.379    [DEBUG][18]    Resuming...[/B]
2012-08-15 19:57:23.167    [DEBUG][5]    cycling MVP servers
2012-08-15 19:57:23.554    [DEBUG][7]    PurgeOldDeletes()
2012-08-15 19:57:23.554    [DEBUG][7]    Recording service noted the system was resuming...
2012-08-15 19:57:23.554    [DEBUG][7]    No D:\NextPVR\Scripts\Wakeup.bat
2012-08-15 19:57:23.601    [DEBUG][7]    allocating recording target filename: D:\Recorded TV\Movie - A Bugs Life\Movie - A Bugs Life_[B]20120815_19002100[/B].ts
2012-08-15 19:57:23.601    [DEBUG][7]    About to start recording (3959 on 22): D:\Recorded TV\Movie - A Bugs Life\Movie - A Bugs Life_20120815_19002100.ts...

[johnsonx42]

you'd have to ask windows why it didn't wake up. the wakeup request for bugs life was properly requested for 6:57pm, however the system slept through it until it woke for some other recording at 7:56pm (maybe it was really tired and kept hitting Snooze?). you bolded the wrong wakeup request by the way.

[Nistrod]

So I did. Old age creeps upon me.

[jcjefferies]

I have had this happen on a few occasions and traced it to the fact that Windows was entering "Sleep" when the wakeup was due. I know from my desktop computer that you cannot Wake it while it is in the process of going into sleep but have to wait until it is fully asleep before it responds to a wakeup in this case from keyboard or mouse.

[Nistrod]

Mmm. PC is set to sleep after 1 hour. It would be a coincidence if I just happened to stop at 17:57. (Pause)
OK, I checked the event log, there is an entry at 18:57 saying the system is Entering Sleep for System Idle reasons.
Looks like you were right.

Sub, its an unlikely problem, but could we schedule two wakeups, a few minutes apart?

[johnsonx42]

One wonders why Windows is so stupid that it would go to sleep at the same time a wakeup is scheduled... shouldn't the power management code check that there are no wakeups scheduled in the next 5-10 minutes before sleeping? And this is Windows 7 too; I might be more understanding of such oversights in Windows 2000 or maybe even XP, but it seems ridiculous to find this in Windows 7 (SP1 even!). I wonder if Windows 8 has the same bug? Probably does...

As Nistrod suggests, having 2 wakeups for each recording would be the only way to work around this. Seems a little silly to have to do it though...

[jcjefferies]

I changed to "Hibernate" for a while last year and because it takes much longer to get into that mode I missed several "Wakeups" so switched back to "Sleep". I just checked my NextPVR Vista server which took 5 seconds to "Sleep" but my desktop PC took 28 seconds which seems excessive! A second wakeup a bit later was something I was going to suggest but had not bothered as the problem happens so infrequently. I suppose so few Windows users use scheduled wakeup that the occasional missed wakeup may not have been reported.

[HansJoachim]

I'm using 2.5.5 with Windows 7 and my PC doesn't wake up for scheduled recordings. I didn't have that problem with previous versions of NPVR. Any idea how I can make my PC wake up for scheduled recordings?

[johnsonx42]

Nothing has changed in NPVR 2.5.5 in that regard; it just asks Windows to wake up at specified times, and depends on Windows to do it's job.

You can post your logs so that we can verify the wakeup requests are being scheduled.

[mvallevand]

I never really used this feature before 2.5.5 but it has been waking up flawlessly for me.

Martin