Help required - stuck in a reboot loop

[a_tivo_noob]

Hi folks,

Backstory: I'd been having issues with port forwarding (for TivoWeb) on my stupid plusnet router, and needed to re-assure myself that my Tivo was definitely set to DHCP as apparently, setting a leased IP on the router, as well as having a static IP on the Tivo can mess with port forwarding.

Anyways, rather than checking whether I had static IP on the Tivo or not, I just followed the instructions here, to set DHCP:
http://www.altepg.com/networkConfiguration/

All went well, and I did the reboot.

Unfortunately, that seems to be all that the machine is capable of. It gets as far as the "indiana jones" sequence, then seconds later (at slightly different points each time), it will just reboot.

Because AltEpg has been so amazing, my knowledge of bash (Etc) has slipped away but i'm happy to re-learn. Can someone give me pointers of what I should check? I have access to a few things that will help me troubleshoot, but wondered if someone could tell me where to focus my efforts. At my parents house I have the following:
- 2 or 3 spare Tivos
- Old PC that I can connect the drive to (and AltEPG Iso if it's worth trying to config the network card via. the install CD)

Ideas I have are:
- to swap the drives into another machine (to see if its a hardware issue with mine)
- to chase the network problem and try and resolve it
- to trawl through log files

Not sure where to start - opinions welcome. The fact all my kit is at my parents house makes this very awkward to try and resolve without many (long distance) trips, or lots of time spent there trying to fight it through.

[Pegasus]

So just to clarify, your Tivo has an AltEPG image installed on the disc? (i.e. not simply an original Tivo which has been reconfigured to work with AltEPG). What version of the AltEPG software is running?

When the opening video is playing on the Tivo, does the Tivo appear in the list of connected devices on your router?

[a_tivo_noob]

So just to clarify, your Tivo has an AltEPG image installed on the disc? (i.e. not simply an original Tivo which has been reconfigured to work with AltEPG). What version of the AltEPG software is running?

When the opening video is playing on the Tivo, does the Tivo appear in the list of connected devices on your router?

Thanks for the reply...

I'm running the latest ('D') version of AltEpg

Re: 2nd bit... good point! - yes, I believe it does... so that rules out my network changes being an issue, right?

edit: I should add, I did a single "Kickstart 57" as well to get the green-screen, but the loop still happened after - anything else along those lines that I could try? I also whipped the cachecard RAM out, but that was just so I could boot the device up quicker to see if I was getting close to fixing anything!

[Pegasus]

I believe it does... so that rules out my network changes being an issue, right?

Not necessarily

Can you remember what code you entered from http://www.altepg.com/networkConfiguration/

How many seconds do you get before it reboots? Is there time to telnet in before it reboots? (note: you don't have to wait for the video to finish playing - you should be able to telnet while it's still booting)

If you can get in can you

Code: Select all

cat  /etc/dhclient.conf

Then run

Code: Select all

tail  -f  /var/log/tclient

to watch the log in realtime. See what the last few lines are before it reboots.

[a_tivo_noob]

I believe it does... so that rules out my network changes being an issue, right?

Not necessarily

Can you remember what code you entered from http://www.altepg.com/networkConfiguration/

How many seconds do you get before it reboots? Is there time to telnet in before it reboots? (note: you don't have to wait for the video to finish playing - you should be able to telnet while it's still booting)

If you can get in can you

Code: Select all

cat  /etc/dhclient.conf

Then run

Code: Select all

tail  -f  /var/log/tclient

to watch the log in realtime. See what the last few lines are before it reboots.

Thanks again - I used (auto detect, DHCP):

Code: Select all

,#0,0

And here's the log output:

Code: Select all

[TiVo [p0] ~]# cat  /etc/dhclient.conf
send host-name "altepgtivo";
timeout 5 ;
[TiVo [p0] ~]#
[TiVo [p0] ~]# tail  -f  /var/log/tclient
10/04:20:45:10: /tvbin/TClient:  updateStatus: In Progress EX| 29 11
10/04:20:45:10: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
10/04:20:45:15: /tvbin/TClient:  updateStatus: Failed EX| 29 11
Saving 'EX|11' to result file
10/04:20:45:16: /tvbin/TClient:  writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
10/04:20:45:18: /tvbin/TClient:  writing Failed to LastCallStatus
10/04:20:45:19: /tvbin/TClient:  SendDialupEvent 30 9 EX|11
10/04:20:45:19: /tvbin/TClient:  TClient login: 1
10/04:20:45:19: /tvbin/TClient:  TClient backhaulDone: 0


I tried it 3 times and each time it gets no further than that line (which appears much earlier than the video)

[Pegasus]

Ok. I think that's telling me that your Tivo barfed with "Failed. Couldn't dial". That suggests the recent change has messed with Tivo's access to the internet.

The simplest fix is probably to run nic_config_tivo and reset things back to how they were, but to do that you'll need to pull the disc.

So before you do that, check that your Tivo can get access through your router. I note your original post talked about setting port forwarding on your router - if you are going to do that then you will need a fixed IP address on your Tivo. Or at least a preferred IP (i.e. the same IP allocated to your Tivo every time it asks for a temporary address). Log-in to your router and (assuming it can do this) set the DHCP allocation so it always gives your Tivo the same IP address. Then (tempo) turn off any port forwarding to the Tivo. Reboot Tivo and see what you get in the log.

Hint: you can test whether Tivo can access out by running

Code: Select all

ping -c3 204.176.49.2

in telnet.

[a_tivo_noob]

Ok. I think that's telling me that your Tivo barfed with "Failed. Couldn't dial". That suggests the recent change has messed with Tivo's access to the internet.

The simplest fix is probably to run nic_config_tivo and reset things back to how they were, but to do that you'll need to pull the disc.

So before you do that, check that your Tivo can get access through your router. I note your original post talked about setting port forwarding on your router - if you are going to do that then you will need a fixed IP address on your Tivo. Or at least a preferred IP (i.e. the same IP allocated to your Tivo every time it asks for a temporary address). Log-in to your router and (assuming it can do this) set the DHCP allocation so it always gives your Tivo the same IP address. Then (tempo) turn off any port forwarding to the Tivo. Reboot Tivo and see what you get in the log.

Hint: you can test whether Tivo can access out by running

Code: Select all

ping -c3 204.176.49.2

in telnet.

Cheers again. I'm off to my folks tomorrow to fix it up to my old PC hopefully then I can run the altepg 1.0d disk, and run nic_config_tivo (have I remembered all that correctly)

Re: port forwarding -hard to explain easily, that should all be unrelated will let you know how I get on

[a_tivo_noob]

Ok - so currently at my parents. Have tried:
1) Problemtic Tivo disk in a spare TiVo - still reboots (so issue with harddrive)
2) Mounting disk into PC, and re-running NIC configuration tool on 1.0d setup disk --> settings have been setup as (see screenshot on link below) - I've changed hostname to "alttivo" (from altepgtivo) for part 3
- http://ibb.co/g9fnWG
3) I've re-ran the earlier steps you suggested over my parents (Talktalk) broadband, plus, without any network connection. Results are:

Code: Select all

[TiVo [p0] ~]# cat /etc/dhclient.conf
send host-name "altepgtivo";
timeout 5 ;
[TiVo [p0] ~]#
[TiVo [p0] ~]# tail -f /var/log/tclient
10/04:20:45:10: /tvbin/TClient:  updateStatus: In Progress EX| 29 11
10/04:20:45:10: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
10/04:20:45:15: /tvbin/TClient:  updateStatus: Failed EX| 29 11
Saving 'EX|11' to result file
10/04:20:45:16: /tvbin/TClient:  writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
10/04:20:45:18: /tvbin/TClient:  writing Failed to LastCallStatus
10/04:20:45:19: /tvbin/TClient:  SendDialupEvent 30 9 EX|11
10/04:20:45:19: /tvbin/TClient:  TClient login: 1
10/04:20:45:19: /tvbin/TClient:  TClient backhaulDone: 0


as you can see... hostname has not changed!? :/

[a_tivo_noob]

Think i've done all I can do for the night - following on from my previous post, I think the "altepgtivo" not changing was a red herring (aka. misunderstanding on my part).

I've set the IP address as a different (static) IP to the one that my parents DHCP had assigned it, and I can ping it straight away with no beef. On telnetting the new IP address, I get the following (same) errors:

Code: Select all

[TiVo [p0] ~]# ping -c3 204.176.49.2
PING 204.176.49.2 (204.176.49.2): 56 data bytes
64 bytes from 204.176.49.2: icmp_seq=0 ttl=241 time=159.432 ms
64 bytes from 204.176.49.2: icmp_seq=1 ttl=241 time=156.764 ms
64 bytes from 204.176.49.2: icmp_seq=2 ttl=241 time=157.127 ms

--- 204.176.49.2 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max/stddev = 156.764/157.774/159.432 ms
[TiVo [p0] ~]#
[TiVo [p0] ~]# cat /etc/dhclient.conf
send host-name "altepgtivo";
timeout 5 ;
[TiVo [p0] ~]#
[TiVo [p0] ~]# tail -f /var/log/tclient
10/04:20:45:10: /tvbin/TClient:  updateStatus: In Progress EX| 29 11
10/04:20:45:10: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
10/04:20:45:15: /tvbin/TClient:  updateStatus: Failed EX| 29 11
Saving 'EX|11' to result file
10/04:20:45:16: /tvbin/TClient:  writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
10/04:20:45:18: /tvbin/TClient:  writing Failed to LastCallStatus
10/04:20:45:19: /tvbin/TClient:  SendDialupEvent 30 9 EX|11
10/04:20:45:19: /tvbin/TClient:  TClient login: 1
10/04:20:45:19: /tvbin/TClient:  TClient backhaulDone: 0


[Pegasus]

I would try setting a static ip on the tivo (appropriate to the router's lan, with netmask 255.255.255.0 and gateway = address of router).

[a_tivo_noob]

I would try setting a static ip on the tivo (appropriate to the router's lan, with netmask 255.255.255.0 and gateway = address of router).

Hey - thanks again for taking the time to try and help. I tried that as my last attempt (see post before yours). DHCP had been setting my router as ".3", so I went for a static IP of ".99".


The change definitely took place (I could telnet to the new IP address of the machine), but the output was still as follows, and reboots continued:

Code: Select all

[TiVo [p0] ~]# ping -c3 204.176.49.2
PING 204.176.49.2 (204.176.49.2): 56 data bytes
64 bytes from 204.176.49.2: icmp_seq=0 ttl=241 time=159.432 ms
64 bytes from 204.176.49.2: icmp_seq=1 ttl=241 time=156.764 ms
64 bytes from 204.176.49.2: icmp_seq=2 ttl=241 time=157.127 ms

--- 204.176.49.2 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max/stddev = 156.764/157.774/159.432 ms
[TiVo [p0] ~]#
[TiVo [p0] ~]# cat /etc/dhclient.conf
send host-name "altepgtivo";
timeout 5 ;
[TiVo [p0] ~]#
[TiVo [p0] ~]# tail -f /var/log/tclient
10/04:20:45:10: /tvbin/TClient:  updateStatus: In Progress EX| 29 11
10/04:20:45:10: /tvbin/TClient:  SendDialupEvent 30 9 EX|29
10/04:20:45:15: /tvbin/TClient:  updateStatus: Failed EX| 29 11
Saving 'EX|11' to result file
10/04:20:45:16: /tvbin/TClient:  writing Failed to LastCallStatus
retrying after errTmBackgroundHoldoff ...
10/04:20:45:18: /tvbin/TClient:  writing Failed to LastCallStatus
10/04:20:45:19: /tvbin/TClient:  SendDialupEvent 30 9 EX|11
10/04:20:45:19: /tvbin/TClient:  TClient login: 1
10/04:20:45:19: /tvbin/TClient:  TClient backhaulDone: 0

Really can't think of anything left to try

[Pegasus]

Ok let's take stock. It was fully working before you reconfigured the network access? You ran KS 57 to do a disk check - it took longer than 2 minutes to complete, yes?

Lack of dial access shouldn't cause a reboot, so I think there's something else going on. Check the main log for any clues:

Code: Select all

tail -n200 /var/log/Otvlog

I would also force a(nother) disk check with

Code: Select all

mfsassert -please

(I've found that sometimes it helps to run this twice to correct any database inconsistencies)

[a_tivo_noob]

Ok let's take stock. It was fully working before you reconfigured the network access? You ran KS 57 to do a disk check - it took longer than 2 minutes to complete, yes?

Lack of dial access shouldn't cause a reboot, so I think there's something else going on. Check the main log for any clues:

Code: Select all

tail -n200 /var/log/Otvlog

I would also force a(nother) disk check with

Code: Select all

mfsassert -please

(I've found that sometimes it helps to run this twice to correct any database inconsistencies)

Hi, yes, it has been working great for at least a year with no issues whatsoever. All was good until a reboot following the network re-configure.

I did KS57 to do a disk check, and it went greenscreen for around 10 minutes (maybe?)

Plan for tonight is to:
a) check the main log as per your suggestion
b) run mfsassert <--- can this be run over telnet even though the machine keeps rebooting? (if not, it may be a while before I get chance to go my parents and hook it up to a computer again)

Should I do KS57 again (or does 'b' cover that?)

[Pegasus]

Yes you can run mfsassert over telnet as soon as you can get in (i.e. before it reboots). Yes KS57is supposed to do the same thing but I prefer running it manually.

Hopefully the log will give a clue as to why it's rebooting.

[a_tivo_noob]

Ok let's take stock. It was fully working before you reconfigured the network access? You ran KS 57 to do a disk check - it took longer than 2 minutes to complete, yes?

Lack of dial access shouldn't cause a reboot, so I think there's something else going on. Check the main log for any clues:

Code: Select all

tail -n200 /var/log/Otvlog

I would also force a(nother) disk check with

Code: Select all

mfsassert -please

(I've found that sometimes it helps to run this twice to correct any database inconsistencies)

Well... here's the log:

Code: Select all

[TiVo [p0] ~]# tail -n200 /var/log/Otvlog
Oct  4 08:18:40 (none) last message repeated 311 times
Oct  4 08:18:40 (none) TmkTransform::Trace[337]: ClipCache 0x30016604 Secondary Reset
Oct  4 08:18:40 (none) ClipCache[334]: 0x30016604 in Entry(), thread 334
Oct  4 08:18:40 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:19:11 (none) last message repeated 278 times
Oct  4 08:20:12 (none) last message repeated 555 times
Oct  4 08:21:13 (none) last message repeated 554 times
Oct  4 08:22:43 (none) last message repeated 111 times
Oct  4 08:22:58 (none) last message repeated 144 times
Oct  4 08:22:58 (none) mempool[332]: MyWorld block: 1065kB/1548kB  chunk: 247kB/399kB  unused: 1694kB  search: 3 (size=3731424)
Oct  4 08:22:58 (none) mempool[332]: Osd block: 90kB/99kB  chunk: 940kB/940kB  unused: 1938kB  search: 0 (size=3051488)
Oct  4 08:22:58 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:26:03 (none) last message repeated 237 times
Oct  4 08:26:46 (none) last message repeated 392 times
Oct  4 08:26:47 (none) TmkMediaswitch::Trace[337]: Drift = -11
Oct  4 08:26:47 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:27:18 (none) last message repeated 282 times
Oct  4 08:28:35 (none) last message repeated 284 times
Oct  4 08:29:36 (none) last message repeated 419 times
Oct  4 08:31:23 (none) last message repeated 88 times
Oct  4 08:32:24 (none) last message repeated 553 times
Oct  4 08:33:29 (none) last message repeated 484 times
Oct  4 08:34:30 (none) last message repeated 553 times
Oct  4 08:35:31 (none) last message repeated 548 times
Oct  4 08:40:15 (none) last message repeated 137 times
Oct  4 08:40:37 (none) last message repeated 196 times
Oct  4 08:40:37 (none) Deadline[338]: Tasks run 1255500
Oct  4 08:40:37 (none) Deadline[338]: Existed for 284:83995:829193
Oct  4 08:40:37 (none) Deadline[338]: Overhead time 0:241:229470
Oct  4 08:40:37 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:41:08 (none) last message repeated 279 times
Oct  4 08:41:18 (none) last message repeated 99 times
Oct  4 08:41:18 (none) mempool[332]: MyWorld block: 1065kB/1548kB  chunk: 247kB/
399kB  unused: 1694kB  search: 3 (size=3731424)
Oct  4 08:41:18 (none) mempool[332]: Osd block: 90kB/99kB  chunk: 940kB/940kB  u
nused: 1938kB  search: 0 (size=3051488)
Oct  4 08:41:19 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:41:50 (none) last message repeated 282 times
Oct  4 08:42:51 (none) last message repeated 554 times
Oct  4 08:44:04 (none) last message repeated 238 times
Oct  4 08:45:39 (none) last message repeated 140 times
Oct  4 08:46:40 (none) last message repeated 331 times
Oct  4 08:48:56 (none) last message repeated 65 times
Oct  4 08:49:57 (none) last message repeated 325 times
Oct  4 08:51:55 (none) last message repeated 111 times
Oct  4 08:52:41 (none) last message repeated 258 times
Oct  4 08:52:41 (none) EventLogger[329]: EventLogger promoWiener list is empty,
resetting timer
Oct  4 08:52:41 (none) EventLogger[329]: EventLogger: Cached promowiener, 0 item
s
Oct  4 08:52:41 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 08:53:12 (none) last message repeated 278 times
Oct  4 08:54:24 (none) last message repeated 333 times
Oct  4 08:56:06 (none) last message repeated 232 times
Oct  4 08:57:16 (none) last message repeated 281 times
Oct  4 08:58:17 (none) last message repeated 422 times
Oct  4 08:58:35 (none) last message repeated 171 times
Oct  4 08:59:38 (none) mempool[332]: MyWorld block: 1065kB/1548kB  chunk: 247kB/
399kB  unused: 1694kB  search: 3 (size=3731424)
Oct  4 08:59:38 (none) mempool[332]: Osd block: 90kB/99kB  chunk: 940kB/940kB  u
nused: 1938kB  search: 0 (size=3051488)
Oct  4 09:00:20 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:00:51 (none) last message repeated 276 times
Oct  4 09:01:52 (none) last message repeated 550 times
Oct  4 09:02:24 (none) last message repeated 294 times
Oct  4 09:02:24 (none) EventLogger[329]: EventLogger clip list is empty, resetti
ng timer
Oct  4 09:02:24 (none) EventLogger[329]: EventLogger: Cached clips, 0 items
Oct  4 09:02:24 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:03:14 (none) last message repeated 217 times
Oct  4 09:04:15 (none) last message repeated 279 times
Oct  4 09:04:35 (none) last message repeated 183 times
Oct  4 09:05:04 (none) TmkTransform::Trace[337]: ClipCache 0x30015be4 Secondary
Reset
Oct  4 09:05:04 (none) ClipCache[333]: 0x30015be4 in Entry(), thread 333
Oct  4 09:06:06 (none) TmkMediaswitch::Trace[337]: Drift = -12
Oct  4 09:07:25 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:08:24 (none) last message repeated 257 times
Oct  4 09:09:25 (none) last message repeated 552 times
Oct  4 09:10:26 (none) last message repeated 554 times
Oct  4 09:11:27 (none) last message repeated 509 times
Oct  4 09:12:28 (none) last message repeated 550 times
Oct  4 09:12:40 (none) last message repeated 113 times
Oct  4 09:12:40 (none) EventLogger[329]: EventLogger: Cached Wish Lists, 1 items
Oct  4 09:12:40 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:12:41 (none) last message repeated 8 times
Oct  4 09:12:41 (none) EventLogger[329]: Done Logging wish lists
Oct  4 09:12:41 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:13:12 (none) last message repeated 206 times
Oct  4 09:14:13 (none) last message repeated 555 times
Oct  4 09:15:14 (none) last message repeated 554 times
Oct  4 09:16:15 (none) last message repeated 553 times
Oct  4 09:17:16 (none) last message repeated 553 times
Oct  4 09:17:58 (none) last message repeated 387 times
Oct  4 09:17:58 (none) mempool[332]: MyWorld block: 1065kB/1548kB  chunk: 247kB/
399kB  unused: 1694kB  search: 3 (size=3731424)
Oct  4 09:17:58 (none) mempool[332]: Osd block: 90kB/99kB  chunk: 940kB/940kB  u
nused: 1938kB  search: 0 (size=3051488)
Oct  4 09:17:58 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:18:29 (none) last message repeated 273 times
Oct  4 09:19:30 (none) last message repeated 555 times
Oct  4 09:20:31 (none) last message repeated 554 times
Oct  4 09:21:32 (none) last message repeated 555 times
Oct  4 09:22:33 (none) last message repeated 552 times
Oct  4 09:23:57 (none) last message repeated 368 times
Oct  4 09:24:28 (none) last message repeated 284 times
Oct  4 09:24:28 (none) EventLogger[329]: EventLogger: Cached Now Showing, 79 ite
ms
Oct  4 09:24:28 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:24:59 (none) last message repeated 275 times
Oct  4 09:25:01 (none) last message repeated 23 times
Oct  4 09:25:55 (none) EventLogger[329]: Done Logging now showing
Oct  4 09:28:02 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:28:48 (none) last message repeated 86 times
Oct  4 09:29:49 (none) last message repeated 450 times
Oct  4 09:30:37 (none) last message repeated 436 times
Oct  4 09:30:40 (none) Deadline[338]: Tasks run 1255600
Oct  4 09:30:40 (none) Deadline[338]: Existed for 285:598:839245
Oct  4 09:30:40 (none) Deadline[338]: Overhead time 0:241:236879
Oct  4 09:31:50 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:32:21 (none) last message repeated 279 times
Oct  4 09:34:18 (none) last message repeated 295 times
Oct  4 09:34:35 (none) MCPaction[208]: Already passed the call time! (1507109675
 vs 1507109612) sending event now.
Oct  4 09:34:35 (none) tcphonehome[208]: Sending message to myself
Oct  4 09:34:35 (none) tcphonehome[210]: getting /Setup/Complete
Oct  4 09:34:35 (none) tcphonehome[210]: useBackupTollFree is 0
Oct  4 09:34:35 (none) tcphonehome[210]: phonehome: recieved message. type = 30
subtype = 6
Oct  4 09:34:35 (none) tcphonehome[210]: Invoking TClient with arg
Oct  4 09:34:36 (none) tcphonehome[210]: GetPostalCodeVersion - /PostalCode/0100
0 ServerVersion=107
Oct  4 09:34:36 (none) tcphonehome[210]: GetPostalCodeVersion - /PostalCode/DBSL
OCAL~01 ServerVersion=122
Oct  4 09:34:36 (none) tcphonehome[210]: getting LastSuccessfulCall
Oct  4 09:34:36 (none) tcphonehome[210]: called getTZ
Oct  4 09:34:36 (none) tcphonehome[210]: Time zone not set, assuming Pacific
Oct  4 09:34:36 (none) tcphonehome[210]: IsPrimeTime: tz_off = -8, now = 3113770
4, adj_hour = 1
Oct  4 09:34:36 (none) tcphonehome[210]: checking ServiceState
Oct  4 09:34:36 (none) tcphonehome[210]: setCallStatus called with: In Progress
state 1
Oct  4 09:34:36 (none) tcphonehome[210]: Successfully got /Setup object
Oct  4 09:34:36 (none) tcphonehome[210]: Last call attempt at 1507022865
Oct  4 09:34:36 (none) tcphonehome[210]: setting call status to: In Progress
Oct  4 09:34:36 (none) tcphonehome[210]: Successfully got ServiceInfo subobject
(first successCount)
Oct  4 09:34:36 (none) tcphonehome[210]: setting call in progress to 1 (second s
uccessCount)
Oct  4 09:34:36 (none) tcphonehome[210]: End of transaction, err is 0 0x00000000
Oct  4 09:34:36 (none) tcphonehome[210]: Setting callActive to 1
Oct  4 09:34:36 (none) tcphonehome[210]: Running command: /bin/getprom -gateway
Oct  4 09:34:37 (none) tcphonehome[210]: Command output: 0.0.0.0
Oct  4 09:34:37 (none) tcphonehome[210]: Command finished status: 0, errno = 2
Oct  4 09:34:37 (none) tcphonehome[210]: Running command: /sbin/route add defaul
t gw 0.0.0.0 netmask 0.0.0.0
Oct  4 09:34:37 (none) tcphonehome[210]: Command finished status: 0, errno = 2
Oct  4 09:34:37 (none) tcphonehome[210]: setting call attempt
Oct  4 09:34:43 (none) tcphonehome[210]: Running TClient
Oct  4 09:34:43 (none) tcphonehome[210]: Running command: $TIVO_ROOT/tvbin/tivos
h $TIVO_ROOT/tvbin/TClient  -auto>> /var/log/tclient
Oct  4 09:34:26 (none) last message repeated 75 times
Oct  4 09:34:43 (none) tcl[14551]: Tcl created pool of 3145728 bytes
Oct  4 09:34:44 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:35:03 (none) EvtSwitcher[154]: Tmk client 309 (pid=14551) has attached
Oct  4 09:35:03 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) is s
ervice Phone Home
Oct  4 09:35:03 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551), thr
ead 14551 has activated MFS
Oct  4 09:35:00 (none) last message repeated 141 times
Oct  4 09:35:04 (none) TClient[14551]: activated
Oct  4 09:35:04 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) has
asked for 30 events
Oct  4 09:35:38 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) has
asked for 26 events
Oct  4 09:35:50 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:36:08 (none) last message repeated 166 times
Oct  4 09:36:11 (none) TClient[14551]: connect 127.0.0.1:8000
Oct  4 09:36:14 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:36:21 (none) last message repeated 41 times
Oct  4 09:36:21 (none) mempool[332]: MyWorld block: 1145kB/1548kB  chunk: 247kB/
399kB  unused: 1694kB  search: 3 (size=3731424)
Oct  4 09:36:21 (none) mempool[332]: Osd block: 90kB/99kB  chunk: 940kB/940kB  u
nused: 1938kB  search: 0 (size=3051488)
Oct  4 09:36:21 (none) TmkMediaswitch::Trace[337]: Lost VBI lock
Oct  4 09:36:30 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) has
asked for 77 events
Oct  4 09:37:42 (none) last message repeated 66 times
Oct  4 09:38:43 (none) last message repeated 544 times
Oct  4 09:38:58 (none) last message repeated 137 times
Oct  4 09:39:52 (none) FinishedLoading[14551]: done
Oct  4 09:39:56 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) has
asked for 23 events
Oct  4 09:39:56 (none) last message repeated 5 times
Oct  4 09:39:56 (none) DbGc[318]: got EVT_DATA_CHANGED/DATA_PROGRAM_GUIDE
Oct  4 09:39:56 (none) DbGc[318]: got INDEX_SOON
Oct  4 09:39:56 (none) DbGc[318]:   setting action's timeout to INDEX_SOON
Oct  4 09:39:56 (none) DbGcBaseAction[318]: Background->SetTimeout( 2 )
Oct  4 09:39:56 (none) DbGc[318]: got EVT_DATA_CHANGED/DATA_SHOWCASE
Oct  4 09:39:56 (none) DbGc[318]: got INDEX_SOON
Oct  4 09:39:56 (none) DbGc[318]:   setting action's timeout to INDEX_SOON
Oct  4 09:39:56 (none) EventLogger[329]: Calling ForcePeriodicLog in DATA_LOG_RE
VERT
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger: Cached Now Showing, 79 ite
ms
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger: Cached Wish Lists, 1 items
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger clip list is empty, resetti
ng timer
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger: Cached clips, 0 items
Oct  4 09:39:56 (none) TClient[14551]: completed
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger promoWiener list is empty,
resetting timer
Oct  4 09:39:56 (none) EventLogger[329]: EventLogger: Cached promowiener, 0 item
s
Oct  4 09:39:57 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) says
 he has finished
Oct  4 09:39:57 (none) EvtSwitcher[154]: Client 309 (Phone Home, pid=14551) bein
g removed
Oct  4 09:39:57 (none) EventLogger[329]: Done Logging wish lists
Oct  4 09:39:57 (none) tcphonehome[210]: Command finished status: 0, errno = 2
Oct  4 09:39:57 (none) tcphonehome[210]: TClient returns status 0
Oct  4 09:39:57 (none) tcphonehome[210]: TClient success
Oct  4 09:39:57 (none) tcphonehome[210]: read /var/tmp/TClient.res TClient::logi
n:retcode 0
Oct  4 09:39:57 (none) tcphonehome[210]: matched, retcode = 0
Oct  4 09:39:57 (none) tcphonehome[210]: read /var/tmp/TClient.res TClient::back
HaulDone 1
Oct  4 09:39:57 (none) tcphonehome[210]: matched, backHaulDone = 1
Oct  4 09:39:57 (none) tcphonehome[210]: read /var/tmp/TClient.res TClient::back
HaulDone 1
Oct  4 09:39:57 (none) tcphonehome[210]: matched, backHaulDone = 1
Oct  4 09:39:57 (none) tcphonehome[210]: tcphonehome: found TClient retcode = 0
Oct  4 09:39:57 (none) tcphonehome[210]: tcphonehome: found TClient backHaulDone
 = 1
Oct  4 09:39:57 (none) tcphonehome[210]: checking ServiceState
Oct  4 09:39:57 (none) tcphonehome[210]: ReschedCall: reason = 0, secSinceLast =
 0, numFails = 0
Oct  4 09:39:57 (none) tcphonehome[210]: ReschedCall: reschedule after success
Oct  4 09:39:57 (none) tcphonehome[210]: setting next attempt at 1507196457 (Thu
 Oct  5 09:40:57 2017 )
Oct  4 09:39:57 (none) tcphonehome[210]: Resched Call: resched for 1441 min; sho
uld happen at Thu Oct  5 09:40:57 2017
Oct  4 09:39:57 (none) tcphonehome[210]: Rotating log files
[TiVo [p0] ~]#
[TiVo [p0] ~]#

......but, its currently up and running, with no reboot in sight. Not sure what's happened, but I'm scared to move it now and will post back if the fault redevelops.

edit: turned it off, put the RAM back in, screwed it all back together, turned it on, and it's buggered again - sods law! *argh* RAM has been out since the 1st post, so can't be that causing the problem :/