Network N24 error, can still connect to Tivo

[diskman]

I removed my cable box from my Tivo setup and switched to having just the cable plugged directly into the Tivo. This required I repeat guided setup. This is where I get the N24 error. Everything was working fine before the switch.

Some things I have tried.

- Tried unplugging and rebooting everything.
- Netgear FA120 works properly when plugged into my laptop
- After I get the first N24 error, I can ping the Tivo from my laptop (static IP)
- The Tivo server running on my desktop computer can connect to the Tivo and retrieve 162 recordings. This is while the Tivo is stuck on the N24 error screen at the beginning of guided setup.

This is happening at the very beginning of guided setup. It happens with both a DHCP setup and a static IP assigned.

It looks as if the network adapter is working, but something else is causing the Tivo to hang.

Please help, I'm open to any suggestions!

[Jamie]

I removed my cable box from my Tivo setup and switched to having just the cable plugged directly into the Tivo. This required I repeat guided setup. This is where I get the N24 error. Everything was working fine before the switch.

Some things I have tried.

- Tried unplugging and rebooting everything.
- Netgear FA120 works properly when plugged into my laptop
- After I get the first N24 error, I can ping the Tivo from my laptop (static IP)
- The Tivo server running on my desktop computer can connect to the Tivo and retrieve 162 recordings. This is while the Tivo is stuck on the N24 error screen at the beginning of guided setup.

This is happening at the very beginning of guided setup. It happens with both a DHCP setup and a static IP assigned.

It looks as if the network adapter is working, but something else is causing the Tivo to hang.

Please help, I'm open to any suggestions!If it is an unhacked tivo, call tivo customer support and/or check the support forums at tivo.com and tivocommunity.com.

If it is a hacked tivo, post the tclient and Otclient logs from /var/log.

Off hand, I'd guess a gateway or routing issue, since your connection to the LAN seems to be working.

[diskman]

/var/log/tclient
Jan 2 17:58:48 (none) AreaCodeChore[406]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:13:47 (none) MercuryChore[444]: UI Setup not complete, skip Mercury pings
Jan 2 18:13:48 (none) WatchdogChore[444]: Compare next call to max future: 02/01/2008 18:13:48
Jan 2 18:13:50 (none) AreaCodeChore[443]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:28:48 (none) MercuryChore[465]: UI Setup not complete, skip Mercury pings
Jan 2 18:28:50 (none) WatchdogChore[465]: Compare next call to max future: 02/01/2008 18:28:50
Jan 2 18:28:52 (none) AreaCodeChore[466]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:43:49 (none) MercuryChore[497]: UI Setup not complete, skip Mercury pings
Jan 2 18:43:52 (none) WatchdogChore[497]: Compare next call to max future: 02/01/2008 18:43:52
Jan 2 18:43:54 (none) AreaCodeChore[497]: ReadDbValues: found: 0, lac: , vers: 0 ph#:


/var/log/Otclient

Dec 31 20:40:21 (none) CommGlobals[27073]: --- Rqst Summary End ---
Dec 31 20:40:21 (none) PhoneHome[27073]: checking ServiceState
Dec 31 20:40:21 (none) PhoneHome[27073]: ReschedCall reason=0 nFail=0
Dec 31 20:40:21 (none) CallBack[27073]: nMinute=1463 nSecSinceSvrResp=9:20
Dec 31 20:40:21 (none) CallBack[27073]: success nMinute=1454
Dec 31 20:40:21 (none) PhoneHome[27073]: Next call attempt at 1199220861 (01/01/2008 20:54:21)
Dec 31 20:40:22 (none) PhoneHome[27073]: ReschedCall(0) in 1454 min (01/01/2008 20:54:21)
Dec 31 20:40:22 (none) PhoneHome[27073]: Setting PHG:NumFails to 0
Dec 31 20:40:22 (none) comm[27073]: BackhaulStatus: type=0 hserver=1 tried=0 ok=1
Dec 31 20:40:22 (none) PhoneHome[27073]: Rotating log files

[Jamie]

Those are the complete logs? It doesn't appear that it even attempted a service call.

The "UI Setup not complete" messages may be a clue. That is coming from Mercury, the process that checks for online scheduling changes every 15 minutes.

When you are in the error state, try to ping one of the tivo servers (e.g. 204.176.49.8). If the pings don't get through, traceroute might help to figure out if you are ever even getting out of your LAN. If you are not, it is likely a routing issue. Check your default route, subnet mask, etc and verify it is all correct.

You could always switch to a phone connection for a call or two, then switch back. That's been known to fix N24 errors.

[diskman]

Here is the ping results from the Tivo.
(none):/var/log$ ping 204.176.49.8
PING 204.176.49.8 (204.176.49.8): 56 data bytes
64 bytes from 204.176.49.8: icmp_seq=0 ttl=234 time=89.6 ms
64 bytes from 204.176.49.8: icmp_seq=1 ttl=234 time=89.8 ms
64 bytes from 204.176.49.8: icmp_seq=2 ttl=234 time=87.7 ms
64 bytes from 204.176.49.8: icmp_seq=3 ttl=234 time=92.8 ms


DNS also appears to be working.
(none):/var/log$ ping www.google.com
PING www.l.google.com (64.233.169.99): 56 data bytes
64 bytes from 64.233.169.99: icmp_seq=0 ttl=243 time=28.2 ms
64 bytes from 64.233.169.99: icmp_seq=1 ttl=243 time=27.7 ms
64 bytes from 64.233.169.99: icmp_seq=2 ttl=243 time=29.7 ms
64 bytes from 64.233.169.99: icmp_seq=3 ttl=243 time=29.2 ms


Here is a bit more of the tclient log.
Jan 2 16:22:16 (none) MercuryChore[451]: UI Setup not complete, skip Mercury pings
Jan 2 16:22:24 (none) WatchdogChore[470]: Compare next call to max future: 02/01/2008 16:22:24
Jan 2 16:22:26 (none) AreaCodeChore[482]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 16:28:09 (none) PhoneChore[485]: SetupComplete Guided=0 Phone=0
Jan 2 16:28:09 (none) PhoneChore[485]: SetupNotComplete: skip call
Jan 2 16:28:09 (none) PhoneHome[485]: ReschedCall reason=1 nFail=0
Jan 2 16:28:09 (none) PhoneHome[485]: ReschedCall(setupNotComplete: call very soon): nMin=72
Jan 2 16:28:09 (none) PhoneHome[485]: Next call attempt at 1199295609 (01/02/2008 17:40:09)
Jan 2 16:28:09 (none) PhoneHome[485]: ReschedCall(1) in 72 min (01/02/2008 17:40:09)
Jan 2 16:37:17 (none) MercuryChore[482]: UI Setup not complete, skip Mercury pings
Jan 2 16:37:26 (none) WatchdogChore[490]: Compare next call to max future: 02/01/2008 16:37:26
Jan 2 16:37:28 (none) AreaCodeChore[491]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 16:52:18 (none) MercuryChore[501]: UI Setup not complete, skip Mercury pings
Jan 2 16:52:28 (none) WatchdogChore[501]: Compare next call to max future: 02/01/2008 16:52:28
Jan 2 16:52:30 (none) AreaCodeChore[502]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 16:57:34 (none) comm[183]: No CaptureRqst SeqNum found in the DB
Jan 2 16:57:34 (none) PhoneHome[183]: Sending connect event...
Jan 2 16:57:34 (none) PhoneHome[183]: cleaning /Setup
Jan 2 16:57:34 (none) DialConfig[183]: Read dial defaults from TClientDocument: Host=204.176.49.2 Port=80 Passwd=MtDubois RadiusDomain=tivo.net
Jan 2 16:57:35 (none) PhoneHome[183]: Fetching /SwSystem/ACTIVE/Name (9.1-01-2-130)
Jan 2 16:57:35 (none) PhoneAval[183]: PhoneHome: statusValue = <0>, getResult = <0>
Jan 2 16:57:35 (none) PhoneAval[183]: /State/Avalanche LastStatus was <0> and getResult was <0>, changing to unknown
Jan 2 16:57:36 (none) PhoneHome[183]: Last Call Status Was: 'Failed' (size 6)
Jan 2 16:57:36 (none) PhoneHome[183]: Last Call Status Will Be: Failed
Jan 2 16:57:36 (none) PhoneHome[183]: Setting callActive to 0
Jan 2 16:57:36 (none) PhoneHome[183]: setCallStatus called with: Failed state 0
Jan 2 16:57:36 (none) PhoneHome[183]: LastCallAttempt at 12/31/2007 20:30:14
Jan 2 16:57:36 (none) PhoneHome[183]: setting call status to: Failed
Jan 2 16:57:36 (none) PhoneHome[183]: finished cleaning /Setup
Jan 2 16:57:36 (none) MercuryChore[183]: First wakeup of Mercury in 575 seconds
Jan 2 16:57:36 (none) AreaCodeChore[183]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 16:57:36 (none) PhoneHome[183]: Timesync is within the past 5 days... all is well
Jan 2 16:57:37 (none) PhoneHome[183]: ReschedCall reason=8 nFail=0
Jan 2 16:57:37 (none) PhoneHome[183]: ReschedCall(phoneSetupNotComplete: call soon): nMin=155
Jan 2 16:57:37 (none) PhoneHome[183]: Next call attempt at 1199302357 (01/02/2008 19:32:37)
Jan 2 16:57:37 (none) PhoneHome[183]: ReschedCall(8) in 155 min (01/02/2008 19:32:37)
Jan 2 16:57:37 (none) PhoneHome[183]: TCphonehome active
Jan 2 17:12:38 (none) WatchdogChore[361]: Compare next call to max future: 02/01/2008 17:12:37
Jan 2 17:12:38 (none) MercuryChore[362]: UI Setup not complete, skip Mercury pings
Jan 2 17:12:40 (none) AreaCodeChore[363]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 17:27:38 (none) MercuryChore[372]: UI Setup not complete, skip Mercury pings
Jan 2 17:27:39 (none) WatchdogChore[372]: Compare next call to max future: 02/01/2008 17:27:39
Jan 2 17:27:41 (none) AreaCodeChore[372]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 17:43:43 (none) comm[183]: No CaptureRqst SeqNum found in the DB
Jan 2 17:43:44 (none) PhoneHome[183]: Sending connect event...
Jan 2 17:43:44 (none) PhoneHome[183]: cleaning /Setup
Jan 2 17:43:44 (none) DialConfig[183]: Read dial defaults from TClientDocument: Host=204.176.49.2 Port=80 Passwd=MtDubois RadiusDomain=tivo.net
Jan 2 17:43:45 (none) PhoneHome[183]: Fetching /SwSystem/ACTIVE/Name (9.1-01-2-130)
Jan 2 17:43:45 (none) PhoneAval[183]: PhoneHome: statusValue = <0>, getResult = <0>
Jan 2 17:43:45 (none) PhoneAval[183]: /State/Avalanche LastStatus was <0> and getResult was <0>, changing to unknown
Jan 2 17:43:45 (none) PhoneHome[183]: Last Call Status Was: 'Failed' (size 6)
Jan 2 17:43:45 (none) PhoneHome[183]: Last Call Status Will Be: Failed
Jan 2 17:43:45 (none) PhoneHome[183]: Setting callActive to 0
Jan 2 17:43:45 (none) PhoneHome[183]: setCallStatus called with: Failed state 0
Jan 2 17:43:45 (none) PhoneHome[183]: LastCallAttempt at 12/31/2007 20:30:14
Jan 2 17:43:45 (none) PhoneHome[183]: setting call status to: Failed
Jan 2 17:43:46 (none) PhoneHome[183]: finished cleaning /Setup
Jan 2 17:43:46 (none) MercuryChore[183]: First wakeup of Mercury in 1025 seconds
Jan 2 17:43:46 (none) AreaCodeChore[183]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 17:43:46 (none) PhoneHome[183]: Timesync is within the past 5 days... all is well
Jan 2 17:43:46 (none) PhoneHome[183]: ReschedCall reason=8 nFail=0
Jan 2 17:43:46 (none) PhoneHome[183]: ReschedCall(phoneSetupNotComplete: call soon): nMin=147
Jan 2 17:43:46 (none) PhoneHome[183]: Next call attempt at 1199304646 (01/02/2008 20:10:46)
Jan 2 17:43:46 (none) PhoneHome[183]: ReschedCall(8) in 147 min (01/02/2008 20:10:46)
Jan 2 17:43:46 (none) PhoneHome[183]: TCphonehome active
Jan 2 17:58:46 (none) WatchdogChore[186]: Compare next call to max future: 02/01/2008 17:58:46
Jan 2 17:58:46 (none) MercuryChore[406]: UI Setup not complete, skip Mercury pings
Jan 2 17:58:48 (none) AreaCodeChore[406]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:13:47 (none) MercuryChore[444]: UI Setup not complete, skip Mercury pings
Jan 2 18:13:48 (none) WatchdogChore[444]: Compare next call to max future: 02/01/2008 18:13:48
Jan 2 18:13:50 (none) AreaCodeChore[443]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:28:48 (none) MercuryChore[465]: UI Setup not complete, skip Mercury pings
Jan 2 18:28:50 (none) WatchdogChore[465]: Compare next call to max future: 02/01/2008 18:28:50
Jan 2 18:28:52 (none) AreaCodeChore[466]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:43:49 (none) MercuryChore[497]: UI Setup not complete, skip Mercury pings
Jan 2 18:43:52 (none) WatchdogChore[497]: Compare next call to max future: 02/01/2008 18:43:52
Jan 2 18:43:54 (none) AreaCodeChore[497]: ReadDbValues: found: 0, lac: , vers: 0 ph#:
Jan 2 18:58:50 (none) MercuryChore[537]: UI Setup not complete, skip Mercury pings
Jan 2 18:58:54 (none) WatchdogChore[516]: Compare next call to max future: 02/01/2008 18:58:54
Jan 2 18:58:56 (none) AreaCodeChore[516]: ReadDbValues: found: 0, lac: , vers: 0 ph#:


I have no landline here, so I'll need to wait for the neighbor to come home before I can borrow his phone line.

Any other ideas? Is there any way I can abort this guided setup and go back to my old setup?