Daily call not working

[MelissaH]

Hi there,
My tivo has stopped making daily calls. I've run through the networking diagnostics and all is fine, until i try wget to minnie when it times out. I've previously had proxy issues that were resolved with much appreciated help and it is possibly related.. http://forums.oztivo.net/showthread.php?t=1427

Nothing else has changed, same ISP, network set-up, etc..

The latest tclient log is attached...

Any advice would be welcome!

Thanks,
Melissa.

[MelissaH]

I forced a daily call and it seems to have worked, but the date/time is incorrect, so it won't record. Any suggestions on how to fix the date/time?

Thank you!

Jun 16 17:38:25 (none) tcphonehome[145]: Log file rotation complete.
Jun 16 17:41:18 (none) tcphonehome[145]: TClient success
Jun 16 17:41:18 (none) comm[145]: CallStatusReporter: Phase: Prepare_Data, Status Succeeded
Jun 16 17:41:18 (none) comm[145]: CallStatusReporter: sending message "EX|49"
Jun 16 17:41:18 (none) tcphonehome[145]: Sending dialup event, subtype 7, msg Succeeded
Jun 16 17:41:18 (none) tcphonehome[145]: setCallStatus called with: Succeeded state 0
Jun 16 17:41:18 (none) tcphonehome[145]: LastCallAttempt at 961167361
Jun 16 17:41:18 (none) tcphonehome[145]: status is 'Succeeded' == 'Succeeded'
Jun 16 17:41:18 (none) tcphonehome[145]: setting last success time to 961167361
Jun 16 17:41:18 (none) tcphonehome[145]: setting call status to: Succeeded
Jun 16 17:41:18 (none) tcphonehome[145]: setting call in progress to 0 (second successCount)
Jun 16 17:41:18 (none) tcphonehome[145]: setting LastSuccessPgdCall to 961167361
Jun 16 17:41:18 (none) tcphonehome[145]: Setting callActive to 0
Jun 16 17:41:18 (none) comm[145]: CallStatusReporter: sending data changed event 20
Jun 16 17:41:18 (none) tcphonehome[145]: destroying CommGlobals!
Jun 16 17:41:18 (none) tcphonehome[145]: pCommMempool empty
Jun 16 17:41:23 (none) tcphonehome[145]: Setting MCP PhoneHome action: ENABLED
Jun 16 17:48:28 (none) WatchdogAction[142]: WatchdogAction::Trigger: Already passed call time! (961177708 vs 961167987) sending event now.
Jun 16 17:48:28 (none) tcphonehome[142]: Sending dialup event, subtype 6, msg
Jun 16 17:48:28 (none) tcphonehome[142]: Sending message to myself
Jun 16 17:48:28 (none) tcphonehome[145]: getting /State/GeneralConfig/Complete
Jun 16 17:48:28 (none) tcphonehome[145]: Setting MCP PhoneHome action: RUNNING
Jun 16 17:48:28 (none) tcphonehome[145]: pCommMempool empty
Jun 16 17:48:28 (none) tcphonehome[145]: useBackupTollFree is 0
Jun 16 17:48:28 (none) tcphonehome[145]: phonehome: received message. type = 30 subtype = 6
Jun 16 17:48:28 (none) tcphonehome[145]: creating CommGlobals
Jun 16 17:52:07 (none) tcphonehome[145]: created CommGlobals

[MelissaH]

I just tried this to fix the time, but it didn't work..:

[TiVo [p0] ~]# ntpdate -v -b 127.0.0.1
oztivo ntpdate $Revision: 1.21 $
Locally configured IP address: 132.163.4.103
Failed 4 times to get_postalcode
[TiVo [p0] ~]# date
Fri Jun 16 18:14:27 localtime 2000
[TiVo [p0] ~]#

[petestrash]

You don't have your location field filled in on the fourm, so I don't know where you are. But Assuming your in NSW, Try the following (if not substitute your capital city code):

[TiVo [p0] ~]# get_postalcode

You should get the response 02000 (for NSW)

If you don't, please do the following

[TiVo [p0] ~]# cat /etc/postcodezones

And show what results you get.

If you did get 02000 then it's your ISP's proxy still causing issues.

[MelissaH]

Thanks, I am in Melbourne and this was the result i got. All the proxy issues were fixed early in 2007, and in the end it was the date/time that was causing the issues as it could make a daily call (as it can now), but the date/time is out.

Cheers,
Melissa.

[TiVo [p1] ~]# get_postalcode
retrying after errTmBackgroundHoldoff ...

[TiVo [p2] ~]# cat /etc/postcodezones
# A list of TiVo postcodes and the timezones that they represent
# Two columns, separated by one tab only.
#
# Australian
00800 Australia/Darwin
02000 Australia/Sydney
02600 Austral
03000 Australia/Melbourne
04000 Australia/Brisbane
05000 Australia/Adelaide
06000 Australia/Perth
07000 Australia/Hobart

# South African
12345 Africa/Johannesburg
54321 Africa/Johannesburg

# New Zealand
02112 Pacific/Auckland
02113 Pacific/Auckland
02115 Pacific/Auckland
02116 Pacific/Auckland

# Netherlands
01014 Europe/Amsterdam
02500 Europe/Amsterdam
02612 Europe/Amsterdam
02671 Europe/Amsterdam
08004 Europe/Amsterdam
09000 Europe/Amsterdam

# Norfolk Island
02899 Pacific/Norfolk
[TiVo [p2] ~]#

[petestrash]

In case there is something weird with the tabs in your postcodes file, download This (ftp://orac.caffeine.co.nz/install/postcodezones) version and replace it. Do not open the file on a PC as it may change the formating.

Peter.

[MelissaH]

Thanks Peter, i updated the file and it now has the correct date, but the time is out by 11 hours (Tivo is behind). It was 19:01 when i ran the below.

[TiVo [p0] ~]# ntpdate -v -b 127.0.0.1
oztivo ntpdate $Revision: 1.21 $
Locally configured IP address: 132.163.4.103
Our postcode is 03000. Found matching timezone Australia/Melbourne
Offset 0 for timezone GMT, daylight saving is 0
Connecting to time server 132.163.4.103
Setting the local time to Wed Jan 2 08:01:20 2008
[TiVo [p0] ~]# date
Wed Jan 2 08:01:37 localtime 2008
[TiVo [p0] ~]#

Also, just in case it helps - this is what i did early last year to fix some daily call/proxy/date & time issues (http://forums.oztivo.net/showthread.php?t=1427)

[TiVo [p0] ~]# cat /etc/ntpdate_local_ip
cat: /etc/ntpdate_local_ip: No such file or directory
[TiVo [p0] ~]# rw
File system is now READ/WRITE.
[TiVo [p0] ~]# echo "132.163.4.103" > /etc/ntpdate_local_ip
[TiVo [p0] ~]# ro
File system is now READ ONLY.
[TiVo [p0] ~]# ntpdate -v -b 127.0.0.1
oztivo ntpdate $Revision: 1.21 $
Locally configured IP address: 132.163.4.103
Our postcode is 03000. Found matching timezone Australia/Melbourne
Offset 36000 for timezone EST, daylight saving is 0
Connecting to time server 132.163.4.103
Setting the local time to Thu Mar 22 07:19:45 2007
[TiVo [p0] ~]#

[MelissaH]

Hi there,
Does anyone have any suggestions on how to fix the time on my Tivo? The tivo is 11 hours behind..

Melissa.

[MattCallow]

Hmm, I wonder if the zoneinfo database is corrupted? You could try:


cd /
rsync -rv 131.244.9.101::oztivo_timezones .
ntpdate -v -b 127.0.0.1
date

Matt

[MelissaH]

Thanks Matt,
Unfortunately it didn't work, it is still 11hrs behind (18:04 when i ran it). Could there be anything wrong with the time server?

Melissa

[TiVo [p0] /]# rsync -rv 131.244.9.101::oztivo_timezones

Welcome to the rsync server for the Unix Archive. If you are only interested
in browsing the Unix Archive, then please see the list of mirrors at
http://www.tuhs.org/archive_sites.html

receiving file list ... done
drwxr-xr-x 512 2006/01/06 23:57:36 .
drwxr-xr-x 512 2006/01/06 23:57:45 hack
drwxr-xr-x 512 2006/05/12 04:17:16 hack/zoneinfo
drwxr-xr-x 512 2006/01/07 00:01:04 hack/zoneinfo/Australia
-rw-r--r-- 785 2006/01/06 23:45:22 hack/zoneinfo/Australia/Adelaide
-rw-r--r-- 160 2006/01/06 23:45:22 hack/zoneinfo/Australia/Brisbane
-rw-r--r-- 790 2006/01/06 23:45:22 hack/zoneinfo/Australia/Broken_Hill
-rw-r--r-- 785 2006/01/07 00:00:20 hack/zoneinfo/Australia/Canberra
-rw-r--r-- 104 2006/01/06 23:45:22 hack/zoneinfo/Australia/Darwin
-rw-r--r-- 825 2006/01/06 23:45:22 hack/zoneinfo/Australia/Hobart
-rw-r--r-- 185 2006/01/06 23:45:22 hack/zoneinfo/Australia/Lindeman
-rw-r--r-- 655 2006/01/06 23:45:22 hack/zoneinfo/Australia/Lord_Howe
-rw-r--r-- 785 2006/01/06 23:45:22 hack/zoneinfo/Australia/Melbourne
-rw-r--r-- 170 2006/11/23 22:01:13 hack/zoneinfo/Australia/Perth
-rw-r--r-- 785 2006/01/06 23:45:22 hack/zoneinfo/Australia/Sydney
drwxr-xr-x 512 2006/05/12 04:17:33 hack/zoneinfo/Pacific
-rw-r--r-- 73 2006/01/06 23:45:22 hack/zoneinfo/Pacific/Norfolk

sent 99 bytes received 535 bytes 140.89 bytes/sec
total size is 6102 speedup is 9.62
[TiVo [p0] /]# ntpdate -v -b 127.0.0.1
oztivo ntpdate $Revision: 1.21 $
Locally configured IP address: 132.163.4.103
Our postcode is 03000. Found matching timezone Australia/Melbourne
Offset 0 for timezone GMT, daylight saving is 0
Connecting to time server 132.163.4.103
Setting the local time to Thu Jan 10 07:04:50 2008
[TiVo [p0] /]# date
Thu Jan 10 07:05:00 localtime 2008
[TiVo [p0] /]#

[MattCallow]

i don't think it's the timeserver. It looks like there's something wrong with your timezone setup. But I'm not sure what ntpdate is doing to get the timezone. I assumed it was using the zoneinfo files, but without the source to ntpdate, I can't be sure

[MelissaH]

Hi there,
Any suggestions out there on how to fix the time on my Tivo? It is 11hrs behind. It is making daily calls and the date is correct, the time is just out..

Many thanks!
Melissa.

[petestrash]

Sorry been away,

Can you try:
cat /etc/ntpdate_local_ip

Peter

[MelissaH]

Hope you had a good holiday Peter!

[TiVo [p1] ~]# [TiVo [p2] ~]# cat /etc/ntpdate_local_ip
132.163.4.103
[TiVo [p2] ~]#

[petestrash]

Sorry, away again.

Are you still having issues?

Peter.

[MelissaH]

No worries Peter, but yes i am still having problems, the tivo is still 11hrs behind...:(

[petestrash]

try the command 'date' and see what it shows, then try 'fixtivotime' and 'date' again.

Peter.

[MelissaH]

[TiVo [p0] ~]# date
Sat Feb 2 03:09:22 localtime 2008
[TiVo [p0] ~]# fixtivotime
[TiVo [p0] ~]# date
Sat Feb 2 03:09:47 localtime 2008
[TiVo [p0] ~]#

No luck..

[ian]

Ok, this might be a silly idea, but what happens if you manually set it?
ie
settime 20080202173000
settime -rtc

(that's 5.30pm today, as I just manually set mine to see if that would make a difference to my problem (http://forums.oztivo.net/showthread.php?t=1713).

Ian

[MelissaH]

Thanks Ian, that works to fix the time, but when it does a daily call, it resets it to 11hrs behind again..

[petestrash]

Melissa, can you try 'rm /etc/ntpdate_local_ip' and then force a daily call.

Ian, I think we need to resolve your DNS issues in the other thread.

Peter.

[MelissaH]

[TiVo [p0] ~]# rm /etc/ntpdate_local_ip
rm: remove `/etc/ntpdate_local_ip', overriding mode 0644?
[TiVo [p0] ~]#

But alas, it is still 11hrs behind.

[petestrash]

sorry, not thinking.

try:
rw
rm /etc/ntpdate_local_ip
ro

That should get rid of the overriding mode 0644 question.

Peter.

[MelissaH]

Tried it, but now it can't make the daily call at all, at best it got stuck on 99% downloaded.

I've attached the tclient log if that helps.

I returned it to previous with the below, but it's still 11hrs behind, is there a different IP i can use for the timeserver?

[TiVo [p0] ~]# cat /etc/ntpdate_local_ip
cat: /etc/ntpdate_local_ip: No such file or directory
[TiVo [p0] ~]# rw
File system is now READ/WRITE.
[TiVo [p0] ~]# echo "132.163.4.103" > /etc/ntpdate_local_ip
[TiVo [p0] ~]# ro
File system is now READ ONLY.
[TiVo [p0] ~]# ntpdate -v -b 127.0.0.1
oztivo ntpdate $Revision: 1.21 $
Locally configured IP address: 132.163.4.103
Our postcode is 03000. Found matching timezone Australia/Melbourne
Offset 0 for timezone GMT, daylight saving is 0
Connecting to time server 132.163.4.103
Setting the local time to Tue Feb 5 07:53:55 2008
[TiVo [p0] ~]# date
Tue Feb 5 07:54:05 localtime 2008
[TiVo [p0] ~]#

[petestrash]

Can you enter the following into a browser (xxx.xxx.xxx.xxx is your TiVo's IP).

http://xxx.xxx.xxx.xxx/mfs/AreaCode

Then click the number under name.

Then click the number next to CityPhoneNum.

What is listed next to 'City'?

Peter.

[MelissaH]

Melbourne/Hobart

[petestrash]

Unzip the attached file, and upload to /var/hack/zoneinfo/Australia/ replacing the existing melbourne file.

Peter.

[MelissaH]

I'm so sorry to be such a dunce Peter, but how do i upload it?

[petestrash]

Download to your PC first, then use an FTP program like smartFTP to transfer from your PC to the /var/hack/zoneinfo/Australia/ directory on your TiVo.

Then unzip melbourne.zip and if successful delete it with rm /var/hack/zoneinfo/Australia/melbourne.zip

Peter.

[MelissaH]

Thanks for the tip. I uploaded it, but nothing has changed, still 11hrs behind.

[petestrash]

can you check /var/hack/zoneinfo/Australia/ and make sure you only have one melbourne file.

Peter.

[petestrash]

also try the following commands:

ls -l /var/hack/zoneinfo/Australia/Melbourne

and

strings /bin/ntpdate | grep tz

Peter.

[MelissaH]

Yes there is only one melbourne file. Here's the info:

Thanks.

[TiVo [p0] ~]# ls -l /var/hack/zoneinfo/Australia/Melbourne
-rwxr-xr-x 1 0 0 785 Feb 10 02:52 /var/hack/zoneinfo/Austra
lia/Melbourne
[TiVo [p0] ~]#
[TiVo [p0] ~]# strings /bin/ntpdate | grep tz
tzname
tzset
Null postcode in set_tz_variable()
detzcode
settzname
tzload
getzname
tzparse
tzname
tzsetwall
set_tz_variable
tzset
[TiVo [p0] ~]#

[petestrash]

That's all good.

can you try:
[TiVo [p0] ~]# get_postalcode

A few times. This didn't work for you before.

If it still doesn't work try

cat -et /etc/postcodezones

Peter.

[MelissaH]

03000 came up, so this is an improvement.. but still 11hrs behind :-)

[petestrash]

Can you try the cat comand above and post the results.

Peter.

[MelissaH]

[TiVo [p0] ~]# cat -et /etc/postcodezones
# A list of TiVo postcodes and the timezones that they represent^M$
# Two columns, separated by one tab only.^M$
#^M$
00800^IAustralia/Darwin^M$
02000^IAustralia/Sydney^M$
02600^IAustralia/Sydney^M$
03000^IAustralia/Melbourne^M$
04000^IAustralia/Brisbane^M$
05000^IAustralia/Adelaide^M$
06000^IAustralia/Perth^M$
07000^IAustralia/Hobart^M$
64000^IPacific/Auckland^M$
^M$
# New Zealand^M$
02112^IPacific/Auckland^M$
02113^IPacific/Auckland^M$
02114^IPacific/Auckland^M$
02115^IPacific/Auckland^M$
02116^IPacific/Auckland^M$
^M$

[petestrash]

Ok, I think we are getting somewhere.

try this

rw

dos2unix /etc/postcodezones

ro

then try cat -et /etc/postcodezones again.

Peter.

[MelissaH]

Awesome! It's now working and on time!! Thanks Peter, you're a star! Are you a beer or wine man as i'd love to send a thank you gift :)

[petestrash]

The problem was an error in postcodezones file, as I thought it was in post #6.

Unfortunately even when you transfered the new version some errors still crept in.

It is fairly common when transfering some files that the FTP program uses acsii or text mode. When this happens it adds ^M to the end of each line causing the corruption.

To stop this make sure all transfers are done in 'Binary Mode'.

Peter.

PS No gifts needed, just sorry it's taken 2 months to resolve.

[MelissaH]

Cool, thanks for the tip and thanks again for your help - frustrating as it was for you i'm sure...! I'm just happy it's working!