no automatic epg update

[ww4397]

I lowered the number of days to 7 and still no joy! Here is the segment of the logs for today. I've also displayed the lines out of WindowsUpdate.log that show when the command to go back to sleep was issued and when I re-woke the machine manually.

Any more ideas?

2/2/2007 5:00:05 AM.515 VERBOSE [13] Will run EPG update soon...
2/2/2007 5:00:35 AM.531 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:01:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:01:35 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:02:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:02:35 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:03:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:03:35 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:04:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:04:35 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:05:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:05:35 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:06:05 AM.593 VERBOSE [13] Keeping machine awake for pending EPG update...
2/2/2007 5:06:05 AM.593 VERBOSE [13] Initiating EPG update...
2/2/2007 5:06:05 AM.593 VERBOSE [13] exporting backup of recordings data...
2/2/2007 5:06:05 AM.765 VERBOSE [13] RecordingFactory.loadSchedule()
2/2/2007 5:06:05 AM.765 VERBOSE [13] getValue cached value: /settings/AutoRemoveMissingRecordings : true
2/2/2007 5:06:06 AM.046 VERBOSE [13] updating EPG...
2/2/2007 5:06:06 AM.109 INFO [13] Auto-Updating EPG
2/2/2007 5:06:06 AM.109 VERBOSE [13] getValue() loading new key/value into cache: /settings/Zap2itListingDays
2/2/2007 5:06:06 AM.125 VERBOSE [13] getValue: /settings/Zap2itListingDays : 7
2/2/2007 5:06:06 AM.125 VERBOSE [13] getValue cached value: /settings/CompleteEPGReload : true
2/2/2007 5:06:06 AM.125 VERBOSE [13] Zap2it full EPG update required.
2/2/2007 5:06:06 AM.203 VERBOSE [13] Processing zap2it channels using SAX parser
2/2/2007 5:06:06 AM.203 VERBOSE [13] Requesting listings for the period: 2007-02-02T00:00:00Z to 2007-02-09T23:59:59Z
2/2/2007 6:00:59 AM.062 VERBOSE [13] Error updating EPG Source (PVR150): System.Net.WebException: The operation has timed out.
at System.Net.WebClient.UploadDataInternal(Uri address, String method, Byte[] data, WebRequest& request)
at System.Net.WebClient.UploadData(Uri address, String method, Byte[] data)
at System.Net.WebClient.UploadData(String address, String method, Byte[] data)
at GBPVR.Backend.Common.Zap2itEpgReader.x7174d5399cba81ee(IEpgUpdateProgressCallback x421ce789b9741187, DateTime xdc88f59e43b65f7f, DateTime x33db96d2cea26583)
at GBPVR.Backend.Common.Zap2itEpgReader.LoadChannels(IEpgUpdateProgressCallback callback)
at GBPVR.RecordingService.xa9e17cce0eee6a7a.x52e8ddd0b9bc8c68()

Here is a line from WindowsUpdate.log that shows the time it went back to sleep. It looks lit 1 minute and 48 seconds after GBPVR was Initiating EPG update... Windows went back to sleep. The 53 minutes and 3 seconds later I woke the machine manually.

2007-02-02 05:07:53 1060 844 AU AU received hibernate event
2007-02-02 06:00:56 1060 844 AU AU received wakeup event

[ww4397]

I guess you could also increase the time-before-goto-sleep in windows-config-energy-settings.

I've been uisng 45 minutes then go-to-sleep. Isn't that enough time?

[HtV]

I've been uisng 45 minutes then go-to-sleep. Isn't that enough time?

I guess that should be, how long does the updfate take normally?
At what time did the system wake up for the epg-dl? Who or what gave the hibernation command?:confused:

[ww4397]

I guess that should be, how long does the updfate take normally?

About 15 minutes.

At what time did the system wake up for the epg-dl?

0458 for a 0500 EPG update.

Who or what gave the hibernation command?:confused:

I don't know who/what is giving the hibernation command. I'm confused, too.

Another observation, in the WindowsUpdate.log file there are a bunch of entries regarding Windows Update -- checking for needed updates, etc. These are all happening when the machine comes out of standby (i.e. starting at 0458). I'm guessing here, but could the Windows Update process be giving the hibernation command after it is finished doing its checks, etc.? Is that the way Windows Update works? Is it taking advantage of the fact that GBPVR is waking the machine for an EPG update and hijacking the system to check for updates and then putting it back into standby when it is finished? How can I find out what is giving the hibernation command?

I'll try turning Windows Automatic Updates off and see if anything changes.

[sub]

Is it taking advantage of the fact that GBPVR is waking the machine for an EPG update and hijacking the system to check for updates and then putting it back into standby when it is finished? How can I find out what is giving the hibernation command?

GB-PVR never tells a machine to hibernate or to standby - it'll only ever tell the machine to wake at certain times. During the EPG update it tries to tell the machine to stay awake at various stages, including just before the request is sent to zap2it, and just after the response is received - but this must eventually "wear off" somewhere between those two points, and whatever auto-hibernate stuff you've got configured must kick in.

[replaytv]

I don't know if this is anything to do with it or not (probably not!) but....
I use xmltv to get my EPG and use Windows scheduler to get it (and import to gbpvr later on). I have it set to grab it at 02:55 and 02:57. The first is to wake the machine up out of hibernate and the network is working fine for pinging something by ip number, but DNS requests fail. The second grab works fine.

I've not spent a lot of time trying to find where the problem lies as yet.

Martin

I am doing similar to this with grab xmlt with dial-up connection and use the postupdateEPG and GBPVR wakeup to automate the process.

Dan L.

Edit this PostUpdateEPG.bat to your setup -


REM PostUpdateEPG.bat for dial-up user
REM Put PostUpdateEPG.bat in the c:\progra~1\devnz\gbpvr\ for nightly update
REM download NirCMD from http://www.nirsoft.net/utils/nircmd.html in put in c:\windows\system32
REM USA - download XMLTV from http://sourceforge.net/projects/xmltv/ and configure it before use
REM psshutdown - download from http://www.sysinternals.com
REM change the directory name for your setup

devcon disable *DEV_104B

ping -n 10 127.0.0.1

REM connect to dial-up - replace "No Charge" with your dialup name

REM nircmd.exe rasdial "No Charge"

REM Empty schedule if wanted
REM c:\progra~1\devnz\gbpvr\gbpvr.exe -OnlyEmptyEPG
ping -n 10 127.0.0.1

REM backup tvguide

cd c:\xmltv-0.5.44-win32
move myguide1.xml myguide2.xml
copy myguide.xml myguide1.xml

REM download new tvguide
REM run one time config xmltv with this command - xmltv.exe tv_grab_na_dd --configure


cd c:\xmltv-0.5.44-win32
c:\xmltv-0.5.44-win32\xmltv.exe tv_grab_na_dd --days 7 --output myguide.xml


ping -n 10 127.0.0.1
echo "Downloading TV Guide "> c:\updateinprogress.txt
ping -n 60 127.0.0.1
time /t >> c:\updateinprogress.txt
echo "in progress" >> c:\updateinprogress.txt
ping -n 10 127.0.0.1
time /t >> c:\updateinprogress.txt
echo "in progress" >> c:\updateinprogress.txt
ping -n 10 127.0.0.1
time /t >> c:\updateinprogress.txt
echo "in progress" >> c:\updateinprogress.txt
ping -n 10 127.0.0.1
time /t >> c:\updateinprogress.txt
echo "in progress...end" >> c:\updateinprogress.txt

REM disconnect dial-up

REM nircmd.exe rashangup "No Charge"

REM Hibernate

ping -n 20 127.0.0.1
REM c:\shutdown.bat
REM nircmd.exe hibernate

FOR %%A IN (6 7 8 9) DO (
netstat -an|find ":590%%A"|find /i "establ" > NUL
IF NOT ERRORLEVEL 1 goto end
)

REM pskill.exe gbpvr.exe

:end

[ww4397]

I'll try turning Windows Automatic Updates off and see if anything changes.

Okay, I think the EPG updated this time (with Windows Automatic Update turned (WAU) off). Here is a snip-it of the logs. Sub, can you confirm that the logs indicate a successful EPG update? I don't see an explicit statement saying so in the log:

2/3/2007 5:00:06 AM.937 VERBOSE [12] Will run EPG update soon...
2/3/2007 5:00:36 AM.937 VERBOSE [12] Keeping machine awake for pending EPG update...
2/3/2007 5:00:36 AM.937 VERBOSE [12] Initiating EPG update...
2/3/2007 5:00:36 AM.937 VERBOSE [12] exporting backup of recordings data...
2/3/2007 5:00:36 AM.968 VERBOSE [12] RecordingFactory.loadSchedule()
2/3/2007 5:00:36 AM.968 VERBOSE [12] getValue cached value: /settings/AutoRemoveMissingRecordings : true
2/3/2007 5:00:37 AM.171 VERBOSE [12] updating EPG...
2/3/2007 5:00:37 AM.171 INFO [12] Auto-Updating EPG
2/3/2007 5:00:37 AM.187 VERBOSE [12] getValue() loading new key/value into cache: /settings/Zap2itListingDays
2/3/2007 5:00:37 AM.187 VERBOSE [12] getValue: /settings/Zap2itListingDays : 15
2/3/2007 5:00:37 AM.187 VERBOSE [12] getValue cached value: /settings/CompleteEPGReload : true
2/3/2007 5:00:37 AM.187 VERBOSE [12] Zap2it full EPG update required.
2/3/2007 5:00:37 AM.250 VERBOSE [12] Processing zap2it channels using SAX parser
2/3/2007 5:00:37 AM.250 VERBOSE [12] Requesting listings for the period: 2007-02-03T00:00:00Z to 2007-02-18T23:59:59Z
2/3/2007 5:02:32 AM.953 VERBOSE [12] getValue() loading new key/value into cache: /settings/SaveZap2itXML
2/3/2007 5:02:32 AM.953 VERBOSE [12] getValue: /settings/SaveZap2itXML : true
2/3/2007 5:02:33 AM.328 VERBOSE [12] About to parse listings
2/3/2007 5:02:33 AM.343 VERBOSE [12] zap2it: The startTime you specified was invalid and has been corrected to 2007-02-02T00:00:00Z.
2/3/2007 5:02:33 AM.343 VERBOSE [12] zap2it: The endTime you specified was invalid and has been corrected to 2007-02-16T23:59:59Z.
2/3/2007 5:02:33 AM.343 VERBOSE [12] zap2it: Your subscription will expire: 2007-04-10T05:28:44Z
2/3/2007 5:02:34 AM.750 VERBOSE [12] Finished parsing listings
2/3/2007 5:02:34 AM.750 VERBOSE [12] Caching Zap2it EPG info incase it is required by other Capture Sources.
2/3/2007 5:02:34 AM.750 VERBOSE [12] getValue cached value: /settings/Zap2itListingDays : 15
2/3/2007 5:02:34 AM.750 VERBOSE [12] Capture Source EPG has been updated recently, so returning cached copy
2/3/2007 5:02:34 AM.750 VERBOSE [12] Caching Zap2it EPG info incase it is required by other Capture Sources.
2/3/2007 5:02:34 AM.750 VERBOSE [12] Skipping EmptyEPG, since not all EPG updates were successful...
2/3/2007 5:02:34 AM.750 VERBOSE [12] short epg update sleep...

If this is in fact a successful update, then I conclude that Windows Automatic Update is prohibiting the EPG update process from completing by putting the machine into standby before the EPG update is finished.

Can anything be done about that or should I just keep WAU turned off?

[GoodGuys]

Personally, I think WAU should be off. I only ever want to manually verify anything I install on my PVR machine...

In any case, I've been having this issue for who knows how long, and never ever have WAU on, on any of my machines, so I beg to differ on your finding.

GGF

[sub]

In any case, I've been having this issue for who knows how long, and never ever have WAU on, on any of my machines, so I beg to differ on your finding.

Yeah, I cant quite see how he is tying WAU into this one either.

I've now got this better logging in the EPG updates though, so if you want to post your logs GG, then I'll take a look at yours too.

[ww4397]

Personally, I think WAU should be off. I only ever want to manually verify anything I install on my PVR machine...

I have WAU set to "Notify me but don't automatically download or install them." I, like you, want to verify anything I install on my PVR machine. I also like to know when updates are avaialble, hence my setting.