Heres the tclient log - part1-
Aug 26 15:10:21 (none) tcphonehome[119]: TCphonehome active
Aug 26 15:10:21 (none) tcphonehome[122]: cleaning /Setup
Aug 26 15:10:21 (none) tcphonehome[122]: Fetching /SwSystemACTIVE/Name
Aug 26 15:10:21 (none) tcphonehome[122]: Last Call Status Was: 'Failed' (size 6)
Aug 26 15:10:21 (none) tcphonehome[122]: Last Call Status Will Be: Failed
Aug 26 15:10:21 (none) tcphonehome[122]: setCallStatus called with: Failed state 0
Aug 26 15:10:21 (none) tcphonehome[122]: LastCallAttempt at 1125066194
Aug 26 15:10:21 (none) tcphonehome[122]: setting call status to: Failed
Aug 26 15:10:21 (none) tcphonehome[122]: setting call in progress to 0 (second successCount)
Aug 26 15:10:21 (none) tcphonehome[122]: Setting callActive to 0
Aug 26 15:10:21 (none) tcphonehome[122]: PhoneHome: statusValue = <0>, getResult = <0>
Aug 26 15:10:21 (none) tcphonehome[122]: /State/Avalanche LastStatus was <0> and getResult was <0>, changing to unknown
Aug 26 15:10:21 (none) tcphonehome[122]: finished cleaning /Setup
Aug 26 15:10:21 (none) tcphonehome[122]: About to execute: /sbin/ifconfig eth0 2>&1
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: eth0 Link encap:Ethernet HWaddr 00:0B:AD:69:C6:67
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: inet addr:0.0.0.0 Bcast:255.255.255.255 Mask:0.0.0.0
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: RX packets:0 errors:0 dropped:0 overruns:0 frame:0
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 coll:0
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output: Interrupt:29
Aug 26 15:10:21 (none) tcphonehome[122]: Command Output:
Aug 26 15:10:21 (none) tcphonehome[122]: Command succeeded
Aug 26 15:10:21 (none) tcphonehome[122]: NOT Using debug ethernet
Aug 26 15:10:21 (none) tcphonehome[122]: Error statting file /var/log/http: No such file or directory (errno=2)
Aug 26 15:10:21 (none) tcphonehome[122]: Error statting file /var/log/Ohttp: No such file or directory (errno=2)
Aug 26 15:10:21 (none) tcphonehome[122]: Timesync is within the past 5 days... all is well
Aug 26 15:10:21 (none) tcphonehome[122]: ReschedCall: reason = 7, secSinceLast = 0, numFails = 0
Aug 26 15:10:21 (none) tcphonehome[122]: Schedule call after failure
Aug 26 15:10:21 (none) tcphonehome[122]: setting next attempt at 1125071181 (Fri Aug 26 15:46:21 2005 )
Aug 26 15:10:21 (none) tcphonehome[122]: ReschedCall(7, 0) in 36 min
Aug 26 15:10:21 (none) PhoneAction[122]: PhoneAction 2 register complete timeout 2160
Aug 26 15:10:21 (none) PhoneAction[122]: Register Complete: Next call should happen at Fri Aug 26 15:46:21 2005
Aug 26 15:10:21 (none) tcphonehome[122]: called getTZ
Aug 26 15:10:21 (none) WatchdogAction[122]: WatchdogAction register complete
Aug 26 15:11:29 (none) tcphonehome[122]: Setting MCP PhoneHome action: RUNNING
Aug 26 15:11:29 (none) tcphonehome[122]: pCommMempool empty
Aug 26 15:11:29 (none) tcphonehome[122]: useBackupTollFree is 1
Aug 26 15:11:29 (none) tcphonehome[122]: phonehome: received message. type = 30 subtype = 2
Aug 26 15:11:29 (none) tcphonehome[122]: creating CommGlobals
Aug 26 15:11:37 (none) tcphonehome[122]: created CommGlobals
Aug 26 15:11:37 (none) tcphonehome[122]: PerformCall, subtype=2 forceTollFree=1 automated=0
Aug 26 15:11:37 (none) tcphonehome[122]: setCallStatus called with: In Progress state 1
Aug 26 15:11:37 (none) tcphonehome[122]: LastCallAttempt at 1125069089
Aug 26 15:11:37 (none) tcphonehome[122]: setting call status to: In Progress
Aug 26 15:11:37 (none) tcphonehome[122]: setting call in progress to 1 (second successCount)
Aug 26 15:11:37 (none) tcphonehome[122]: Setting callActive to 1
Aug 26 15:11:37 (none) tcphonehome[122]: setting call attempt
Aug 26 15:11:37 (none) tcphonehome[122]: Error statting file /var/log/http: No such file or directory (errno=2)
Aug 26 15:11:37 (none) tcphonehome[122]: Error statting file /var/log/Ohttp: No such file or directory (errno=2)
Aug 26 15:11:37 (none) tcphonehome[122]: Calling TClient
Aug 26 15:11:37 (none) comm[122]: CallStatusReporter: Phase: Select_Phone, Status In Progress
Aug 26 15:11:37 (none) comm[122]: CallStatusReporter: sending message "EX|29"
Aug 26 15:11:37 (none) comm[122]: CallService: preprocessing call.
Aug 26 15:11:37 (none) comm[122]: CallStatusReporter: Phase: Prepare_Call, Status In Progress
Aug 26 15:11:37 (none) comm[122]: CallStatusReporter: sending message "EX|29"
Aug 26 15:11:45 (none) comm[122]: LogMgr: start writing to GzPacker(/var/tmp/syslog.gz)
Aug 26 15:11:45 (none) comm[122]: skipping filter line itself!
Aug 26 15:11:58 (none) last message repeated 8490 times
Aug 26 15:11:58 (none) comm[122]: LogMgr: end writing to GzPacker(/var/tmp/syslog.gz)
Aug 26 15:11:58 (none) comm[122]: Starting Private Logs processing; send event to MW
Aug 26 15:11:58 (none) comm[122]: waiting for logs to divert...
Aug 26 15:11:58 (none) comm[121]: Received the LOGMGR WAIT event
Aug 26 15:11:58 (none) comm[122]: logs diverted.
Aug 26 15:11:58 (none) comm[122]: Do actual private logs processing
Aug 26 15:11:59 (none) comm[122]: Error getting demographic info: No Tuner Count
Aug 26 15:11:59 (none) comm[122]: CmdProc: wrote /var/persist/thumbslog.log.gz
Aug 26 15:11:59 (none) comm[122]: encryptFile: infile = /var/persist/thumbslog.log.gz outfile = /var/persist/thumbslog.log.gz.bfg keyname = BACKHAUL_THUMB
Aug 26 15:11:59 (none) comm[122]: About to execute: /tvbin/genkey 128 1 2>&1
Aug 26 15:12:00 (none) comm[122]: Command succeeded
Aug 26 15:12:00 (none) comm[122]: EncryptLog: key was empty for BACKHAUL_THUMB
Aug 26 15:12:04 (none) comm[122]: CmdProc: wrote /var/log/tivoLog.prv.gz
Aug 26 15:12:04 (none) comm[122]: encryptFile: infile = /var/log/tivoLog.prv.gz outfile = /var/log/tivoLog.prv.gz.bfg keyname = BACKHAUL_PRIVATE
Aug 26 15:12:04 (none) comm[122]: About to execute: /tvbin/genkey 128 1 2>&1
Aug 26 15:12:04 (none) comm[122]: Command succeeded
Aug 26 15:12:04 (none) comm[122]: EncryptLog: key was empty for BACKHAUL_PRIVATE
Aug 26 15:12:04 (none) comm[122]: Done with actual private logs processing
Aug 26 15:12:04 (none) comm[122]: About to execute: /tvbin/crypto -gsn 2>&1
Aug 26 15:12:05 (none) comm[122]: Command succeeded
Aug 26 15:12:05 (none) comm[122]: About to execute: /tvbin/crypto -gsv 2>&1
Aug 26 15:12:06 (none) comm[122]: Command succeeded
Aug 26 15:12:07 (none) comm[122]: About to execute: /tvbin/crypto -x -gek 2>&1
Aug 26 15:12:13 (none) comm[122]: Command succeeded
Aug 26 15:12:13 (none) comm[122]: SvcLogRqst: open(/var/log/svclog.tmp)ed (tmpfd = 19)
Aug 26 15:12:13 (none) comm[122]: SvcLogRqst: open(/var/log/svclog.upload)ed (uploadfd = 20)
Aug 26 15:12:13 (none) comm[122]: SvcLogRqst: final (351 bytes)::read() (status = 0)
Aug 26 15:12:13 (none) comm[122]: SvcLogRqst: unlink(/var/log/svclog.tmp)ed (status = 0)
Aug 26 15:12:13 (none) comm[122]: SvcLogRqst: rename failed (/var/log/commlog probably doesn't exist) skipping
Aug 26 15:12:13 (none) comm[122]: Start TCD411 Request ==================
Aug 26 15:12:13 (none) comm[122]: areaCode: 002
Aug 26 15:12:13 (none) comm[122]: prefix: %2C%23401
Aug 26 15:12:13 (none) comm[122]: dialInAreaCode:
Aug 26 15:12:13 (none) comm[122]: curPhNum:
Aug 26 15:12:13 (none) comm[122]: objVer:
Aug 26 15:12:13 (none) comm[122]: tcdId: 0020000202190EF
Aug 26 15:12:13 (none) comm[122]: callId: 1125069097
Aug 26 15:12:13 (none) comm[122]: dialPrefix: %2C%23401
Aug 26 15:12:13 (none) comm[122]: dialToneCheck: 0
Aug 26 15:12:13 (none) comm[122]: offHookDetect: 0
Aug 26 15:12:13 (none) comm[122]: tonePulseDial: T
Aug 26 15:12:13 (none) comm[122]: callWaitPrefix:
Aug 26 15:12:13 (none) comm[122]: tollFreeAuth: 1
Aug 26 15:12:13 (none) comm[122]: callType: TS_SR_ACCEPTED
Aug 26 15:12:13 (none) comm[122]: swVerName: 3.0-01-1-000
Aug 26 15:12:13 (none) comm[122]: End TCD411 Request ==================
Aug 26 15:12:13 (none) comm[122]: sigType=st=2 connector=con=1...
Aug 26 15:12:13 (none) comm[122]: Error getting the Avalanche Disk Directories: errNmNameNotFound
Aug 26 15:12:13 (none) comm[122]: Start Ident ==================
Aug 26 15:12:13 (none) comm[122]: version: 3
Aug 26 15:12:13 (none) comm[122]: centerID: 0020000202190EF
Aug 26 15:12:13 (none) comm[122]: reasonCode: 6
Aug 26 15:12:13 (none) comm[122]: 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-1|6406
Aug 26 15:12:13 (none) comm[122]: 320-1|6406321-1|6406322-1|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 26 15:12:13 (none) comm[122]: 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|
Bookmarks