New router - daily call failing

[KerryC]

I installed a new router yesterday. I made sure it was using the same subnet as the previous one (subnet 0) and put the Tivo Series 1 in the DNS with a fixed address (its current one of 192.168.0.3)

The series 1 is connected to the router via Ethernet, I have not rebooted the TiVo.

I can connect to it via telnet to its IP address, but it cannot make a daily call.

It can ping the router, and the resolv.conf file has already been automatically updated with the router's name and IP address (different from the old one)

But the daily call is failing with Service unavailable.

What else should I check?

Thanks

Kerry

[Skolink]

When you say new router does that also include new ISP? Do you mean you added a fixed DHCP entry for the TiVo? You shouldn't need to put the router's name anywhere on the TiVo. You will need to change the default gateway entry on the TiVo if your new router has a different address, and you are not using DHCP on the TiVo.

[KerryC]

Thanks for the reply.

I have the same ISP, I just replaced my router.

I noticed when I telneted to the TiVo (which has not yet been rebooted since the router was replaced) that the resolv.conf file has the IP address of the new router, not the old router (the IP address of the router has changed from 192.168.0.1 to 192.168.0.254). I did not edit this file, so it was done by the TiVo. It is thus aware of the new router's IP address, but the daily call is failing nonetheless.

I did run nic_config_tivo to check the MAC address.

I am using the 1.6 image, but have the IP address set to static (from memory).

The simplist thing may be to set the IP address on the TiVo to DHCP, then just reboot and let it sort it out with the router.

Which is the easiest way to do this - nic_config_tivo with filesystem set to read/write?

Thanks

Kerry

[petestrash]

Change the "gateway=" line in /etc/oztivo.conf to 192.168.0.254

Save the file, make the TiVo Read Only again, then reboot.

Peter.

[KerryC]

Thanks Peter.

I had used nic_configure_tivo to set the Cachecard to DHCP, then rebooted the TiVo. It came up fine, I can telnet to it, it can ping the router and the router appears as the default gateway when you run the route command.

I edited /etc/oztivo.conf and changed the emulatorport to 9090 (it was 80), (the daily call failed, tclient log below, I edited oztivo.conf and replaced emulator=www.oztivo.net with 65.49.60.197, but the call failed again)

/etc/oztivo.conf now says

# OzTiVo menu configuration settings file
# Please do not edit this file
# Run the nic_config_tivo tool to reconfigure
nic=Cachecard
ip=DHCP
mac=00:0B:AD:70:12:36
timing=3
hostname=oztivo
emulator=www.oztivo.net
emulatorport=9090
netmask=
gateway=
ssid=
wep=
wepkey=

then did a daily call again

(I also remembered that the TiVo was always using DHCP, I had setup the router to always hand over the same IP address. (which is now in the new router).)

The daily call has failed again with service unavailable

The tclient log says

Aug 17 23:45:00 (none) tcphonehome[158]: Sending dialup event, subtype 8, msg EX|14
Aug 17 23:45:00 (none) tcphonehome[158]: setCallStatus called with: Failed state 0
Aug 17 23:45:00 (none) tcphonehome[158]: LastCallAttempt at 1282088486
Aug 17 23:45:00 (none) tcphonehome[158]: setting call status to: Failed
Aug 17 23:45:00 (none) tcphonehome[158]: setting call in progress to 0 (second successCount)
Aug 17 23:45:00 (none) tcphonehome[158]: Setting callActive to 0
Aug 17 23:45:00 (none) comm[158]: CallStatusReporter: sending data changed event 20
Aug 17 23:45:00 (none) tcphonehome[158]: destroying CommGlobals!
Aug 17 23:45:00 (none) tcphonehome[158]: pCommMempool empty
Aug 17 23:45:08 (none) tcphonehome[158]: Setting MCP PhoneHome action: ENABLED
[TiVo [p0] /var/log]# tail -100 tclient
Aug 17 23:42:00 (none) comm[158]: tollFreeAuth: 1
Aug 17 23:42:00 (none) comm[158]: callType: TS_SR_ACCEPTED
Aug 17 23:42:00 (none) comm[158]: swVerName: 3.0-01-1-000
Aug 17 23:42:00 (none) comm[158]: End TCD411 Request ==================
Aug 17 23:42:00 (none) comm[158]: found message 20000019 (localId = 2842), from 1
Aug 17 23:42:00 (none) comm[158]: found message 20000020 (localId = 498464), from 1
Aug 17 23:42:00 (none) comm[158]: found message 20000021 (localId = 498518), from 1
Aug 17 23:42:00 (none) comm[158]: found message 20000029 (localId = 570196), from 1
Aug 17 23:42:00 (none) comm[158]: found message 20000024 (localId = 510356), from 1
Aug 17 23:42:00 (none) comm[158]: sigType=st=5 connector=con=5...
Aug 17 23:42:00 (none) comm[158]: Error getting the Avalanche Disk Directories: errNmNameNotFound
Aug 17 23:42:00 (none) comm[158]: Start Ident ==================
Aug 17 23:42:00 (none) comm[158]: version: 3
Aug 17 23:42:00 (none) comm[158]: centerID: 0020000202304E6
Aug 17 23:42:00 (none) comm[158]: reasonCode: 1
Aug 17 23:42:00 (none) comm[158]: softwareDesc: 6406306-51|6406288-1|6406290-1|6406292-1|6406294-1|6406296-1|6406298-1|6406300-1|6406302-1|6406304-1|6406307-1|6406308-1|6406309-1|6406310-1|6406311-1|6406312-1|6406313-1|6406314-1|6406315-1|6406316-1|6406317-1|6406318-1|6406319-3|6406
Aug 17 23:42:00 (none) comm[158]: 320-5|6406321-1|6406322-6|6406323-1|6406324-1|6406325-1|6406326-1|6406327-1|6406328-1|6406329-1|6406330-1|6406331-1|6406332-1|6406333-1|6406334-1|6406335-1|6406336-1|6406337-1|6406338-1|6406339-1|6406340-1|6406341-1|6406342-1|6406343-1|6406344-1|6406345-1|
Aug 17 23:42:00 (none) comm[158]: 6406346-1|6406347-1|6406348-1|6406349-1|6406350-1|6406351-1|6406352-1|6406353-1|6406354-1|6406355-1|411986-104|412025-19|412046-11|412086-19|412107-19|412149-71|412202-19|412214-6|
Aug 17 23:42:00 (none) comm[158]: locationID: 03000-87
Aug 17 23:42:00 (none) comm[158]: sequenceCookie: 12345678
Aug 17 23:42:00 (none) comm[158]: headendID: VI03013-14838
Aug 17 23:42:00 (none) comm[158]: headendHisto: VI03013:14838-68,14839-5,14841-17,14843-17,14844-73,14845-9,14847-3,14848-4,14849-8
Aug 17 23:42:00 (none) comm[158]: headendWorst: VI03013:7001830,7000140,7002100,7000180,7000170,70 00180,7000190,7000200,7000210,7000190
Aug 17 23:42:00 (none) comm[158]: showcaseDesc: showcase0-0
Aug 17 23:42:00 (none) comm[158]: inventoryFile:
Aug 17 23:42:00 (none) comm[158]: waitingCount: 0
Aug 17 23:42:00 (none) comm[158]: confInfo: :127.0.0.1:8000::::
Aug 17 23:42:00 (none) comm[158]: dialConfig: 127
Aug 17 23:42:00 (none) comm[158]: messageDesc: 20000019-2|20000020-2|20000021-3|20000029-3|20000024-2|
Aug 17 23:42:00 (none) comm[158]: irdbVersion: 250
Aug 17 23:42:00 (none) comm[158]: genreVersion: 14
Aug 17 23:42:00 (none) comm[158]: logoVersion:
Aug 17 23:42:00 (none) comm[158]: affiliationVersion:
Aug 17 23:42:00 (none) comm[158]: showcaseVersion:
Aug 17 23:42:00 (none) comm[158]: demoMode:
Aug 17 23:42:00 (none) comm[158]: swVerName: 3.0-01-1-000
Aug 17 23:42:00 (none) comm[158]: apgOnly:
Aug 17 23:42:00 (none) comm[158]: useChksums: TRUE
Aug 17 23:42:00 (none) comm[158]: premiumShowcases:
Aug 17 23:42:00 (none) comm[158]: captureRequests:
Aug 17 23:42:00 (none) comm[158]: menuItems:
Aug 17 23:42:00 (none) comm[158]: collabData:
Aug 17 23:42:00 (none) comm[158]: signedFiles:
Aug 17 23:42:00 (none) comm[158]: otherDatasets:
Aug 17 23:42:00 (none) comm[158]: rbBackgrounds:
Aug 17 23:42:00 (none) comm[158]: dataGroupList:
Aug 17 23:42:00 (none) comm[158]: lastAvalDownload: 0,0 0,0
Aug 17 23:42:00 (none) comm[158]: userInitiated: 1
Aug 17 23:42:00 (none) comm[158]: mfsTotalApp: 785168
Aug 17 23:42:00 (none) comm[158]: mfsAvailApp: 719040
Aug 17 23:42:00 (none) comm[158]: mfsTotalMedia: 622657536
Aug 17 23:42:00 (none) comm[158]: mfsAvailMedia: 23279616
Aug 17 23:42:00 (none) comm[158]: clipsTotalSize: 10000000
Aug 17 23:42:00 (none) comm[158]: clipsAvailSize: 9948800
Aug 17 23:42:00 (none) comm[158]: rbTotalSize: 0
Aug 17 23:42:00 (none) comm[158]: rbAvailSize: 0
Aug 17 23:42:00 (none) comm[158]: tcdId: 0020000202304E6
Aug 17 23:42:00 (none) comm[158]: callId: 1282088486
Aug 17 23:42:00 (none) comm[158]: sourceParameterList: st=5,con=5,drm=,ekr=1,ccn=30057,brn=_Foxtel_Oztivo ,lin=1
Aug 17 23:42:00 (none) comm[158]: configParameterList: zip=03000,dar=1,rcq=75,tz=7,as=1,tun=,suc=7,sta=3, exp=0
Aug 17 23:42:00 (none) comm[158]: iceboxinfo:
Aug 17 23:42:00 (none) comm[158]: timeStamp: ...
Aug 17 23:42:00 (none) comm[158]: End Ident ==================
Aug 17 23:42:00 (none) comm[158]: CallService: Start err 0
Aug 17 23:42:00 (none) comm[158]: CAM_ID not found.
Aug 17 23:42:00 (none) comm[158]: CallStatusReporter: Phase: Dial, Status In Progress
Aug 17 23:42:00 (none) comm[158]: CallStatusReporter: sending message "CL|30"
Aug 17 23:42:00 (none) comm[158]: Backdoor code 4, enable calls via broadband
Aug 17 23:42:00 (none) comm[158]: Using Ethernet. Not starting modem/pppd.
Aug 17 23:42:00 (none) comm[158]: CallStatusReporter: Phase: Start_Auth, Status In Progress
Aug 17 23:42:00 (none) comm[158]: CallStatusReporter: sending message "ST|33"
Aug 17 23:42:00 (none) comm[158]: CommUtil: connection to host 127.0.0.1, port 8000, err 0x0
Aug 17 23:42:00 (none) comm[158]: Uploading HTTP Header for modLog of /var/log/svclog: POST /tivo-service/mlog.cgi HTTP/1.0^M Content-Length: 5117^M ^M
Aug 17 23:44:57 (none) comm[158]: XferRqst timeout waiting to read
Aug 17 23:44:57 (none) comm[158]: drainGetPostQ: doXfer failed err=65535 (0xffff)
Aug 17 23:44:57 (none) comm[158]: NetAgent: drainPostQ failed, err = 0xffff
Aug 17 23:44:57 (none) comm[158]: CallService: NetAgent Process err 65535
Aug 17 23:44:57 (none) comm[158]: CallService: NetAgent failed, status 0xffff
Aug 17 23:44:57 (none) comm[158]: CallService: EndCall start
Aug 17 23:44:59 (none) comm[158]: CallStatusReporter: Turning off-hook LED off
Aug 17 23:44:59 (none) comm[158]: Recover private logs
Aug 17 23:44:59 (none) comm[158]: Opening old private log files
Aug 17 23:44:59 (none) comm[158]: Reverting private logs
Aug 17 23:44:59 (none) comm[158]: Cleanup: GetAddDiskBasedDirectories returned <70001> "0x70001"...skipping cleanup
Aug 17 23:44:59 (none) tcphonehome[158]: CallService return status 0xffff
Aug 17 23:44:59 (none) tcphonehome[158]: GetPostalCodeVersion - /PostalCode/03000 ServerVersion=87
Aug 17 23:44:59 (none) tcphonehome[158]: debug: sumServerVersion=87
Aug 17 23:45:00 (none) comm[158]: CallStatusReporter: Phase: Start_Auth, Status Failed
Aug 17 23:45:00 (none) comm[158]: CallStatusReporter: sending message "EX|14"
Aug 17 23:45:00 (none) tcphonehome[158]: TClient failed: EX|14
Aug 17 23:45:00 (none) tcphonehome[158]: Sending dialup event, subtype 8, msg EX|14
Aug 17 23:45:00 (none) tcphonehome[158]: setCallStatus called with: Failed state 0
Aug 17 23:45:00 (none) tcphonehome[158]: LastCallAttempt at 1282088486
Aug 17 23:45:00 (none) tcphonehome[158]: setting call status to: Failed
Aug 17 23:45:00 (none) tcphonehome[158]: setting call in progress to 0 (second successCount)
Aug 17 23:45:00 (none) tcphonehome[158]: Setting callActive to 0
Aug 17 23:45:00 (none) comm[158]: CallStatusReporter: sending data changed event 20
Aug 17 23:45:00 (none) tcphonehome[158]: destroying CommGlobals!
Aug 17 23:45:00 (none) tcphonehome[158]: pCommMempool empty
Aug 17 23:45:08 (none) tcphonehome[158]: Setting MCP PhoneHome action: ENABLED
[TiVo [p0] /var/log]#


Kerry

[petestrash]

Have you rebooted since changing the emulator port?

Peter.

[KerryC]

Hmm, I don't believe so. I will do so when I get home.

Thanks

Kerry

I've rebooted the TiVo - the daily call is still failing

# OzTiVo menu configuration settings file
# Please do not edit this file
# Run the nic_config_tivo tool to reconfigure
nic=Cachecard
ip=DHCP
mac=00:0B:AD:70:12:36
timing=3
hostname=oztivo
emulator=www.oztivo.net
emulatorport=9090
netmask=
gateway=
ssid=
wep=
wepkey=

[petestrash]

I came in to send a message to you to see how it went and only just saw your reply, don't edit your posts as notifications are not sent that it has been updated.

have you also changed ISP's or just the router?

Can you please go through these (http://www.oztivo.net/twiki/bin/view/Network/NetworkingHowto#Configuration_and_Diagnostics) tests and post the results.

Peter.