benallenuk
03-18-2007, 12:49 PM
Tivo keeps crashing requiring a power cycle. It crashed on the 18th at 06.47, i have attached log files 10mins before and after. can someone tell me why its happening.
Mar 18 06:33:15 (none) last message repeated 552 times
Mar 18 06:34:16 (none) last message repeated 552 times
Mar 18 06:34:19 (none) last message repeated 34 times
Mar 18 06:34:19 (none) TmkTransform::Trace[201]: ClipCache 0x30015124 Secondary Reset
Mar 18 06:34:19 (none) ClipCache[197]: 0x30015124 in Entry(), thread 197
Mar 18 06:34:19 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:36:14 (none) last message repeated 29 times
Mar 18 06:38:10 (none) last message repeated 119 times
Mar 18 06:38:42 (none) last message repeated 288 times
Mar 18 06:39:04 (none) TmkTransform::Trace[201]: ClipCache 0x30012fbc Secondary Reset
Mar 18 06:39:04 (none) ClipCache[198]: 0x30012fbc in Entry(), thread 198
Mar 18 06:39:59 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:40:30 (none) last message repeated 278 times
Mar 18 06:41:31 (none) last message repeated 554 times
Mar 18 06:41:52 (none) last message repeated 193 times
Mar 18 06:42:09 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:42:09 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:42:09 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:42:09 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:42:09 (none) Recorder[202]: Allocated stream Id 1748846, size 208896
Mar 18 06:42:09 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:42:09 (none) Recorder[202]: Adding check schedule task
Mar 18 06:42:17 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:42:17 (none) MultiMpegCache[202]: Delete stream 1748781(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:42:17 (none) Recorder[202]: Free live cache chunk: 1748781
Mar 18 06:42:27 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:42:58 (none) last message repeated 279 times
Mar 18 06:43:18 (none) last message repeated 189 times
Mar 18 06:43:18 (none) TmkTransform::Trace[201]: ClipCache 0x30010274 Secondary Reset
Mar 18 06:43:18 (none) ClipCache[197]: 0x30010274 in Entry(), thread 197
Mar 18 06:43:18 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:43:49 (none) last message repeated 273 times
Mar 18 06:44:21 (none) last message repeated 292 times
Mar 18 06:46:05 (none) mempool[196]: MyWorld block: 763kB/1052kB chunk: 144kB/244kB unused: 2345kB search: 1 (size=3731424)
Mar 18 06:46:05 (none) mempool[196]: Osd block: 104kB/118kB chunk: 969kB/1265kB unused: 1594kB search: 0 (size=3051488)
Mar 18 06:47:05 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:47:05 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:47:05 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:47:05 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:47:05 (none) Recorder[202]: Allocated stream Id 1748847, size 208896
Mar 18 06:47:05 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:47:05 (none) Recorder[202]: Adding check schedule task
Mar 18 06:47:13 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:47:13 (none) MultiMpegCache[202]: Delete stream 1748783(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:38:42 (none) last message repeated 288 times
Mar 18 06:39:04 (none) TmkTransform::Trace[201]: ClipCache 0x30012fbc Secondary Reset
Mar 18 06:39:04 (none) ClipCache[198]: 0x30012fbc in Entry(), thread 198
Mar 18 06:39:59 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:40:30 (none) last message repeated 278 times
Mar 18 06:41:31 (none) last message repeated 554 times
Mar 18 06:41:52 (none) last message repeated 193 times
Mar 18 06:42:09 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:42:09 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:42:09 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:42:09 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:42:09 (none) Recorder[202]: Allocated stream Id 1748846, size 208896
Mar 18 06:42:09 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:42:09 (none) Recorder[202]: Adding check schedule task
Mar 18 06:42:17 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:42:17 (none) MultiMpegCache[202]: Delete stream 1748781(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:42:17 (none) Recorder[202]: Free live cache chunk: 1748781
Mar 18 06:42:27 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:42:58 (none) last message repeated 279 times
Mar 18 06:43:18 (none) last message repeated 189 times
Mar 18 06:43:18 (none) TmkTransform::Trace[201]: ClipCache 0x30010274 Secondary Reset
Mar 18 06:43:18 (none) ClipCache[197]: 0x30010274 in Entry(), thread 197
Mar 18 06:43:18 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:43:49 (none) last message repeated 273 times
Mar 18 06:44:21 (none) last message repeated 292 times
Mar 18 06:46:05 (none) mempool[196]: MyWorld block: 763kB/1052kB chunk: 144kB/244kB unused: 2345kB search: 1 (size=3731424)
Mar 18 06:46:05 (none) mempool[196]: Osd block: 104kB/118kB chunk: 969kB/1265kB unused: 1594kB search: 0 (size=3051488)
Mar 18 06:47:05 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:47:05 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:47:05 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:47:05 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:47:05 (none) Recorder[202]: Allocated stream Id 1748847, size 208896
Mar 18 06:47:05 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:47:05 (none) Recorder[202]: Adding check schedule task
Mar 18 06:47:13 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:47:13 (none) MultiMpegCache[202]: Delete stream 1748783(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:47:14 (none) Recorder[202]: Free live cache chunk: 1748783
Mar 18 09:02:45 (none) EvtSwitcher[110]: EventSwitcher is starting up
Mar 18 09:02:45 (none) MfsDaemon[111]: MFS Daemon started, id is 111
Mar 18 09:02:45 (none) MfsDaemon[111]: Initializing MFS
Mar 18 09:02:45 (none) MfsDaemon[111]: Found 1 analog, 0 digital tuners: 364544-byte heap
Mar 18 09:02:45 (none) NmCache[111]: Cache at 0x0x7fee0cc4, array of 100 entries at 0x0x7fee0d4c
Mar 18 09:02:47 (none) MfsDaemon[111]: The MfsDaemon is starting up
Mar 18 09:02:47 (none) MfsDaemon[111]: Initialization complete
Mar 18 09:02:47 (none) EvtSwitcher[110]: MFS Daemon started, id is 111
Mar 18 09:02:47 (none) EvtSwitcher[110]: Tmk client 1 (pid=109) has attached
Mar 18 09:02:47 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:47 (none) EvtSwitcher[110]: Group 109 was not an MFS group
Mar 18 09:02:47 (none) EvtSwitcher[110]: Client 1 (pid=109) being removed
Mar 18 09:02:47 (none) EvtSwitcher[110]: Tmk client 2 (pid=111) has attached
Mar 18 09:02:47 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:47 (none) EvtSwitcher[110]: Client 2 (pid=111), thread 111 has activated MFS
Mar 18 09:02:51 (none) DbUpgrader[136]: majorVersionNum:4
Mar 18 09:02:51 (none) DbUpgrader[136]: minorVersionNum:40
Mar 18 09:02:51 (none) DbUpgrader[136]: converted majorVersionNum:4
Mar 18 09:02:51 (none) DbUpgrader[136]: converted minorVersionNum:40
Mar 18 09:02:51 (none) DbUpgrader[136]: Existing version: 4.40
Mar 18 09:02:51 (none) DbUpgrader[136]: Database is already up to date.
Mar 18 09:02:51 (none) convert-db[136]: no pending ZapRequests
Mar 18 09:02:51 (none) convert-db[136]: High water mark:
Mar 18 09:02:51 (none) convert-db[136]: tmk 1348/ 0 bytes ( 19 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: directory 112/ 1536 bytes ( 2 blocks/ 2 chunks)
Mar 18 09:02:51 (none) convert-db[136]: database 268/ 512 bytes ( 6 blocks/ 1 chunks)
Mar 18 09:02:51 (none) convert-db[136]: executive 44/ 0 bytes ( 2 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: tmkevent 32/ 0 bytes ( 1 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: TOTAL 1748/ 2048 bytes ( 29 blocks/ 3 chunks)
Mar 18 09:02:51 (none) tcl[137]: Tcl created pool of 1458176 bytes
Mar 18 09:02:52 (none) EvtSwitcher[110]: Tmk client 3 (pid=137) has attached
Mar 18 09:02:52 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137), thread 137 has activated MFS
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137) says he has finished
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137) being removed
Mar 18 09:02:52 (none) EvtSwitcher[110]: LaunchPhasedService
Mar 18 09:02:52 (none) EvtService[110]: Launching /tvbin/mcp phase 10
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 4 being removed
Mar 18 09:02:53 (none) tcl[143]: Tcl created pool of 2916352 bytes
Mar 18 09:02:54 (none) EvtSwitcher[110]: Tmk client 5 (pid=143) has attached
Mar 18 09:02:54 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 5 (pid=143), thread 143 has activated MFS
Mar 18 09:02:54 (none) EvtSwitcher[110]: Tmk client 6 (pid=139) has attached
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) is service Mcp
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) has asked for 42 events
Mar 18 09:02:54 (none) EvtSwitcher[110]: First CLIENT_STARTUP event, current 1, total 1
Mar 18 09:02:54 (none) EvtSwitcher[110]: Service Mcp has finished startup
Mar 18 09:02:54 (none) EvtSwitcher[110]: LaunchPhasedService
Mar 18 09:02:54 (none) EvtService[110]: Launching /tvbin/tcphonehome phase 20
Mar 18 09:02:54 (none) MCPserver[145]: MCP startup complete
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) has asked for 19 events
Mar 18 09:02:59 (none) EvtSwitcher[110]: Tmk client 7 (pid=148) has attached
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) is service Phone Home
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148), thread 148 has activated MFS
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 19 events
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 42 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 30 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 69 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 62 events
Mar 18 09:03:01 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 77 events
Mar 18 09:03:01 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 76 events
Mar 18 09:03:01 (none) tcphonehome[152]: TCphonehome active
Mar 18 09:03:02 (none) tcphonehome[156]: cleaning /Setup
Mar 18 09:03:02 (none) tcphonehome[156]: getting setup
Mar 18 09:03:02 (none) tcphonehome[156]: got setup
Mar 18 09:03:02 (none) tcphonehome[156]: Fetching /SwSystemACTIVE/Name
Mar 18 09:03:02 (none) tcphonehome[156]: Last Call Status Was: 'Succeeded' (size 9)
Mar 18 09:03:02 (none) tcphonehome[156]: Last Call Status Will Be: Succeeded
Mar 18 09:03:02 (none) tcphonehome[156]: setCallStatus called with: Succeeded state 0
Mar 18 09:03:02 (none) tcphonehome[156]: Successfully got /Setup object
Mar 18 09:03:02 (none) tcphonehome[156]: Last call attempt at 1174188926
Mar 18 09:03:02 (none) tcphonehome[156]: status is 'Succeeded' == 'Succeeded'
Mar 18 09:03:02 (none) tcphonehome[156]: setting last success time to 1174188926
Mar 18 09:03:02 (none) tcphonehome[156]: setting call status to: Succeeded
Mar 18 09:03:02 (none) tcphonehome[156]: Successfully got ServiceInfo subobject (first successCount)
Mar 18 09:03:02 (none) tcphonehome[156]: setting call in progress to 0 (second successCount)
Mar 18 09:03:03 (none) tcphonehome[156]: End of transaction, err is 0 0x00000000
Mar 18 09:03:03 (none) tcphonehome[156]: Setting callActive to 0
Mar 18 09:03:03 (none) tcphonehome[156]: PhoneHome: statusValue = <0>, getREsult = <0>
Mar 18 09:03:03 (none) tcphonehome[156]: /State/Avalanche LastStatus was <0> and getResult was <0>, changing to unknown
Mar 18 09:03:03 (none) tcphonehome[156]: finished cleaning /Setup
Mar 18 09:03:03 (none) tcphonehome[156]: Running command: /sbin/ifconfig eth0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: eth0 Link encap:Ethernet HWaddr 00:0B:AD:4A:22:E1
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: inet addr:192.168.2.100 Bcast:192.168.2.255 Mask:255.255.255.0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: RX packets:0 errors:0 dropped:0 overruns:0 frame:0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 coll:0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: Interrupt:29
Mar 18 09:03:03 (none) tcphonehome[156]: Command output:
Mar 18 09:03:03 (none) tcphonehome[156]: Command finished status: 0, errno = 2
Mar 18 09:03:03 (none) tcphonehome[156]: USING ethernet. true
Mar 18 09:03:03 (none) tcphonehome[156]: Timesync is within the past 5 days... all is well
Mar 18 09:03:03 (none) tcphonehome[156]: getting LastSuccessfulCall
Mar 18 09:03:04 (none) tcphonehome[156]: ReschedCall: reason = 6, secSinceLast = 19657, numFails = 0
Mar 18 09:03:04 (none) tcphonehome[156]: Last call was 19657 secs. ago
Mar 18 09:03:04 (none) tcphonehome[156]: setting next attempt at 1174275424 (Mon Mar 19 03:37:04 2007 )
Mar 18 09:03:04 (none) MCPaction[156]: PhoneAction 2 got RegisterComplete. Setting ticcount to 66840
Mar 18 09:03:04 (none) MCPaction[156]: Register Complete: Next call should happen at Mon Mar 19 03:37:04 2007
Mar 18 09:03:04 (none) tcphonehome[156]: called getTZ
Mar 18 09:03:04 (none) EvtSwitcher[110]: First CLIENT_STARTUP event, current 1, total 2
Mar 18 09:03:04 (none) MCPaction[156]: WatchdogAction register complete
Mar 18 09:03:04 (none) EvtSwitcher[110]: Another CLIENT_STARTUP event, current 2, total 2
Mar 18 09:03:04 (none) EvtSwitcher[110]: Service Phone Home has finished startup
Mar 18 09:03:04 (none) EvtService[110]: Launching /tvbin/dbgc-mcp phase 40
Mar 18 09:03:05 (none) EvtSwitcher[110]: Tmk client 8 (pid=175) has attached
Mar 18 06:41:11 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:41:11 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:43:41 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:43:41 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:44:56 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:44:56 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Jan 1 00:00:23 (none) kernel: Loaded 20 symbols from 8 modules.
Jan 1 00:00:23 (none) kernel: Old MediaSwitch address map
Jan 1 00:00:23 (none) kernel: Memory configured for 16MB
Jan 1 00:00:23 (none) kernel: CPU clock speed: 54MHz
Jan 1 00:00:23 (none) kernel: ^MTeleworld Customer Device Starting Up!
Jan 1 00:00:23 (none) kernel: DMA Channel VidOut, next irq 50, idle irq 58, channel 5
Jan 1 00:00:23 (none) kernel: MS IMR 2020
Jan 1 00:00:23 (none) kernel: DMA Channel VidIn, next irq 55, idle irq 63, channel 1
Jan 1 00:00:23 (none) kernel: MS IMR 2121
Jan 1 00:00:23 (none) kernel: DMA Channel Events, next irq 52, idle irq 60, channel 4
Jan 1 00:00:23 (none) kernel: MS IMR 2929
Jan 1 00:00:23 (none) kernel: DMA Channel OSD, next irq 48, idle irq 56, channel 7
Jan 1 00:00:23 (none) kernel: MS IMR a9a9
Jan 1 00:00:23 (none) kernel: DMA Channel AudOut, next irq 49, idle irq 57, channel 6
Jan 1 00:00:23 (none) kernel: MS IMR e9e9
Jan 1 00:00:23 (none) kernel: DMA Channel AudIn, next irq 54, idle irq 62, channel 2
Jan 1 00:00:23 (none) kernel: MS IMR ebeb
Jan 1 00:00:23 (none) kernel: DMA Channel VBI, next irq 53, idle irq 61, channel 3
Jan 1 00:00:23 (none) kernel: MS IMR efef
Jan 1 00:00:23 (none) kernel: DMA Channel Ide, next irq 51, idle irq 59, channel 0
Jan 1 00:00:23 (none) kernel: MS IMR ffff
Jan 1 00:00:23 (none) kernel: Boot Parameters= root=/dev/hda4
Jan 1 00:00:23 (none) kernel: Initial MAC address=0:0:0:0:0:0
Jan 1 00:00:23 (none) kernel: Calibrating delay loop.. ok - 53.86 BogoMIPS
Jan 1 00:00:23 (none) kernel: Contiguous region of 1310720 bytes (1179648 for inputs, 131072 for outputs) at 0x801543f0 allocated.
Jan 1 00:00:23 (none) kernel: mem_init: start_mem = 0x80295000, end_mem = 0x81000000
Jan 1 00:00:23 (none) kernel: Memory: 13740k/16384k available (700k kernel code, 1944k data)
Jan 1 00:00:23 (none) kernel: Kernel virtual start=0x81000000, end=0x81d6b000
Jan 1 00:00:23 (none) kernel: Swansea University Computer Society NET3.038 for Linux 2.1
Jan 1 00:00:23 (none) kernel: NET3: Unix domain sockets 0.15 for Linux NET3.038.
Jan 1 00:00:23 (none) kernel: Swansea University Computer Society TCP/IP for NET3.037
Jan 1 00:00:23 (none) kernel: IP Protocols: IGMP, ICMP, UDP, TCP
Jan 1 00:00:23 (none) kernel: Linux version 2.1.24-TiVo-2.5 (build@buildmaster17) (gcc version 2.8.1) #2 Thu Feb 7 17:49:48 PST 2002
Jan 1 00:00:23 (none) kernel: POSIX conformance testing by UNIFIX
Jan 1 00:00:23 (none) kernel: Serial driver version 4.23 with no serial options enabled
Jan 1 00:00:23 (none) kernel: tty00 at 0xf4100000 (irq = 6) is a S403
Jan 1 00:00:23 (none) kernel: tty01 at 0xffc00000 (irq = 3) is a 16550A
Jan 1 00:00:23 (none) kernel: tty03 at 0xffb40000 (irq = 7) is a 16550A
Jan 1 00:00:23 (none) kernel: Ramdisk driver initialized : 16 ramdisks of 4096K size
Jan 1 00:00:23 (none) kernel: IDE: Setup IDE dma engine call back function completed
Jan 1 00:00:23 (none) kernel: set maxFrags and initialize fragp for h=0
Jan 1 00:00:23 (none) kernel: hda: WDC WD1200JB-00CRA1, TiVo-realtime enabled ATA DISK drive
Jan 1 00:00:23 (none) kernel: ide0 at 0xffd00400-0xffd00407,0xffd00438 on irq 32
Jan 1 00:00:23 (none) kernel: idinfo 82=346B 83=4B01 85=3469 86=0801 87=4003
Jan 1 00:00:23 (none) kernel: hda: WDC WD1200JB-00CRA1, 114473MB w/8192kB Cache, multiple 16, LBA, CHS=16383/16/63, DMA, SMART
Jan 1 00:00:23 (none) kernel: PPP: version 2.3.0 (demand dialling)
Jan 1 00:00:23 (none) kernel: TCP compression code copyright 1989 Regents of the University of California
Jan 1 00:00:23 (none) kernel: PPP line discipline registered.
Jan 1 00:00:23 (none) kernel: PPP Deflate Compression module registered
Jan 1 00:00:23 (none) kernel: Partition check:
Jan 1 00:00:23 (none) kernel: hda:Started kswapd v 1.12
Jan 1 00:00:23 (none) kernel: hda1 hda2 hda3 hda4 hda5 hda6 hda7 hda8 hda9 hda10 hda11 hda12 hda13 hda14
Jan 1 00:00:23 (none) kernel: VFS: Mounted root (ext2 filesystem) readonly.
Jan 1 00:00:23 (none) kernel: 16 pages of PROM memory freed
Jan 1 00:00:23 (none) kernel: 3 pages of unused kernel memory freed
Jan 1 00:00:23 (none) kernel: Init: Console opened as FD 0
Jan 1 00:00:23 (none) kernel: Starting rc.sysinit
Jan 1 00:00:23 (none) kernel: Releasing /initrd and clearing ramdisk, if they exist
Jan 1 00:00:23 (none) kernel: warning: can't open /var/mtab: No such file or directory
Jan 1 00:00:23 (none) kernel: umount: /initrd: not mounted
Jan 1 00:00:23 (none) kernel: Activating swap partitions
Jan 1 00:00:23 (none) kernel: Adding Swap: 130044k swap-space (priority -1)
Jan 1 00:00:23 (none) kernel: Loading i2c driver
Jan 1 00:00:23 (none) kernel: Box setup for PAL mode
Jan 1 00:00:23 (none) kernel: Loading FPGA driver
Jan 1 00:00:23 (none) kernel: fpga driver configured in PAL mode.
Jan 1 00:00:23 (none) kernel: fpga module running SAA7118 video encoder
Jan 1 00:00:23 (none) kernel: Msp: MajorRevCode Version 0x4
Jan 1 00:00:23 (none) kernel: Msp: ROM Version 0xa25
Jan 1 00:00:23 (none) kernel: Msp: ProductCode = 0xa
Jan 1 00:00:23 (none) kernel: Msp: MSP3410
Jan 1 00:00:23 (none) kernel: MSP34X0D
Jan 1 00:00:23 (none) kernel: memstart = 0x80155000 : size = 0x120000
Jan 1 00:00:23 (none) kernel: Standin 0: addr 0x80159000, len 0x11c000
Jan 1 00:00:23 (none) kernel: Checking for Kickstart panic signal
Jan 1 00:00:23 (none) kernel: No panic situation detected
Jan 1 00:00:23 (none) kernel: Cleanup /dev/hda9 pass 1
Jan 1 00:00:23 (none) kernel: ext2fs_check_if_mount: No such file or directory while determining whether /dev/hda9 is mounted.^M
Jan 1 00:00:23 (none) kernel: /dev/hda9 was not cleanly unmounted, check forced.
Jan 1 00:00:23 (none) kernel: Fix summary information? yes
Jan 1 00:00:23 (none) kernel:
Jan 1 00:00:23 (none) kernel: /dev/hda9: 1720/32768 files (6.6% non-contiguous), 111639/131072 blocks
Jan 1 00:00:23 (none) kernel: Cleanup /dev/hda9 pass 2
Jan 1 00:00:23 (none) kernel: ext2fs_check_if_mount: No such file or directory while determining whether /dev/hda9 is mounted.^M
Jan 1 00:00:23 (none) kernel: /dev/hda9: clean, 1720/32768 files, 111639/131072 blocks
Jan 1 00:00:23 (none) kernel: /dev/hda9 is clean after pass 2
Jan 1 00:00:23 (none) kernel: Mounting /var
Jan 1 00:00:23 (none) kernel: warning: can't open /var/mtab: No such file or directory
Jan 1 00:00:23 (none) kernel: /dev/hda9 on /var type ext2 (rw)
Jan 1 00:00:23 (none) kernel: Cleaning up /var/mtab...
Jan 1 00:00:23 (none) kernel: Using default TIVO_SVR_ADDR
Jan 1 00:00:23 (none) kernel: Mounting initial environment...
Jan 1 00:00:23 (none) kernel: Cleaning up temporary files in /var/tmp
Jan 1 00:00:23 (none) kernel: Cleaning up old slices and bundles in /var/packages...
Jan 1 00:00:23 (none) kernel: Checking space in /var
Jan 1 00:00:23 (none) kernel: /var partition is rather full...
Jan 1 00:00:23 (none) kernel: 103021^I/var
Jan 1 00:00:23 (none) kernel: Removing old logs...
Jan 1 00:00:23 (none) kernel: About to check tasks for tty to shutdown
Jan 1 00:00:23 (none) kernel: This one's (65) goin' down
Jan 1 00:00:23 (none) kernel: Attempting to fix modem using: /tvlib/modem/patches/P2109-V90/ram/expect_script
Jan 1 00:00:23 (none) kernel: spawn /tvbin/modempatch /tvlib/modem/patches/P2109-V90/ram/Patch9-2-RAM.s37 ^M
Jan 1 00:00:23 (none) kernel: ATZ^M^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: AT**^M^M
Jan 1 00:00:23 (none) kernel: Download initiated ..^M
Jan 1 00:00:23 (none) kernel: Getting ready to send files
Jan 1 00:00:23 (none) kernel: ..................................................^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: Sending of patch succeeded
Jan 1 00:00:23 (none) kernel: INTERACTIVE
Jan 1 00:00:23 (none) kernel: ^MAT!9F01?^M^M
Jan 1 00:00:23 (none) kernel: 9F01: 09 02 A9 6B 8D E7 9D E2 ^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: PATCH_VERIFIED
Jan 1 00:00:23 (none) kernel: FILEBASED
Jan 1 00:00:23 (none) kernel: EXITING WITH '0'
Jan 1 00:00:23 (none) kernel: About to check tasks for tty to shutdown
Jan 1 00:00:23 (none) kernel: This one's (71) goin' down
Jan 1 00:00:23 (none) kernel: Scanning for phase1 repair scripts
Jan 1 00:00:23 (none) kernel: Initializing TiVo extension...
Jan 1 00:00:23 (none) kernel: Set up environment vars for hardware configuration...
Jan 1 00:00:23 (none) kernel: Remote control is TIVO
Jan 1 00:00:23 (none) kernel: MFS partition on /dev/hda10
Jan 1 00:00:23 (none) kernel: Loading media drivers...
Jan 1 00:00:23 (none) kernel: Loading ideturbo ...
Jan 1 00:00:23 (none) kernel: TiVo disk statistics module loaded (byteswap enabled)
Jan 1 00:00:23 (none) kernel: Disk cautionlevel is 3
Jan 1 00:00:23 (none) kernel: hda: Generic ATA management
Jan 1 00:00:23 (none) kernel: Auto-detecting oslink module (asic 1).
Jan 1 00:00:23 (none) kernel: Multi-Verifier
Jan 1 00:00:23 (none) kernel: Not Installing oslink module.
Jan 1 00:00:23 (none) kernel: Loading fan ...
Jan 1 00:00:23 (none) kernel: Loading therm ...
Jan 1 00:00:23 (none) kernel: Loading pxmpegdecode ...
Jan 1 00:00:23 (none) kernel: We are using the Philips 7128 video encoder.
Jan 1 00:00:23 (none) kernel: pxmpegdecode configured in PAL mode.
Jan 1 00:00:23 (none) kernel: PX DECODE HYBRID: IBM CS22 initialization completed!
Jan 1 00:00:23 (none) kernel: MPEG: MPEG subsystem initialization completed
Jan 1 00:00:23 (none) kernel: MAJOR number = 78
Jan 1 00:00:23 (none) kernel: Loading scartmux ...
Jan 1 00:00:23 (none) kernel: Splash the screen...
Jan 1 00:00:23 (none) kernel: Writing 401560 bytes to OSD at address 0
Jan 1 00:00:23 (none) kernel: Update IR microcode using /sbin/irmicro.hex
Jan 1 00:00:23 (none) kernel: Atmel part found, 2kB of program memory, AT90S2313
Jan 1 00:00:23 (none) kernel: MicroVeriry: bad=0, GetFpaRegCnt=0, AndFpgaCnt=0, OrFpgaCnt=0
Jan 1 00:00:23 (none) kernel: Device already programmed with this microcode
Jan 1 00:00:23 (none) kernel: Starting update ...
Jan 1 00:00:23 (none) kernel: Look for debug board ...
Jan 1 00:00:23 (none) kernel: SIOCSIFHWADDR: Operation not supported by device
Jan 1 00:00:23 (none) kernel: IP struct was not filled in!
Jan 1 00:00:23 (none) kernel: sa is: 0x20000 0x0 0x7ffffce4 0x7ffffd00
Jan 1 00:00:23 (none) kernel: sa.sin_addr = 0x7ffffc48 a sockaddr is 16 bytes
Jan 1 00:00:23 (none) kernel: SIOCSIFADDR: Operation not supported by device
Jan 1 00:00:23 (none) kernel: eth0: unknown interface.
Jan 1 00:00:23 (none) kernel: Setting TCP keepalive parameters...
Jan 1 00:00:23 (none) kernel: Starting logging daemons...
Jan 1 00:00:23 (none) kernel: Check for PROM update ...
Jan 1 00:00:23 (none) kernel: Loading mixaud ...
Jan 1 00:00:23 (none) kernel: Updating system clock UID=0
Mar 18 09:02:45 (none) kernel: Time set to: Sun Mar 18 09:02:45 2007
Mar 18 09:02:45 (none) kernel: Have a nice day.
Mar 18 09:02:45 (none) kernel: Checking for additional disk...
Mar 18 09:02:45 (none) kernel: Starting EventSwitcher...
Mar 18 09:02:47 (none) kernel: Start fan control...
Mar 18 09:02:48 (none) kernel: Scanning for phase2 repair scripts
Mar 18 09:02:51 (none) kernel: Checking for database conversions...
Mar 18 09:02:51 (none) kernel: Checking new software installation
Mar 18 09:02:52 (none) kernel: SwSystem 2.5.5-01-1-023 is already active, nothing to do.
Mar 18 09:02:52 (none) kernel: Scanning for phase3 repair scripts
Mar 18 09:02:52 (none) kernel: Starting Services.
Mar 18 09:02:52 (none) kernel: Scanning for phase4 repair scripts
Mar 18 09:02:53 (none) kernel: rc.sysinit is complete
Mar 18 09:02:54 (none) kernel: MCP startup complete
Mar 18 09:02:59 (none) kernel: turbonet driver version 20050218
Mar 18 09:02:59 (none) kernel: turbonet: updated tlb entry 2 to 1M
Mar 18 09:02:59 (none) kernel: turbonet: using MAC address 00:0B:AD:4A:22:E1
Mar 18 09:02:59 (none) kernel: turbonet: driver installed
Mar 18 09:02:59 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:02:59 (none) kernel: IP struct was not filled in!
Mar 18 09:02:59 (none) kernel: sa is: 0x20000 0xc0a80264 0x7ffffcd4 0x7ffffcf0
Mar 18 09:02:59 (none) kernel: sa.sin_addr = 0x7ffffc38 a sockaddr is 16 bytes
Mar 18 09:03:01 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:03:01 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:03:02 (none) kernel: SIOCADDRT: Network is unreachable
Mar 18 09:03:02 (none) kernel: route.tivo forgot to specify route netmask.
Mar 18 09:03:56 (none) kernel: Microcode version 00034
Mar 18 04:14:28 (none) syslogd 1.3-3: restart.
Performed an emergency /var cleanup
Jan 1 00:00:23 (none) syslogd 1.3-3: restart.
Mar 18 09:02:49 (none) Stats: == System startup resource statistics ==
Mar 18 09:02:49 (none) Stats: ++ System build version numbers ++
Mar 18 09:02:49 (none) Stats: b-2-5-5 @74190 2002.02.07-1746 release-tivo1 COMPACT_LAYOUT STANDALONE DIRECTV PAL TCPHONEHOME_POST_PROCESSING
Mar 18 09:02:49 (none) Stats: 2002.02.07-1746 2.5.5-01-1
Mar 18 09:02:49 (none) Stats:
Mar 18 09:02:49 (none) Stats:
Mar 18 09:02:49 (none) Stats: ^MTiVoProm Monitor version 1.88mojo^M
Mar 18 09:02:49 (none) Stats: ^M
Mar 18 09:02:50 (none) Stats: IR version Microcode version 00034.
Mar 18 09:02:50 (none) Stats: System Serial Number: 0230000602328EC
Mar 18 09:02:50 (none) Stats: Linux version 2.1.24-TiVo-2.5 (build@buildmaster17) (gcc version 2.8.1) #2 Thu Feb 7 17:49:48 PST 2002
Mar 18 09:02:50 (none) Stats: ++ Memory usage ++
Mar 18 09:02:50 (none) Stats: total: used: free: shared: buffers: cached:
Mar 18 09:02:50 (none) Stats: Mem: 14147584 12238848 1908736 3792896 5451776 4173824
Mar 18 09:02:50 (none) Stats: Swap: 133165056 0 133165056
Mar 18 09:02:50 (none) Stats: MemTotal: 13816 kB
Mar 18 09:02:50 (none) Stats: MemFree: 1864 kB
Mar 18 09:02:50 (none) Stats: MemShared: 3704 kB
Mar 18 09:02:50 (none) Stats: Buffers: 5324 kB
Mar 18 09:02:50 (none) Stats: Cached: 4076 kB
Mar 18 09:02:50 (none) Stats: SwapTotal: 130044 kB
Mar 18 09:02:50 (none) Stats: SwapFree: 130044 kB
Mar 18 09:02:50 (none) Stats: ++ CPU info ++
Mar 18 09:02:50 (none) Stats: processor^I: 0
Mar 18 09:02:50 (none) Stats: cpu^I^I: IBM 403GCX
Mar 18 09:02:50 (none) Stats: clock^I^I: 54MHz
Mar 18 09:02:50 (none) Stats: revision^I: 20.1
Mar 18 09:02:50 (none) Stats: bogomips^I: 53.86
Mar 18 09:02:50 (none) Stats: machine^I^I: Teleworld Customer Device
Mar 18 09:02:50 (none) Stats: ++ Module info ++
Mar 18 09:02:50 (none) Stats: mixaud 5872 0 (unused)
Mar 18 09:02:50 (none) Stats: scartmux 2628 0 (unused)
Mar 18 09:02:50 (none) Stats: pxmpegdecode 65260 0 [scartmux]
Mar 18 09:02:50 (none) Stats: therm 1164 0 (unused)
Mar 18 09:02:50 (none) Stats: fan 976 0
Mar 18 09:02:50 (none) Stats: ideturbo 4920 1
Mar 18 09:02:50 (none) Stats: fpga7114 146156 0 [scartmux]
Mar 18 09:02:50 (none) Stats: i2c 10480 0 [scartmux pxmpegdecode therm fpga7114]
Mar 18 09:02:50 (none) Stats: ++ Device info ++
Mar 18 09:02:50 (none) Stats: Character devices:
Mar 18 09:02:50 (none) Stats: 1 mem
Mar 18 09:02:50 (none) Stats: 2 pty
Mar 18 09:02:50 (none) Stats: 3 ttyp
Mar 18 09:02:50 (none) Stats: 4 ttyp
Mar 18 09:02:50 (none) Stats: 5 cua
Mar 18 09:02:50 (none) Stats: 78 mpegdecoder
Mar 18 09:02:50 (none) Stats: 90 fpga
Mar 18 09:02:50 (none) Stats: 91 scartmux
Mar 18 09:02:50 (none) Stats: 97 fan
Mar 18 09:02:50 (none) Stats: 99 i2c
Mar 18 09:02:50 (none) Stats: 127 mixaud
Mar 18 09:02:50 (none) Stats:
Mar 18 09:02:50 (none) Stats: Block devices:
Mar 18 09:02:50 (none) Stats: 1 ramdisk
Mar 18 09:02:50 (none) Stats: 3 ide0
Mar 18 09:02:50 (none) Stats: ++ Network device info ++
Mar 18 09:02:51 (none) Stats: Inter-| Receive | Transmit
Mar 18 09:02:51 (none) Stats: face |packets errs drop fifo frame|packets errs drop fifo colls carrier
Mar 18 09:02:51 (none) Stats: lo: 0 0 0 0 0 0 0 0 0 0 0
Mar 18 09:03:02 (none) init: Changing to level 3
cheers
Mar 18 06:33:15 (none) last message repeated 552 times
Mar 18 06:34:16 (none) last message repeated 552 times
Mar 18 06:34:19 (none) last message repeated 34 times
Mar 18 06:34:19 (none) TmkTransform::Trace[201]: ClipCache 0x30015124 Secondary Reset
Mar 18 06:34:19 (none) ClipCache[197]: 0x30015124 in Entry(), thread 197
Mar 18 06:34:19 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:36:14 (none) last message repeated 29 times
Mar 18 06:38:10 (none) last message repeated 119 times
Mar 18 06:38:42 (none) last message repeated 288 times
Mar 18 06:39:04 (none) TmkTransform::Trace[201]: ClipCache 0x30012fbc Secondary Reset
Mar 18 06:39:04 (none) ClipCache[198]: 0x30012fbc in Entry(), thread 198
Mar 18 06:39:59 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:40:30 (none) last message repeated 278 times
Mar 18 06:41:31 (none) last message repeated 554 times
Mar 18 06:41:52 (none) last message repeated 193 times
Mar 18 06:42:09 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:42:09 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:42:09 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:42:09 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:42:09 (none) Recorder[202]: Allocated stream Id 1748846, size 208896
Mar 18 06:42:09 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:42:09 (none) Recorder[202]: Adding check schedule task
Mar 18 06:42:17 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:42:17 (none) MultiMpegCache[202]: Delete stream 1748781(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:42:17 (none) Recorder[202]: Free live cache chunk: 1748781
Mar 18 06:42:27 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:42:58 (none) last message repeated 279 times
Mar 18 06:43:18 (none) last message repeated 189 times
Mar 18 06:43:18 (none) TmkTransform::Trace[201]: ClipCache 0x30010274 Secondary Reset
Mar 18 06:43:18 (none) ClipCache[197]: 0x30010274 in Entry(), thread 197
Mar 18 06:43:18 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:43:49 (none) last message repeated 273 times
Mar 18 06:44:21 (none) last message repeated 292 times
Mar 18 06:46:05 (none) mempool[196]: MyWorld block: 763kB/1052kB chunk: 144kB/244kB unused: 2345kB search: 1 (size=3731424)
Mar 18 06:46:05 (none) mempool[196]: Osd block: 104kB/118kB chunk: 969kB/1265kB unused: 1594kB search: 0 (size=3051488)
Mar 18 06:47:05 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:47:05 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:47:05 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:47:05 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:47:05 (none) Recorder[202]: Allocated stream Id 1748847, size 208896
Mar 18 06:47:05 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:47:05 (none) Recorder[202]: Adding check schedule task
Mar 18 06:47:13 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:47:13 (none) MultiMpegCache[202]: Delete stream 1748783(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:38:42 (none) last message repeated 288 times
Mar 18 06:39:04 (none) TmkTransform::Trace[201]: ClipCache 0x30012fbc Secondary Reset
Mar 18 06:39:04 (none) ClipCache[198]: 0x30012fbc in Entry(), thread 198
Mar 18 06:39:59 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:40:30 (none) last message repeated 278 times
Mar 18 06:41:31 (none) last message repeated 554 times
Mar 18 06:41:52 (none) last message repeated 193 times
Mar 18 06:42:09 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:42:09 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:42:09 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:42:09 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:42:09 (none) Recorder[202]: Allocated stream Id 1748846, size 208896
Mar 18 06:42:09 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:42:09 (none) Recorder[202]: Adding check schedule task
Mar 18 06:42:17 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:42:17 (none) MultiMpegCache[202]: Delete stream 1748781(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:42:17 (none) Recorder[202]: Free live cache chunk: 1748781
Mar 18 06:42:27 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:42:58 (none) last message repeated 279 times
Mar 18 06:43:18 (none) last message repeated 189 times
Mar 18 06:43:18 (none) TmkTransform::Trace[201]: ClipCache 0x30010274 Secondary Reset
Mar 18 06:43:18 (none) ClipCache[197]: 0x30010274 in Entry(), thread 197
Mar 18 06:43:18 (none) TmkMediaswitch::Trace[201]: Lost VBI lock
Mar 18 06:43:49 (none) last message repeated 273 times
Mar 18 06:44:21 (none) last message repeated 292 times
Mar 18 06:46:05 (none) mempool[196]: MyWorld block: 763kB/1052kB chunk: 144kB/244kB unused: 2345kB search: 1 (size=3731424)
Mar 18 06:46:05 (none) mempool[196]: Osd block: 104kB/118kB chunk: 969kB/1265kB unused: 1594kB search: 0 (size=3051488)
Mar 18 06:47:05 (none) Recorder[202]: AllocateFiles for live cache
Mar 18 06:47:05 (none) Recorder[202]: Live cache size 1671168
Mar 18 06:47:05 (none) Recorder[202]: User recording 104491392 free 365952
Mar 18 06:47:05 (none) Recorder[202]: TivoClip total 10000000 free 9712256
Mar 18 06:47:05 (none) Recorder[202]: Allocated stream Id 1748847, size 208896
Mar 18 06:47:05 (none) mediamgr[202]: AddLiveFile input#0
Mar 18 06:47:05 (none) Recorder[202]: Adding check schedule task
Mar 18 06:47:13 (none) mediamgr[202]: RemoveWrittenLiveFile input#0
Mar 18 06:47:13 (none) MultiMpegCache[202]: Delete stream 1748783(inx = 0, read = 7, write =7) totalCount 9
Mar 18 06:47:14 (none) Recorder[202]: Free live cache chunk: 1748783
Mar 18 09:02:45 (none) EvtSwitcher[110]: EventSwitcher is starting up
Mar 18 09:02:45 (none) MfsDaemon[111]: MFS Daemon started, id is 111
Mar 18 09:02:45 (none) MfsDaemon[111]: Initializing MFS
Mar 18 09:02:45 (none) MfsDaemon[111]: Found 1 analog, 0 digital tuners: 364544-byte heap
Mar 18 09:02:45 (none) NmCache[111]: Cache at 0x0x7fee0cc4, array of 100 entries at 0x0x7fee0d4c
Mar 18 09:02:47 (none) MfsDaemon[111]: The MfsDaemon is starting up
Mar 18 09:02:47 (none) MfsDaemon[111]: Initialization complete
Mar 18 09:02:47 (none) EvtSwitcher[110]: MFS Daemon started, id is 111
Mar 18 09:02:47 (none) EvtSwitcher[110]: Tmk client 1 (pid=109) has attached
Mar 18 09:02:47 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:47 (none) EvtSwitcher[110]: Group 109 was not an MFS group
Mar 18 09:02:47 (none) EvtSwitcher[110]: Client 1 (pid=109) being removed
Mar 18 09:02:47 (none) EvtSwitcher[110]: Tmk client 2 (pid=111) has attached
Mar 18 09:02:47 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:47 (none) EvtSwitcher[110]: Client 2 (pid=111), thread 111 has activated MFS
Mar 18 09:02:51 (none) DbUpgrader[136]: majorVersionNum:4
Mar 18 09:02:51 (none) DbUpgrader[136]: minorVersionNum:40
Mar 18 09:02:51 (none) DbUpgrader[136]: converted majorVersionNum:4
Mar 18 09:02:51 (none) DbUpgrader[136]: converted minorVersionNum:40
Mar 18 09:02:51 (none) DbUpgrader[136]: Existing version: 4.40
Mar 18 09:02:51 (none) DbUpgrader[136]: Database is already up to date.
Mar 18 09:02:51 (none) convert-db[136]: no pending ZapRequests
Mar 18 09:02:51 (none) convert-db[136]: High water mark:
Mar 18 09:02:51 (none) convert-db[136]: tmk 1348/ 0 bytes ( 19 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: directory 112/ 1536 bytes ( 2 blocks/ 2 chunks)
Mar 18 09:02:51 (none) convert-db[136]: database 268/ 512 bytes ( 6 blocks/ 1 chunks)
Mar 18 09:02:51 (none) convert-db[136]: executive 44/ 0 bytes ( 2 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: tmkevent 32/ 0 bytes ( 1 blocks/ 0 chunks)
Mar 18 09:02:51 (none) convert-db[136]: TOTAL 1748/ 2048 bytes ( 29 blocks/ 3 chunks)
Mar 18 09:02:51 (none) tcl[137]: Tcl created pool of 1458176 bytes
Mar 18 09:02:52 (none) EvtSwitcher[110]: Tmk client 3 (pid=137) has attached
Mar 18 09:02:52 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137), thread 137 has activated MFS
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137) says he has finished
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 3 (pid=137) being removed
Mar 18 09:02:52 (none) EvtSwitcher[110]: LaunchPhasedService
Mar 18 09:02:52 (none) EvtService[110]: Launching /tvbin/mcp phase 10
Mar 18 09:02:52 (none) EvtSwitcher[110]: Client 4 being removed
Mar 18 09:02:53 (none) tcl[143]: Tcl created pool of 2916352 bytes
Mar 18 09:02:54 (none) EvtSwitcher[110]: Tmk client 5 (pid=143) has attached
Mar 18 09:02:54 (none) EvtSwitcher[110]: Invalid service attempted to attach?
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 5 (pid=143), thread 143 has activated MFS
Mar 18 09:02:54 (none) EvtSwitcher[110]: Tmk client 6 (pid=139) has attached
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) is service Mcp
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) has asked for 42 events
Mar 18 09:02:54 (none) EvtSwitcher[110]: First CLIENT_STARTUP event, current 1, total 1
Mar 18 09:02:54 (none) EvtSwitcher[110]: Service Mcp has finished startup
Mar 18 09:02:54 (none) EvtSwitcher[110]: LaunchPhasedService
Mar 18 09:02:54 (none) EvtService[110]: Launching /tvbin/tcphonehome phase 20
Mar 18 09:02:54 (none) MCPserver[145]: MCP startup complete
Mar 18 09:02:54 (none) EvtSwitcher[110]: Client 6 (Mcp, pid=139) has asked for 19 events
Mar 18 09:02:59 (none) EvtSwitcher[110]: Tmk client 7 (pid=148) has attached
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) is service Phone Home
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148), thread 148 has activated MFS
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 19 events
Mar 18 09:02:59 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 42 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 30 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 69 events
Mar 18 09:03:00 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 62 events
Mar 18 09:03:01 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 77 events
Mar 18 09:03:01 (none) EvtSwitcher[110]: Client 7 (Phone Home, pid=148) has asked for 76 events
Mar 18 09:03:01 (none) tcphonehome[152]: TCphonehome active
Mar 18 09:03:02 (none) tcphonehome[156]: cleaning /Setup
Mar 18 09:03:02 (none) tcphonehome[156]: getting setup
Mar 18 09:03:02 (none) tcphonehome[156]: got setup
Mar 18 09:03:02 (none) tcphonehome[156]: Fetching /SwSystemACTIVE/Name
Mar 18 09:03:02 (none) tcphonehome[156]: Last Call Status Was: 'Succeeded' (size 9)
Mar 18 09:03:02 (none) tcphonehome[156]: Last Call Status Will Be: Succeeded
Mar 18 09:03:02 (none) tcphonehome[156]: setCallStatus called with: Succeeded state 0
Mar 18 09:03:02 (none) tcphonehome[156]: Successfully got /Setup object
Mar 18 09:03:02 (none) tcphonehome[156]: Last call attempt at 1174188926
Mar 18 09:03:02 (none) tcphonehome[156]: status is 'Succeeded' == 'Succeeded'
Mar 18 09:03:02 (none) tcphonehome[156]: setting last success time to 1174188926
Mar 18 09:03:02 (none) tcphonehome[156]: setting call status to: Succeeded
Mar 18 09:03:02 (none) tcphonehome[156]: Successfully got ServiceInfo subobject (first successCount)
Mar 18 09:03:02 (none) tcphonehome[156]: setting call in progress to 0 (second successCount)
Mar 18 09:03:03 (none) tcphonehome[156]: End of transaction, err is 0 0x00000000
Mar 18 09:03:03 (none) tcphonehome[156]: Setting callActive to 0
Mar 18 09:03:03 (none) tcphonehome[156]: PhoneHome: statusValue = <0>, getREsult = <0>
Mar 18 09:03:03 (none) tcphonehome[156]: /State/Avalanche LastStatus was <0> and getResult was <0>, changing to unknown
Mar 18 09:03:03 (none) tcphonehome[156]: finished cleaning /Setup
Mar 18 09:03:03 (none) tcphonehome[156]: Running command: /sbin/ifconfig eth0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: eth0 Link encap:Ethernet HWaddr 00:0B:AD:4A:22:E1
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: inet addr:192.168.2.100 Bcast:192.168.2.255 Mask:255.255.255.0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: RX packets:0 errors:0 dropped:0 overruns:0 frame:0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 coll:0
Mar 18 09:03:03 (none) tcphonehome[156]: Command output: Interrupt:29
Mar 18 09:03:03 (none) tcphonehome[156]: Command output:
Mar 18 09:03:03 (none) tcphonehome[156]: Command finished status: 0, errno = 2
Mar 18 09:03:03 (none) tcphonehome[156]: USING ethernet. true
Mar 18 09:03:03 (none) tcphonehome[156]: Timesync is within the past 5 days... all is well
Mar 18 09:03:03 (none) tcphonehome[156]: getting LastSuccessfulCall
Mar 18 09:03:04 (none) tcphonehome[156]: ReschedCall: reason = 6, secSinceLast = 19657, numFails = 0
Mar 18 09:03:04 (none) tcphonehome[156]: Last call was 19657 secs. ago
Mar 18 09:03:04 (none) tcphonehome[156]: setting next attempt at 1174275424 (Mon Mar 19 03:37:04 2007 )
Mar 18 09:03:04 (none) MCPaction[156]: PhoneAction 2 got RegisterComplete. Setting ticcount to 66840
Mar 18 09:03:04 (none) MCPaction[156]: Register Complete: Next call should happen at Mon Mar 19 03:37:04 2007
Mar 18 09:03:04 (none) tcphonehome[156]: called getTZ
Mar 18 09:03:04 (none) EvtSwitcher[110]: First CLIENT_STARTUP event, current 1, total 2
Mar 18 09:03:04 (none) MCPaction[156]: WatchdogAction register complete
Mar 18 09:03:04 (none) EvtSwitcher[110]: Another CLIENT_STARTUP event, current 2, total 2
Mar 18 09:03:04 (none) EvtSwitcher[110]: Service Phone Home has finished startup
Mar 18 09:03:04 (none) EvtService[110]: Launching /tvbin/dbgc-mcp phase 40
Mar 18 09:03:05 (none) EvtSwitcher[110]: Tmk client 8 (pid=175) has attached
Mar 18 06:41:11 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:41:11 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:42:26 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:43:41 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:43:41 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:44:56 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:44:56 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:46:11 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x8046d3e0)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x804f2620)
Mar 18 06:47:26 (none) kernel: tcp_keepalive: call keepopen(0x806b5760)
Jan 1 00:00:23 (none) kernel: Loaded 20 symbols from 8 modules.
Jan 1 00:00:23 (none) kernel: Old MediaSwitch address map
Jan 1 00:00:23 (none) kernel: Memory configured for 16MB
Jan 1 00:00:23 (none) kernel: CPU clock speed: 54MHz
Jan 1 00:00:23 (none) kernel: ^MTeleworld Customer Device Starting Up!
Jan 1 00:00:23 (none) kernel: DMA Channel VidOut, next irq 50, idle irq 58, channel 5
Jan 1 00:00:23 (none) kernel: MS IMR 2020
Jan 1 00:00:23 (none) kernel: DMA Channel VidIn, next irq 55, idle irq 63, channel 1
Jan 1 00:00:23 (none) kernel: MS IMR 2121
Jan 1 00:00:23 (none) kernel: DMA Channel Events, next irq 52, idle irq 60, channel 4
Jan 1 00:00:23 (none) kernel: MS IMR 2929
Jan 1 00:00:23 (none) kernel: DMA Channel OSD, next irq 48, idle irq 56, channel 7
Jan 1 00:00:23 (none) kernel: MS IMR a9a9
Jan 1 00:00:23 (none) kernel: DMA Channel AudOut, next irq 49, idle irq 57, channel 6
Jan 1 00:00:23 (none) kernel: MS IMR e9e9
Jan 1 00:00:23 (none) kernel: DMA Channel AudIn, next irq 54, idle irq 62, channel 2
Jan 1 00:00:23 (none) kernel: MS IMR ebeb
Jan 1 00:00:23 (none) kernel: DMA Channel VBI, next irq 53, idle irq 61, channel 3
Jan 1 00:00:23 (none) kernel: MS IMR efef
Jan 1 00:00:23 (none) kernel: DMA Channel Ide, next irq 51, idle irq 59, channel 0
Jan 1 00:00:23 (none) kernel: MS IMR ffff
Jan 1 00:00:23 (none) kernel: Boot Parameters= root=/dev/hda4
Jan 1 00:00:23 (none) kernel: Initial MAC address=0:0:0:0:0:0
Jan 1 00:00:23 (none) kernel: Calibrating delay loop.. ok - 53.86 BogoMIPS
Jan 1 00:00:23 (none) kernel: Contiguous region of 1310720 bytes (1179648 for inputs, 131072 for outputs) at 0x801543f0 allocated.
Jan 1 00:00:23 (none) kernel: mem_init: start_mem = 0x80295000, end_mem = 0x81000000
Jan 1 00:00:23 (none) kernel: Memory: 13740k/16384k available (700k kernel code, 1944k data)
Jan 1 00:00:23 (none) kernel: Kernel virtual start=0x81000000, end=0x81d6b000
Jan 1 00:00:23 (none) kernel: Swansea University Computer Society NET3.038 for Linux 2.1
Jan 1 00:00:23 (none) kernel: NET3: Unix domain sockets 0.15 for Linux NET3.038.
Jan 1 00:00:23 (none) kernel: Swansea University Computer Society TCP/IP for NET3.037
Jan 1 00:00:23 (none) kernel: IP Protocols: IGMP, ICMP, UDP, TCP
Jan 1 00:00:23 (none) kernel: Linux version 2.1.24-TiVo-2.5 (build@buildmaster17) (gcc version 2.8.1) #2 Thu Feb 7 17:49:48 PST 2002
Jan 1 00:00:23 (none) kernel: POSIX conformance testing by UNIFIX
Jan 1 00:00:23 (none) kernel: Serial driver version 4.23 with no serial options enabled
Jan 1 00:00:23 (none) kernel: tty00 at 0xf4100000 (irq = 6) is a S403
Jan 1 00:00:23 (none) kernel: tty01 at 0xffc00000 (irq = 3) is a 16550A
Jan 1 00:00:23 (none) kernel: tty03 at 0xffb40000 (irq = 7) is a 16550A
Jan 1 00:00:23 (none) kernel: Ramdisk driver initialized : 16 ramdisks of 4096K size
Jan 1 00:00:23 (none) kernel: IDE: Setup IDE dma engine call back function completed
Jan 1 00:00:23 (none) kernel: set maxFrags and initialize fragp for h=0
Jan 1 00:00:23 (none) kernel: hda: WDC WD1200JB-00CRA1, TiVo-realtime enabled ATA DISK drive
Jan 1 00:00:23 (none) kernel: ide0 at 0xffd00400-0xffd00407,0xffd00438 on irq 32
Jan 1 00:00:23 (none) kernel: idinfo 82=346B 83=4B01 85=3469 86=0801 87=4003
Jan 1 00:00:23 (none) kernel: hda: WDC WD1200JB-00CRA1, 114473MB w/8192kB Cache, multiple 16, LBA, CHS=16383/16/63, DMA, SMART
Jan 1 00:00:23 (none) kernel: PPP: version 2.3.0 (demand dialling)
Jan 1 00:00:23 (none) kernel: TCP compression code copyright 1989 Regents of the University of California
Jan 1 00:00:23 (none) kernel: PPP line discipline registered.
Jan 1 00:00:23 (none) kernel: PPP Deflate Compression module registered
Jan 1 00:00:23 (none) kernel: Partition check:
Jan 1 00:00:23 (none) kernel: hda:Started kswapd v 1.12
Jan 1 00:00:23 (none) kernel: hda1 hda2 hda3 hda4 hda5 hda6 hda7 hda8 hda9 hda10 hda11 hda12 hda13 hda14
Jan 1 00:00:23 (none) kernel: VFS: Mounted root (ext2 filesystem) readonly.
Jan 1 00:00:23 (none) kernel: 16 pages of PROM memory freed
Jan 1 00:00:23 (none) kernel: 3 pages of unused kernel memory freed
Jan 1 00:00:23 (none) kernel: Init: Console opened as FD 0
Jan 1 00:00:23 (none) kernel: Starting rc.sysinit
Jan 1 00:00:23 (none) kernel: Releasing /initrd and clearing ramdisk, if they exist
Jan 1 00:00:23 (none) kernel: warning: can't open /var/mtab: No such file or directory
Jan 1 00:00:23 (none) kernel: umount: /initrd: not mounted
Jan 1 00:00:23 (none) kernel: Activating swap partitions
Jan 1 00:00:23 (none) kernel: Adding Swap: 130044k swap-space (priority -1)
Jan 1 00:00:23 (none) kernel: Loading i2c driver
Jan 1 00:00:23 (none) kernel: Box setup for PAL mode
Jan 1 00:00:23 (none) kernel: Loading FPGA driver
Jan 1 00:00:23 (none) kernel: fpga driver configured in PAL mode.
Jan 1 00:00:23 (none) kernel: fpga module running SAA7118 video encoder
Jan 1 00:00:23 (none) kernel: Msp: MajorRevCode Version 0x4
Jan 1 00:00:23 (none) kernel: Msp: ROM Version 0xa25
Jan 1 00:00:23 (none) kernel: Msp: ProductCode = 0xa
Jan 1 00:00:23 (none) kernel: Msp: MSP3410
Jan 1 00:00:23 (none) kernel: MSP34X0D
Jan 1 00:00:23 (none) kernel: memstart = 0x80155000 : size = 0x120000
Jan 1 00:00:23 (none) kernel: Standin 0: addr 0x80159000, len 0x11c000
Jan 1 00:00:23 (none) kernel: Checking for Kickstart panic signal
Jan 1 00:00:23 (none) kernel: No panic situation detected
Jan 1 00:00:23 (none) kernel: Cleanup /dev/hda9 pass 1
Jan 1 00:00:23 (none) kernel: ext2fs_check_if_mount: No such file or directory while determining whether /dev/hda9 is mounted.^M
Jan 1 00:00:23 (none) kernel: /dev/hda9 was not cleanly unmounted, check forced.
Jan 1 00:00:23 (none) kernel: Fix summary information? yes
Jan 1 00:00:23 (none) kernel:
Jan 1 00:00:23 (none) kernel: /dev/hda9: 1720/32768 files (6.6% non-contiguous), 111639/131072 blocks
Jan 1 00:00:23 (none) kernel: Cleanup /dev/hda9 pass 2
Jan 1 00:00:23 (none) kernel: ext2fs_check_if_mount: No such file or directory while determining whether /dev/hda9 is mounted.^M
Jan 1 00:00:23 (none) kernel: /dev/hda9: clean, 1720/32768 files, 111639/131072 blocks
Jan 1 00:00:23 (none) kernel: /dev/hda9 is clean after pass 2
Jan 1 00:00:23 (none) kernel: Mounting /var
Jan 1 00:00:23 (none) kernel: warning: can't open /var/mtab: No such file or directory
Jan 1 00:00:23 (none) kernel: /dev/hda9 on /var type ext2 (rw)
Jan 1 00:00:23 (none) kernel: Cleaning up /var/mtab...
Jan 1 00:00:23 (none) kernel: Using default TIVO_SVR_ADDR
Jan 1 00:00:23 (none) kernel: Mounting initial environment...
Jan 1 00:00:23 (none) kernel: Cleaning up temporary files in /var/tmp
Jan 1 00:00:23 (none) kernel: Cleaning up old slices and bundles in /var/packages...
Jan 1 00:00:23 (none) kernel: Checking space in /var
Jan 1 00:00:23 (none) kernel: /var partition is rather full...
Jan 1 00:00:23 (none) kernel: 103021^I/var
Jan 1 00:00:23 (none) kernel: Removing old logs...
Jan 1 00:00:23 (none) kernel: About to check tasks for tty to shutdown
Jan 1 00:00:23 (none) kernel: This one's (65) goin' down
Jan 1 00:00:23 (none) kernel: Attempting to fix modem using: /tvlib/modem/patches/P2109-V90/ram/expect_script
Jan 1 00:00:23 (none) kernel: spawn /tvbin/modempatch /tvlib/modem/patches/P2109-V90/ram/Patch9-2-RAM.s37 ^M
Jan 1 00:00:23 (none) kernel: ATZ^M^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: AT**^M^M
Jan 1 00:00:23 (none) kernel: Download initiated ..^M
Jan 1 00:00:23 (none) kernel: Getting ready to send files
Jan 1 00:00:23 (none) kernel: ..................................................^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: Sending of patch succeeded
Jan 1 00:00:23 (none) kernel: INTERACTIVE
Jan 1 00:00:23 (none) kernel: ^MAT!9F01?^M^M
Jan 1 00:00:23 (none) kernel: 9F01: 09 02 A9 6B 8D E7 9D E2 ^M
Jan 1 00:00:23 (none) kernel: OK^M
Jan 1 00:00:23 (none) kernel: PATCH_VERIFIED
Jan 1 00:00:23 (none) kernel: FILEBASED
Jan 1 00:00:23 (none) kernel: EXITING WITH '0'
Jan 1 00:00:23 (none) kernel: About to check tasks for tty to shutdown
Jan 1 00:00:23 (none) kernel: This one's (71) goin' down
Jan 1 00:00:23 (none) kernel: Scanning for phase1 repair scripts
Jan 1 00:00:23 (none) kernel: Initializing TiVo extension...
Jan 1 00:00:23 (none) kernel: Set up environment vars for hardware configuration...
Jan 1 00:00:23 (none) kernel: Remote control is TIVO
Jan 1 00:00:23 (none) kernel: MFS partition on /dev/hda10
Jan 1 00:00:23 (none) kernel: Loading media drivers...
Jan 1 00:00:23 (none) kernel: Loading ideturbo ...
Jan 1 00:00:23 (none) kernel: TiVo disk statistics module loaded (byteswap enabled)
Jan 1 00:00:23 (none) kernel: Disk cautionlevel is 3
Jan 1 00:00:23 (none) kernel: hda: Generic ATA management
Jan 1 00:00:23 (none) kernel: Auto-detecting oslink module (asic 1).
Jan 1 00:00:23 (none) kernel: Multi-Verifier
Jan 1 00:00:23 (none) kernel: Not Installing oslink module.
Jan 1 00:00:23 (none) kernel: Loading fan ...
Jan 1 00:00:23 (none) kernel: Loading therm ...
Jan 1 00:00:23 (none) kernel: Loading pxmpegdecode ...
Jan 1 00:00:23 (none) kernel: We are using the Philips 7128 video encoder.
Jan 1 00:00:23 (none) kernel: pxmpegdecode configured in PAL mode.
Jan 1 00:00:23 (none) kernel: PX DECODE HYBRID: IBM CS22 initialization completed!
Jan 1 00:00:23 (none) kernel: MPEG: MPEG subsystem initialization completed
Jan 1 00:00:23 (none) kernel: MAJOR number = 78
Jan 1 00:00:23 (none) kernel: Loading scartmux ...
Jan 1 00:00:23 (none) kernel: Splash the screen...
Jan 1 00:00:23 (none) kernel: Writing 401560 bytes to OSD at address 0
Jan 1 00:00:23 (none) kernel: Update IR microcode using /sbin/irmicro.hex
Jan 1 00:00:23 (none) kernel: Atmel part found, 2kB of program memory, AT90S2313
Jan 1 00:00:23 (none) kernel: MicroVeriry: bad=0, GetFpaRegCnt=0, AndFpgaCnt=0, OrFpgaCnt=0
Jan 1 00:00:23 (none) kernel: Device already programmed with this microcode
Jan 1 00:00:23 (none) kernel: Starting update ...
Jan 1 00:00:23 (none) kernel: Look for debug board ...
Jan 1 00:00:23 (none) kernel: SIOCSIFHWADDR: Operation not supported by device
Jan 1 00:00:23 (none) kernel: IP struct was not filled in!
Jan 1 00:00:23 (none) kernel: sa is: 0x20000 0x0 0x7ffffce4 0x7ffffd00
Jan 1 00:00:23 (none) kernel: sa.sin_addr = 0x7ffffc48 a sockaddr is 16 bytes
Jan 1 00:00:23 (none) kernel: SIOCSIFADDR: Operation not supported by device
Jan 1 00:00:23 (none) kernel: eth0: unknown interface.
Jan 1 00:00:23 (none) kernel: Setting TCP keepalive parameters...
Jan 1 00:00:23 (none) kernel: Starting logging daemons...
Jan 1 00:00:23 (none) kernel: Check for PROM update ...
Jan 1 00:00:23 (none) kernel: Loading mixaud ...
Jan 1 00:00:23 (none) kernel: Updating system clock UID=0
Mar 18 09:02:45 (none) kernel: Time set to: Sun Mar 18 09:02:45 2007
Mar 18 09:02:45 (none) kernel: Have a nice day.
Mar 18 09:02:45 (none) kernel: Checking for additional disk...
Mar 18 09:02:45 (none) kernel: Starting EventSwitcher...
Mar 18 09:02:47 (none) kernel: Start fan control...
Mar 18 09:02:48 (none) kernel: Scanning for phase2 repair scripts
Mar 18 09:02:51 (none) kernel: Checking for database conversions...
Mar 18 09:02:51 (none) kernel: Checking new software installation
Mar 18 09:02:52 (none) kernel: SwSystem 2.5.5-01-1-023 is already active, nothing to do.
Mar 18 09:02:52 (none) kernel: Scanning for phase3 repair scripts
Mar 18 09:02:52 (none) kernel: Starting Services.
Mar 18 09:02:52 (none) kernel: Scanning for phase4 repair scripts
Mar 18 09:02:53 (none) kernel: rc.sysinit is complete
Mar 18 09:02:54 (none) kernel: MCP startup complete
Mar 18 09:02:59 (none) kernel: turbonet driver version 20050218
Mar 18 09:02:59 (none) kernel: turbonet: updated tlb entry 2 to 1M
Mar 18 09:02:59 (none) kernel: turbonet: using MAC address 00:0B:AD:4A:22:E1
Mar 18 09:02:59 (none) kernel: turbonet: driver installed
Mar 18 09:02:59 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:02:59 (none) kernel: IP struct was not filled in!
Mar 18 09:02:59 (none) kernel: sa is: 0x20000 0xc0a80264 0x7ffffcd4 0x7ffffcf0
Mar 18 09:02:59 (none) kernel: sa.sin_addr = 0x7ffffc38 a sockaddr is 16 bytes
Mar 18 09:03:01 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:03:01 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Mar 18 09:03:02 (none) kernel: SIOCADDRT: Network is unreachable
Mar 18 09:03:02 (none) kernel: route.tivo forgot to specify route netmask.
Mar 18 09:03:56 (none) kernel: Microcode version 00034
Mar 18 04:14:28 (none) syslogd 1.3-3: restart.
Performed an emergency /var cleanup
Jan 1 00:00:23 (none) syslogd 1.3-3: restart.
Mar 18 09:02:49 (none) Stats: == System startup resource statistics ==
Mar 18 09:02:49 (none) Stats: ++ System build version numbers ++
Mar 18 09:02:49 (none) Stats: b-2-5-5 @74190 2002.02.07-1746 release-tivo1 COMPACT_LAYOUT STANDALONE DIRECTV PAL TCPHONEHOME_POST_PROCESSING
Mar 18 09:02:49 (none) Stats: 2002.02.07-1746 2.5.5-01-1
Mar 18 09:02:49 (none) Stats:
Mar 18 09:02:49 (none) Stats:
Mar 18 09:02:49 (none) Stats: ^MTiVoProm Monitor version 1.88mojo^M
Mar 18 09:02:49 (none) Stats: ^M
Mar 18 09:02:50 (none) Stats: IR version Microcode version 00034.
Mar 18 09:02:50 (none) Stats: System Serial Number: 0230000602328EC
Mar 18 09:02:50 (none) Stats: Linux version 2.1.24-TiVo-2.5 (build@buildmaster17) (gcc version 2.8.1) #2 Thu Feb 7 17:49:48 PST 2002
Mar 18 09:02:50 (none) Stats: ++ Memory usage ++
Mar 18 09:02:50 (none) Stats: total: used: free: shared: buffers: cached:
Mar 18 09:02:50 (none) Stats: Mem: 14147584 12238848 1908736 3792896 5451776 4173824
Mar 18 09:02:50 (none) Stats: Swap: 133165056 0 133165056
Mar 18 09:02:50 (none) Stats: MemTotal: 13816 kB
Mar 18 09:02:50 (none) Stats: MemFree: 1864 kB
Mar 18 09:02:50 (none) Stats: MemShared: 3704 kB
Mar 18 09:02:50 (none) Stats: Buffers: 5324 kB
Mar 18 09:02:50 (none) Stats: Cached: 4076 kB
Mar 18 09:02:50 (none) Stats: SwapTotal: 130044 kB
Mar 18 09:02:50 (none) Stats: SwapFree: 130044 kB
Mar 18 09:02:50 (none) Stats: ++ CPU info ++
Mar 18 09:02:50 (none) Stats: processor^I: 0
Mar 18 09:02:50 (none) Stats: cpu^I^I: IBM 403GCX
Mar 18 09:02:50 (none) Stats: clock^I^I: 54MHz
Mar 18 09:02:50 (none) Stats: revision^I: 20.1
Mar 18 09:02:50 (none) Stats: bogomips^I: 53.86
Mar 18 09:02:50 (none) Stats: machine^I^I: Teleworld Customer Device
Mar 18 09:02:50 (none) Stats: ++ Module info ++
Mar 18 09:02:50 (none) Stats: mixaud 5872 0 (unused)
Mar 18 09:02:50 (none) Stats: scartmux 2628 0 (unused)
Mar 18 09:02:50 (none) Stats: pxmpegdecode 65260 0 [scartmux]
Mar 18 09:02:50 (none) Stats: therm 1164 0 (unused)
Mar 18 09:02:50 (none) Stats: fan 976 0
Mar 18 09:02:50 (none) Stats: ideturbo 4920 1
Mar 18 09:02:50 (none) Stats: fpga7114 146156 0 [scartmux]
Mar 18 09:02:50 (none) Stats: i2c 10480 0 [scartmux pxmpegdecode therm fpga7114]
Mar 18 09:02:50 (none) Stats: ++ Device info ++
Mar 18 09:02:50 (none) Stats: Character devices:
Mar 18 09:02:50 (none) Stats: 1 mem
Mar 18 09:02:50 (none) Stats: 2 pty
Mar 18 09:02:50 (none) Stats: 3 ttyp
Mar 18 09:02:50 (none) Stats: 4 ttyp
Mar 18 09:02:50 (none) Stats: 5 cua
Mar 18 09:02:50 (none) Stats: 78 mpegdecoder
Mar 18 09:02:50 (none) Stats: 90 fpga
Mar 18 09:02:50 (none) Stats: 91 scartmux
Mar 18 09:02:50 (none) Stats: 97 fan
Mar 18 09:02:50 (none) Stats: 99 i2c
Mar 18 09:02:50 (none) Stats: 127 mixaud
Mar 18 09:02:50 (none) Stats:
Mar 18 09:02:50 (none) Stats: Block devices:
Mar 18 09:02:50 (none) Stats: 1 ramdisk
Mar 18 09:02:50 (none) Stats: 3 ide0
Mar 18 09:02:50 (none) Stats: ++ Network device info ++
Mar 18 09:02:51 (none) Stats: Inter-| Receive | Transmit
Mar 18 09:02:51 (none) Stats: face |packets errs drop fifo frame|packets errs drop fifo colls carrier
Mar 18 09:02:51 (none) Stats: lo: 0 0 0 0 0 0 0 0 0 0 0
Mar 18 09:03:02 (none) init: Changing to level 3
cheers