Test call doesn't work either.
The log stops at 9 January for some reason ?! Last logs look like this:
Jan 9 06:28:30 (none) Recorder[153]: Free live cache chunk: 1550622
Jan 9 06:28:30 (none) Recorder[153]: Adding check schedule task
Jan 9 06:28:38 (none) mempool[146]: MyWorld block: 902kB/1137kB chunk: 242kB/422kB unused: 2082kB search: 1 (size=3731424)
Jan 9 06:28:38 (none) mempool[146]: Osd block: 74kB/83kB chunk: 949kB/1245kB unused: 1649kB search: 0 (size=3051488)
Jan 9 06:30:05 (none) Recorder[153]: AllocateFiles for live cache
Jan 9 06:30:05 (none) Recorder[153]: Live cache size 1462272
Jan 9 06:30:05 (none) Recorder[153]: User recording 104700288 free 1137024
Jan 9 06:30:05 (none) Recorder[153]: TivoClip total 10000000 free 9712256
Jan 9 06:30:05 (none) Recorder[153]: Allocated stream Id 1550678, size 208896
Jan 9 06:30:05 (none) mediamgr[153]: AddLiveFile input#0
Jan 9 06:30:06 (none) Recorder[153]: Adding check schedule task
Jan 9 06:31:22 (none) TmkTransform::Trace[152]: ClipCache 0x300143d8 Secondary Reset
Jan 9 06:31:22 (none) ClipCache[148]: 0x300143d8 in Entry(), thread 148
Jan 9 06:32:04 (none) TmkMediaswitch::Trace[152]: Drift = -5
Jan 9 06:34:26 (none) mediamgr[153]: RemoveWrittenLiveFile input#0
Jan 9 06:34:26 (none) MultiMpegCache[153]: Delete stream 1550623(inx = 0, read = 0, write =7) totalCount 8
Jan 9 06:34:26 (none) TmkMultiMpegClip::Trace[153]: Tried to delete reader clip
Jan 9 06:34:26 (none) TmkMultiMpegClip::Trace[153]: bactrace is 01be8018 01be75bc 01bdf048 01b06378 01b05c90 01b98f88 01b98e24 01b03f80 01b04e88 01b91bb4
Jan 9 06:34:26 (none) Recorder[153]: Free live cache chunk: 1550623
Jan 9 06:34:26 (none) Recorder[153]: Adding check schedule task
Jan 9 06:35:07 (none) Recorder[153]: AllocateFiles for live cache
Jan 9 06:35:07 (none) Recorder[153]: Live cache size 1462272
Jan 9 06:35:07 (none) Recorder[153]: User recording 104700288 free 1137024
Jan 9 06:35:07 (none) Recorder[153]: TivoClip total 10000000 free 9712256
Jan 9 06:35:07 (none) Recorder[153]: Allocated stream Id 1550679, size 208896
Jan 9 06:35:07 (none) mediamgr[153]: AddLiveFile input#0
Jan 9 06:35:07 (none) Recorder[153]: Adding check schedule task
Jan 9 06:36:17 (none) TmkTransform::Trace[152]: ClipCache 0x3000697c Secondary Reset
Jan 9 06:36:17 (none) ClipCache[147]: 0x3000697c in Entry(), thread 147
Jan 9 06:37:57 (none) MCPaction[119]: Trigger action 2 client 1!
Jan 9 06:37:57 (none) tcphonehome[119]: getting /Setup/Complete
Jan 9 06:37:57 (none) MCPaction[119]: Set complete: 7
Jan 9 06:37:57 (none) tcphonehome[119]: useBackupTollFree is 0
Jan 9 06:37:57 (none) tcphonehome[119]: phonehome: recieved message. type = 30 subtype = 6
Jan 9 06:37:57 (none) tcphonehome[119]: Invoking TClient with arg
Jan 9 06:37:57 (none) tcphonehome[119]: GetPostalCodeVersion - /PostalCode/PE28 ServerVersion=675
Jan 9 06:37:57 (none) tcphonehome[119]: getting LastSuccessfulCall
Jan 9 06:37:58 (none) tcphonehome[119]: called getTZ
Jan 9 06:37:58 (none) tcphonehome[119]: IsPrimeTime: tz_off = 0, now = 31137704, adj_hour = 6
Jan 9 06:37:58 (none) tcphonehome[119]: checking ServiceState
Jan 9 06:37:58 (none) tcphonehome[119]: setCallStatus called with: In Progress state 1
Jan 9 06:37:58 (none) tcphonehome[119]: Successfully got /Setup object
Jan 9 06:37:58 (none) tcphonehome[119]: Last call attempt at 1136701681
Jan 9 06:37:58 (none) tcphonehome[119]: setting call status to: In Progress
Jan 9 06:37:58 (none) tcphonehome[119]: Successfully got ServiceInfo subobject (first successCount)
Jan 9 06:37:58 (none) tcphonehome[119]: setting call in progress to 1 (second successCount)
Jan 9 06:37:58 (none) tcphonehome[119]: End of transaction, err is 0 0x00000000
Jan 9 06:37:58 (none) tcphonehome[119]: Setting callActive to 1
Jan 9 06:37:58 (none) tcphonehome[119]: setting call attempt
Jan 9 06:37:58 (none) tcphonehome[119]: Running TClient
Jan 9 06:37:58 (none) tcphonehome[119]: Running command: $TIVO_ROOT/tvbin/tivosh $TIVO_ROOT/tvbin/TClient -auto>> /var/log/tclient
Jan 9 06:37:58 (none) tcl[349]: Tcl created pool of 3145728 bytes
Jan 9 06:38:09 (none) EvtSwitcher[74]: Tmk client 14 (pid=349) has attached
Jan 9 06:38:09 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) is service Phone Home
Jan 9 06:38:09 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349), thread 349 has activated MFS
Jan 9 06:38:09 (none) TClient[349]: activated
Jan 9 06:38:09 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) has asked for 30 events
Jan 9 06:38:15 (none) mediamgr[153]: RemoveWrittenLiveFile input#0
Jan 9 06:38:15 (none) MultiMpegCache[153]: Delete stream 1550624(inx = 0, read = 0, write =7) totalCount 8
Jan 9 06:38:15 (none) TmkMultiMpegClip::Trace[153]: Tried to delete reader clip
Jan 9 06:38:15 (none) TmkMultiMpegClip::Trace[153]: bactrace is 01be8018 01be75bc 01bdf048 01b06378 01b05c90 01b98f88 01b98e24 01b03f80 01b04e88 01b91bb4
Jan 9 06:38:16 (none) Recorder[153]: Free live cache chunk: 1550624
Jan 9 06:38:16 (none) Recorder[153]: Adding check schedule task
Jan 9 06:38:36 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) has asked for 26 events
Jan 9 06:39:36 (none) Recorder[153]: AllocateFiles for live cache
Jan 9 06:39:36 (none) Recorder[153]: Live cache size 1462272
Jan 9 06:39:36 (none) Recorder[153]: User recording 104700288 free 1137024
Jan 9 06:39:36 (none) Recorder[153]: TivoClip total 10000000 free 9712256
Jan 9 06:39:36 (none) Recorder[153]: Allocated stream Id 1550680, size 208896
Jan 9 06:39:36 (none) mediamgr[153]: AddLiveFile input#0
Jan 9 06:39:36 (none) Recorder[153]: Adding check schedule task
Jan 9 06:40:08 (none) TClient[349]: connect 204.176.49.3:80
Jan 9 06:40:32 (none) FinishedLoading[349]: done
Jan 9 06:40:33 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) has asked for 77 events
Jan 9 06:40:40 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) has asked for 23 events
Jan 9 06:40:40 (none) last message repeated 3 times
Jan 9 06:40:40 (none) DbGc[139]: got EVT_DATA_CHANGED/DATA_PROGRAM_GUIDE
Jan 9 06:40:40 (none) DbGc[139]: got INDEX_SOON
Jan 9 06:40:40 (none) DbGc[139]: setting action's timeout to INDEX_SOON
Jan 9 06:40:40 (none) DbGcBaseAction[139]: Background->SetTimeout( 2 )
Jan 9 06:40:40 (none) DbGc[139]: got EVT_DATA_CHANGED/DATA_SHOWCASE
Jan 9 06:40:40 (none) DbGc[139]: got INDEX_SOON
Jan 9 06:40:40 (none) DbGc[139]: setting action's timeout to INDEX_SOON
Jan 9 06:40:40 (none) EventLogger[144]: Calling ForcePeriodicLog in DATA_LOG_REVERT
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger: Cached Now Showing, 72 items
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger: Cached Wish Lists, 6 items
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger clip list is empty, resetting timer
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger: Cached clips, 0 items
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger promoWiener list is empty, resetting timer
Jan 9 06:40:41 (none) EventLogger[144]: EventLogger: Cached promowiener, 0 items
Jan 9 06:40:41 (none) TClient[349]: completed
Jan 9 06:40:41 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) says he has finished
Jan 9 06:40:41 (none) EvtSwitcher[74]: Client 14 (Phone Home, pid=349) being removed
Jan 9 06:40:41 (none) tcphonehome[119]: Command finished status: 0, errno = 2
Jan 9 06:40:41 (none) tcphonehome[119]: TClient returns status 0
Jan 9 06:40:41 (none) tcphonehome[119]: TClient success
Jan 9 06:40:41 (none) tcphonehome[119]: read /var/tmp/TClient.res TClient::login:retcode 0
Jan 9 06:40:41 (none) tcphonehome[119]: matched, retcode = 0
Jan 9 06:40:41 (none) tcphonehome[119]: read /var/tmp/TClient.res TClient::backHaulDone 1
Jan 9 06:40:41 (none) tcphonehome[119]: matched, backHaulDone = 1
Jan 9 06:40:41 (none) tcphonehome[119]: read /var/tmp/TClient.res TClient::backHaulDone 1
Jan 9 06:40:41 (none) tcphonehome[119]: matched, backHaulDone = 1
Jan 9 06:40:41 (none) tcphonehome[119]: tcphonehome: found TClient retcode = 0
Jan 9 06:40:41 (none) tcphonehome[119]: tcphonehome: found TClient backHaulDone = 1
Jan 9 06:40:41 (none) tcphonehome[119]: checking ServiceState
Jan 9 06:40:42 (none) tcphonehome[119]: ReschedCall: reason = 0, secSinceLast = 0, numFails = 0
Jan 9 06:40:42 (none) tcphonehome[119]: ReschedCall: reschedule after success
Jan 9 06:40:42 (none) tcphonehome[119]: setting next attempt at 1136875302 (Tue Jan 10 06:41:42 2006 )
Jan 9 06:40:42 (none) tcphonehome[119]: Resched Call: resched for 1441 min; should happen at Tue Jan 10 06:41:42 2006
Jan 9 06:40:42 (none) tcphonehome[119]: Running command: $TIVO_ROOT/tvbin/modemtest AT\&V1\&V2
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: AT&V1&V2
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: TERMINATION REASON.......... LOCAL REQUEST
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: LAST TX rate................ 24000 BPS
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: HIGHEST TX rate............. 24000 BPS
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: LAST RX rate................ 31200 BPS
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: HIGHEST RX rate............. 31200 BPS
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: PROTOCOL.................... LAPM
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: COMPRESSION................. NONE
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Line QUALITY................ 042
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Rx LEVEL.................... 028
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Highest Rx State............ 67
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Highest TX State............ 67
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: EQM Sum..................... 003C
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: RBS Pattern................. FF
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Rate Drop................... FF
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Digital Loss................ None
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Local Rtrn Count............ 00
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Remote Rtrn Count........... 00
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: Flex fail
Jan 9 06:40:43 (none) tcphonehome[119]: Command output:
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: BEGINaa12ab12ac12ad12ba15bb15bc15bd15ca77cb69cc205da5ea0eb0fa68fb67fc68ga12gb3ha42hb36hc44hd0he65hf60hg0hh0hi0hj40hk44hl40hm40h
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: n39ho43hp42hq39hr41hs43ia23ib23ic23ja0jb0jc0jd0je0jf0ka1kb1kc1kd1ke16kf16kg0kh4ki38kj0kk0kl37km33kn255la103lb103lc103ld103ma0mb
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: 0mc22na0nb0oa0ob255oc255od20oe254of7og181pa1pb0pc0pd0qa0qb1qc6ra2rb255rc255rd255re255rf255rg255rh213sa255sb255sc255sd255ta15tb0
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: tc0td218te255tf255tg255th0ti0END
Jan 9 06:40:43 (none) tcphonehome[119]: Command output:
Jan 9 06:40:43 (none) tcphonehome[119]: Command output: OK
Jan 9 06:40:43 (none) tcphonehome[119]: Command finished status: 0, errno = 2
Jan 9 06:40:43 (none) tcphonehome[119]: Rotating log files