PDA

View Full Version : Tivo keeps crashing, log files attached, can you tlel me why.


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

blindlemon
03-18-2007, 12:58 PM
Can't see anything obvious in these logs.

Interestingly enough, this started happening to one of my TiVos a few days ago (total lockup with a frozen picture onscreen) but at first there was nothing suspicious in the logs so I was a bit mystified.

However, it did it again on Friday and, after restarting it, there were errors in the log showing that the drive was failing. So I swapped the drive and all is now well :)

benallenuk
03-18-2007, 06:14 PM
yes, i have 1 reoccuring bad sector, so the kernal log says. I thought i might be able to live with it, but if its going to keep crashing then im going to have to get a new drive. Whats the best drives around at the moment. 120, or 200gb would be ok.

Cheers

blindlemon
03-19-2007, 03:32 AM
The best drives, IMHO, are the Samsung 250gb HA250JC media drives. These run at 5400rpm and are the quietest, coolest drives over 40gb I have ever handled.

However, they're now officially discontinued, so what stocks are left are rather expensive - bare drives can be had from www.ultratec.co.uk, or preconfigured ones from the usual source;)

Other than that, I'm sure Pete77 will argue the merits of the Samsung HD400LD which is their new 7200rpm 400gb drive and slightly cheaper for more capacity. Again, these are excellent drives for TiVo use, though not as quiet or cool as the HA250JC.

There's hardly any point going for less than 250gb these days, as drive prices have come down a lot recently.

Pete77
03-19-2007, 05:18 AM
However, they're now officially discontinued, so what stocks are left are rather expensive - bare drives can be had from www.ultratec.co.uk, or preconfigured ones from the usual source;)

I believe blindlemon knows a man at www.tivoheaven.co.uk who has some of these rather expensive 250Gb hard drives. They do also sell a much cheaper 250Gb Samsung drive than the HA250JC as well.

Other than that, I'm sure Pete77 will argue the merits of the Samsung HD400LD which is their new 7200rpm 400gb drive and slightly cheaper for more capacity. Again, these are excellent drives for TiVo use, though not as quiet or cool as the HA250JC.

There's hardly any point going for less than 250gb these days, as drive prices have come down a lot recently.

I have 2 of the Samsung HA250JC drives in my Tivo which cost me £78 each and which I fitted myself 21 months ago. However I would have a bit or problem having to pay a lot more than that 21 months later, given how much hard drive prices have fallen. But for some quality comes ahead of price, regardless of cost. ;)

The 400Gb Samsung HD400LD seems better value and comes with a 3 year warranty etc but then with a 400Gb drive you really need to also buy a Cachecard and 512MB of RAM to stop the Now Playing list slowing down as the hard drive fills up. Mind you to get the most out of your Tivo I would recommend a Cachecard and 512MB of RAM in any case. TivoHeaven now have some much better prices on purchasing the Cachecard from them and are as cheap as any supplier in the UK marketplace if you buy your Cachecard from them along with a pre-configured hard drive. :up:

blindlemon
03-19-2007, 05:28 AM
I have 2 of the Samsung HA250JC drives in my Tivo which cost me £78 each and which I fitted myself 21 months ago. However I would have a bit or problem having to pay a lot more than that 21 months later, given how much hard drive prices have fallen.Unfortunately, the cost of a bare HA250JC is more than that these days - prices on those have gone up rather than fallen :eek:

I remember the days when you could pick one up for a mere £75... :cool:

Pete77
03-19-2007, 05:41 AM
Unfortunately, the cost of a bare HA250JC is more than that these days - prices on those have gone up rather than fallen :eek:

I remember the days when you could pick one up for a mere £75... :cool:

Yes I know that's the case and I'm not accusing you of profiteering on these HA250JC drives.

However due to their high price that's why I would only recommend them to those who are particularly concerned about hard disk noise and having the very quietest possible Tivo.

blindlemon
03-19-2007, 06:20 AM
I think we are agreed on that :)

However, the original question was 'what are the best drives around at the moment' - and the answer to that has to be: 'the HA250JC' :up:

Pete77
03-19-2007, 06:46 AM
However, the original question was 'what are the best drives around at the moment' - and the answer to that has to be: 'the HA250JC' :up:

Still nothing better 21 months on.................................

Nice to know I still have 2 of the best hard drives out there. :up:

benallenuk
03-19-2007, 05:16 PM
Hi, i bought a SAMSUNG HD300LD for £59 from PC-World, just finished fitting it, upgrade was easy. Just for reference the failing drive was a Western Digital WD1200JB. The drive is 3 years old and been on 24/7,my tivo did suffered mutiple power cuts.

The new drive is very quiet and nice and cool.

Cheers

Pete77
03-19-2007, 06:02 PM
Hi, i bought a SAMSUNG HD300LD for £59 from PC-World, just finished fitting it, upgrade was easy. Just for reference the failing drive was a Western Digital WD1200JB. The drive is 3 years old and been on 24/7,my tivo did suffered mutiple power cuts.

The new drive is very quiet and nice and cool.

Good to hear that all went well Ben.

6022tivo
03-20-2007, 02:03 PM
It is a great achievement when you upgrade yourself and all works well. I remember when I first did mine, a 40g to a 120g. It was great to see it all up and running, nice and quite and cool. Well done.

I soon moved on to more adventurous upgrades, the most nervous one was soldering another 16 mb of surface mount memory to the main system board, scary stuff, then altering the prom to check/detect and use this memory knowing if enabled and not soldered correctly it would brick the system board for ever.... Again afterwards a great sense of achievement.

blindlemon
03-20-2007, 03:00 PM
Time to update your sig now then :)

benallenuk
03-20-2007, 03:51 PM
Done.