Tivo randomly locking up... Log analysis please?

[geordi]

Ok, I'm not really sure why my tivo is STILL asking my if I love it... But the latest is that it will go a day or so just fine, then at some point will just lock up completely, the only light on the front will be the 480i indicator, and ONLY a hard unplug-it-for-a-few-seconds will reset it.

I went digging in the massive logs, and here is what I found in the kernel log. My understanding of the code and reporting is fuzzy at best, but am I correct in guessing that there are major problems in communicating with the card? Could a power supply be causing all of these errors?

I need input from people smarter than me on this, thanks.


Apr 10 17:34:15 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:15 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:15 (none) last message repeated 2 times
Apr 10 17:34:16 (none) kernel: /tvbin/dssappAV: ### WARNING: CamPayloadTransfer: Read 0 payload byte(s), expected 43
Apr 10 17:34:16 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:16 (none) last message repeated 2 times
Apr 10 17:34:17 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: (timeout)
Apr 10 17:34:17 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:17 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:19 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:19 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:22 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:22 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:22 (none) last message repeated 2 times
Apr 10 17:34:23 (none) kernel: /tvbin/dssappAV: ### WARNING: CamPayloadTransfer: Read 0 payload byte(s), expected 43
Apr 10 17:34:23 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:23 (none) last message repeated 2 times
Apr 10 17:34:24 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: (timeout)
Apr 10 17:34:24 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:24 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:25 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:25 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:25 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:26 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:26 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:28 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:28 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:29 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: (timeout)
Apr 10 17:34:29 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:29 (none) last message repeated 2 times
Apr 10 17:34:31 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_FORCE_ATR failed: Bad address
Apr 10 17:34:31 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:34:32 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:32 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:35 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:35 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:35 (none) last message repeated 2 times
Apr 10 17:34:36 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: (timeout)
Apr 10 17:34:36 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:36 (none) last message repeated 2 times
Apr 10 17:34:38 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: (timeout)
Apr 10 17:34:38 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:38 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:39 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:39 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:40 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:40 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:40 (none) last message repeated 2 times
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Failed to read procedure byte: Bad address
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:50 (none) kernel: AUDIO/MESSAGE BUFFER OVERRUN!
Apr 10 17:34:50 (none) kernel:
Apr 10 17:34:50 (none) kernel: AUDIO/MESSAGE BUFFER OVERRUN!
Apr 10 17:34:50 (none) kernel:
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### ERROR: CwpPrimaryThread: Packet error on vchan 0 (overflow=1, bytesAvailable=4096)
Apr 10 17:34:50 (none) kernel: AUDIO/MESSAGE BUFFER OVERRUN!
Apr 10 17:34:50 (none) kernel:
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_DisplayMessage[0]: host failed to respond
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:50 (none) kernel: Hardware event buffer overflowed!
Apr 10 17:34:50 (none) kernel: /tvbin/dssappAV: ### WARNING: CaDisplayMessageResponse[0]: unexpected message tag (wanted: 8015C6C0, got: 8015C60A)
Apr 10 17:34:51 (none) kernel: /tvbin/dssappAV: ### WARNING: CamPayloadTransfer: Failed to read 23 payload byte(s): Bad address
Apr 10 17:34:51 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:51 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:51 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:51 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:34:54 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:54 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:54 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:34:54 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:54 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:34:55 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:34:55 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:34:55 (none) kernel: /tvbin/dssappAV: ### WARNING: LL_GetUartStatus: UART status is not clean: 02
Apr 10 17:35:02 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_FORCE_ATR failed: Bad address
Apr 10 17:35:02 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:35:02 (none) last message repeated 2 times
Apr 10 17:35:02 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:35:02 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:35:02 (none) last message repeated 3 times
Apr 10 17:35:07 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_FORCE_ATR failed: Bad address
Apr 10 17:35:07 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:35:44 (none) last message repeated 6 times
Apr 10 17:36:27 (none) last message repeated 9 times
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address
Apr 10 17:36:27 (none) last message repeated 13 times
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: CamIoSequence: Command write failed: Bad address
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_DisplayMessage[0]: host failed to respond
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_DisplayMessage[0]: cleared spurious response
Apr 10 17:36:27 (none) kernel: /tvbin/dssappAV: ### WARNING: VI_ResetCam BRCM_IOCTL_SCARD_GET_ATR failed: Bad address

[geordi]

Here is the rest of the log, from my reboot sequence:


Jan 2 00:00:19 (none) kernel: klogd 1.3-3, log source = /proc/kmsg started.
Jan 2 00:00:19 (none) kernel: CPU revision is: 00005430
Jan 2 00:00:19 (none) kernel: FPU revision is: 00005410
Jan 2 00:00:19 (none) kernel: Primary instruction cache 32kb, linesize 32 bytes.
Jan 2 00:00:19 (none) kernel: Primary data cache 32kb, linesize 32 bytes.
Jan 2 00:00:19 (none) kernel: Linux version 2.4.20 (build@buildmaster50) (gcc version 3.3.4) #1 Tue Feb 14 20:55:02 PST 2006
Jan 2 00:00:19 (none) kernel: Determined physical RAM map:
Jan 2 00:00:19 (none) kernel: memory: 08000000 @ 00000000 (usable)
Jan 2 00:00:19 (none) kernel: On node 0 totalpages: 32768
Jan 2 00:00:19 (none) kernel: zone(0): 32768 pages.
Jan 2 00:00:19 (none) kernel: zone(1): 0 pages.
Jan 2 00:00:19 (none) kernel: zone(2): 0 pages.
Jan 2 00:00:19 (none) kernel: Kernel command line: root=/dev/hda7 brev=0x100A dsscon=true console=2,9600 upgradesoftware=false
Jan 2 00:00:19 (none) kernel: Monotonic time calibrated: 81.01 counts per usec
Jan 2 00:00:19 (none) kernel: Calibrating delay loop... 161.79 BogoMIPS
Jan 2 00:00:19 (none) kernel: Contiguous region 1: 12582912 bytes @ address 0x80585000
Jan 2 00:00:19 (none) kernel: Contiguous region 2: 2097152 bytes @ address 0x81185000
Jan 2 00:00:19 (none) kernel: Contiguous region 8: 23068672 bytes @ address 0x81385000
Jan 2 00:00:19 (none) kernel: Contiguous region of 37748736 bytes total reserved at 0x80585000.
Jan 2 00:00:19 (none) kernel: Memory: 91232k/131072k available (1224k kernel code, 39840k reserved, 68k data, 64k init, 0k highmem)
Jan 2 00:00:19 (none) kernel: Dentry cache hash table entries: 16384 (order: 5, 131072 bytes)
Jan 2 00:00:19 (none) kernel: Inode cache hash table entries: 8192 (order: 4, 65536 bytes)
Jan 2 00:00:19 (none) kernel: Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
Jan 2 00:00:19 (none) kernel: Buffer-cache hash table entries: 8192 (order: 3, 32768 bytes)
Jan 2 00:00:19 (none) kernel: Page-cache hash table entries: 32768 (order: 5, 131072 bytes)
Jan 2 00:00:19 (none) kernel: Checking for 'wait' instruction... unavailable.
Jan 2 00:00:19 (none) kernel: POSIX conformance testing by UNIFIX
Jan 2 00:00:19 (none) kernel: PCI: Probing PCI hardware
Jan 2 00:00:19 (none) kernel: ttyS00 at iomem 0xb4100100 (irq = 79) is a 16550A
Jan 2 00:00:19 (none) kernel: ttyS00 at port 0xbc010000 (irq = 133) is a unknown
Jan 2 00:00:19 (none) kernel: ttyS00 at iomem 0xb4100140 (irq = 81) is a 16550A
Jan 2 00:00:19 (none) kernel: ttyS00 at iomem 0xb4100120 (irq = 80) is a 16550A
Jan 2 00:00:19 (none) kernel: Linux NET4.0 for Linux 2.4
Jan 2 00:00:19 (none) kernel: Based upon Swansea University Computer Society NET3.039
Jan 2 00:00:19 (none) kernel: Initializing RT netlink socket
Jan 2 00:00:19 (none) kernel: Starting kswapd
Jan 2 00:00:19 (none) kernel: Serial driver version 5.05c (2001-07-08) with MANY_PORTS SHARE_IRQ SERIAL_PCI enabled
Jan 2 00:00:19 (none) kernel: ttyS00 at 0xb4100100 (irq = 79) is a 16550A
Jan 2 00:00:19 (none) kernel: ttyS01 at 0xbc010000 (irq = 133) is a unknown
Jan 2 00:00:19 (none) kernel: ttyS02 at 0xb4100140 (irq = 81) is a 16550A
Jan 2 00:00:19 (none) kernel: ttyS03 at 0xb4100120 (irq = 80) is a 16550A
Jan 2 00:00:19 (none) kernel: Uniform Multi-Platform E-IDE driver Revision: 6.31
Jan 2 00:00:19 (none) kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Jan 2 00:00:19 (none) kernel: hda: WDC WD2500BB-55GUC0, ATA DISK drive
Jan 2 00:00:19 (none) kernel: hdb: WDC WD3000JB-00KFA0, ATA DISK drive
Jan 2 00:00:19 (none) kernel: ide0 at 0x400-0x407,0x438 on irq 87
Jan 2 00:00:19 (none) kernel: hda: 488397168 sectors (250059 MB) w/2048KiB Cache, CHS=30401/255/63
Jan 2 00:00:19 (none) kernel: hdb: 586072368 sectors (300069 MB) w/8192KiB Cache, CHS=36481/255/63
Jan 2 00:00:19 (none) kernel: Partition check:
Jan 2 00:00:19 (none) kernel: hda: [mac] hda1 hda2 hda3 hda4 hda5 hda6 hda7 hda8 hda9 hda10 hda11 hda12 hda13 hda14
Jan 2 00:00:19 (none) kernel: hdb: [mac] hdb1 hdb2 hdb3 hdb4
Jan 2 00:00:19 (none) kernel: RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
Jan 2 00:00:19 (none) kernel: PPP generic driver version 2.4.2
Jan 2 00:00:19 (none) kernel: PPP Deflate Compression module registered
Jan 2 00:00:19 (none) kernel: Kernel Panic Logger registered
Jan 2 00:00:19 (none) kernel: NET4: Linux TCP/IP 1.0 for NET4.0
Jan 2 00:00:19 (none) kernel: IP Protocols: ICMP, UDP, TCP
Jan 2 00:00:19 (none) kernel: IP: routing cache hash table of 1024 buckets, 8Kbytes
Jan 2 00:00:19 (none) kernel: TCP: Hash tables configured (established 8192 bind 16384)
Jan 2 00:00:19 (none) kernel: ip_conntrack version 2.1 (1024 buckets, 8192 max) - 152 bytes per conntrack
Jan 2 00:00:19 (none) kernel: ip_tables: (C) 2000-2002 Netfilter core team
Jan 2 00:00:19 (none) kernel: NET4: Unix domain sockets 1.0/SMP for Linux NET4.0.
Jan 2 00:00:19 (none) kernel: VFS: Mounted root (ext2 filesystem) readonly.
Jan 2 00:00:19 (none) kernel: Freeing unused kernel memory: 64k freed
Jan 2 00:00:19 (none) kernel: Starting rc.sysinit
Jan 2 00:00:19 (none) kernel: Running boot Stage A_PreKickstart scripts
Jan 2 00:00:19 (none) kernel: Scanning for configuration files
Jan 2 00:00:19 (none) kernel: starting test.conf
Jan 2 00:00:19 (none) kernel: starting /init/001_bash.init
Jan 2 00:00:19 (none) kernel: starting /init/002_hdstart.init
Jan 2 00:00:19 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Jan 2 00:00:19 (none) kernel: resetting boot parameters; must reboot to take effect...
Jan 2 00:00:19 (none) kernel: root=/dev/hda7 brev=0x100A dsscon=true console=2,9600 upgradesoftware=false
Jan 2 00:00:19 (none) kernel:
Jan 2 00:00:19 (none) kernel: No device name given, assuming /dev/hda
Jan 2 00:00:19 (none) kernel: Updated boot page on /dev/hda
Jan 2 00:00:19 (none) kernel: No device name given, assuming /dev/hda
Jan 2 00:00:19 (none) kernel: root=/dev/hda7 brev=0x100A dsscon=true console=2,9600 upgradesoftware=false
Jan 2 00:00:19 (none) kernel: starting /init/011_usb.init
Jan 2 00:00:19 (none) kernel: making root filesystem writable; type 'rootread' to make read-only...
Jan 2 00:00:19 (none) kernel:
Jan 2 00:00:19 (none) kernel: executing mount -o remount,rw /
Jan 2 00:00:19 (none) kernel:
Jan 2 00:00:19 (none) kernel: done!
Jan 2 00:00:19 (none) kernel: making root filesystem read-only; type 'rootwrite' to make writable...
Jan 2 00:00:19 (none) kernel:
Jan 2 00:00:19 (none) kernel: executing mount -o remount,ro /
Jan 2 00:00:19 (none) kernel:
Jan 2 00:00:19 (none) kernel: done!
Jan 2 00:00:19 (none) kernel: Loading tivoconfig.o
Jan 2 00:00:19 (none) kernel: Invoking startup scripts for:
Jan 2 00:00:19 (none) kernel: platform 'phoenix'
Jan 2 00:00:19 (none) kernel: implementation 'Series2'
Jan 2 00:00:19 (none) kernel: implementer 'TiVo'
Jan 2 00:00:19 (none) kernel: Releasing /initrd and clearing ramdisk, if they exist
Jan 2 00:00:19 (none) kernel: umount: /initrd: not mounted
Jan 2 00:00:19 (none) kernel: Activating swap partitions
Jan 2 00:00:19 (none) kernel: Adding Swap: 130044k swap-space (priority -1)
Jan 2 00:00:19 (none) kernel: Loading core system drivers
Jan 2 00:00:19 (none) kernel: Loading fpga.o
Jan 2 00:00:19 (none) kernel: Parallel modem driver loaded, 1 ports starting at /dev/ttyS1
Jan 2 00:00:19 (none) kernel: Loading i2c_Series2.o
Jan 2 00:00:19 (none) kernel: Loading ircatch.o
Jan 2 00:00:19 (none) kernel: Found rev 'F' Si2433 modem on /dev/cua1
Jan 2 00:00:19 (none) kernel: Checking for Kickstart panic signal
Jan 2 00:00:19 (none) kernel: Running boot Stage B_PostKickstart scripts
Jan 2 00:00:19 (none) kernel: Cleanup /dev/hda9 pass 1
Jan 2 00:00:19 (none) kernel: /dev/hda9 was not cleanly unmounted, check forced.
Jan 2 00:00:19 (none) kernel: /dev/hda9: 712/32768 files (14.5% non-contiguous), 32940/131072 blocks
Jan 2 00:00:19 (none) kernel: Cleanup /dev/hda9 pass 2
Jan 2 00:00:19 (none) kernel: /dev/hda9: clean, 712/32768 files, 32940/131072 blocks
Jan 2 00:00:19 (none) kernel: /dev/hda9 is clean after pass 2
Jan 2 00:00:19 (none) kernel: Mounting /var
Jan 2 00:00:19 (none) kernel: /dev/hda9 on /var type ext2 (rw)
Jan 2 00:00:19 (none) kernel: Cleaning up files in /var
Jan 2 00:00:19 (none) kernel: Checking space in /var
Jan 2 00:00:19 (none) kernel: Mounting initial environment
Jan 2 00:00:19 (none) kernel: Starting logging daemons
Jan 2 00:00:19 (none) kernel: Found Silicon Labs "Si2433" modem, rev F, skipping modem patch
Jan 2 00:00:19 (none) kernel: Scanning for phase1 repair scripts
Jan 2 00:00:20 (none) kernel: Running boot Stage C_MediaInitialization scripts
Jan 2 00:00:20 (none) kernel: Loading input section drivers
Jan 2 00:00:20 (none) kernel: Loading oslink.o
Jan 2 00:00:20 (none) kernel: Loading cobra.o
Jan 2 00:00:22 (none) kernel: cobra module was successfully installed, LNBPA 0x10 LNBPB 0x12
Jan 2 00:00:22 (none) kernel: Loading output section drivers
Jan 2 00:00:22 (none) kernel: Loading brcmdrv-rb.o
Jan 2 00:00:24 (none) kernel: Linux BCM7020 STB interface: version 510082104
Jan 2 00:00:24 (none) kernel: ******** versionInfo->major is 2 ********
Jan 2 00:00:24 (none) kernel: Using dual-tuner HD client priorities
Jan 2 00:00:24 (none) kernel: Changed Broadcom output format to 4801
Jan 2 00:00:24 (none) kernel: Broadcom DVI colorspace set to RGB
Jan 2 00:00:24 (none) kernel: Loading si9190.o
Jan 2 00:00:25 (none) kernel: Splash the screen
Jan 2 00:00:25 (none) kernel: Bound Hdmi Semaphore to name
Jan 2 00:00:25 (none) kernel: PHOENIX GETS MORE CONTIGMEM: height=1349
Jan 2 00:00:26 (none) kernel: Running boot Stage D_PreMfs scripts
Jan 2 00:00:26 (none) kernel: Remote control is TIVO
Jan 2 00:00:26 (none) kernel: MFS partition on /dev/hda10
Jan 2 00:00:28 (none) kernel: Loading Phoenix dssapp
Jan 2 00:00:28 (none) kernel: Look for debug board
Jan 2 00:00:28 (none) kernel: /tvbin/dssappAV: DSS Interface Version 1.24, compiled on Jun 15 2006
Jan 2 00:00:28 (none) kernel: Loading Phoenix ATSC tuner daemon (AtscTunerD) ...
Jan 2 00:00:29 (none) kernel: Loading Phoenix atscapp
Jan 2 00:00:30 (none) kernel: Loading irblast.o
Jan 2 00:00:30 (none) kernel: Loading ideturbo.o
Jan 2 00:00:30 (none) kernel: /tvbin/atscapp: ATSC (Hpk-Enabled OsServices-Enabled) Interface Version 0.7, compiled on Jun 15 2006
Jan 2 00:00:30 (none) kernel: TiVo disk statistics module loaded
Jan 2 00:00:30 (none) kernel: Disk cautionlevel is 3
Jan 2 00:00:30 (none) kernel: Loading fan.o
Jan 2 00:00:30 (none) kernel: hda: Generic ATA management
Jan 2 00:00:30 (none) kernel: <InitInputs> Found a total of 2 inputs.
Jan 2 00:00:30 (none) kernel: <InitInputs> got 2 ATSC inputs.
Jan 2 00:00:30 (none) kernel: 2004 probe
Jan 2 00:00:30 (none) kernel: Loading therm.o
Jan 2 00:00:31 (none) kernel: Loading TvBus router
Jan 2 00:00:31 (none) kernel: Updating system clock
Apr 11 00:53:45 (none) kernel: Time set to: Wed Apr 11 00:53:45 2007
Apr 11 00:53:45 (none) kernel: <InitInputVcxoDevice> VcxoDevice for input #1 already opened, fileId = 8
Apr 11 00:53:46 (none) kernel: <BroadcomAtscInputResetMuxedInputForDirectv> Set tuner mux pin state to DSS
Apr 11 00:53:47 (none) kernel: <BroadcomAtscInputResetMuxedInputForDirectv> Set record type to RecordDirecTV_ES
Apr 11 00:53:47 (none) kernel: Enabling local route
Apr 11 00:53:47 (none) kernel: Setting TCP keepalive parameters
Apr 11 00:53:47 (none) kernel: Checking for additional disk
Apr 11 00:53:49 (none) kernel: hdb: Generic ATA management
Apr 11 00:53:49 (none) kernel: Start fan control
Apr 11 00:53:49 (none) kernel: First temperature parameters set:
Apr 11 00:53:49 (none) kernel: Terminal temp: 71
Apr 11 00:53:49 (none) kernel: Critical temp: 62
Apr 11 00:53:49 (none) kernel: Logging temp: 60
Apr 11 00:53:49 (none) kernel: Target temp: 50
Apr 11 00:53:49 (none) kernel: Lowest fan speed: 7

[geordi]

JEEZ this character limiter is annoying!

Still more boot sequence:


Apr 11 00:53:49 (none) kernel: /tvbin/fancontrol is running in the background.
Apr 11 00:53:49 (none) kernel: Starting TvLauncher
Apr 11 00:53:56 (none) kernel: Waiting for launcher to start.
Apr 11 00:53:56 (none) kernel: Launcher is running.
Apr 11 00:53:58 (none) kernel: Scanning for phase2 repair scripts
Apr 11 00:53:58 (none) kernel: Running boot Stage E_PreApplication scripts
Apr 11 00:54:00 (none) kernel: Checking for database conversions
Apr 11 00:54:00 (none) kernel: No upgrade to load
Apr 11 00:54:00 (none) kernel: Not upgrading software
Apr 11 00:54:01 (none) kernel: Scanning for phase3 repair scripts
Apr 11 00:54:01 (none) kernel: Running boot Stage F_ApplicationLaunch scripts
Apr 11 00:54:01 (none) kernel: Starting Services.
Apr 11 00:54:07 (none) kernel: Microcode version is TiVo!
Apr 11 00:54:11 (none) kernel: usb.c: registered new driver usbdevfs
Apr 11 00:54:11 (none) kernel: usb.c: registered new driver hub
Apr 11 00:54:11 (none) kernel: usb.c: starting integrated EHCI driver
Apr 11 00:54:11 (none) kernel: ehci_hcd 00:0d.2: PCI device 1033:00e0
Apr 11 00:54:11 (none) kernel: ehci_hcd 00:0d.2: irq 6, pci mem b4002000
Apr 11 00:54:11 (none) kernel: usb.c: new USB bus registered, assigned bus number 1
Apr 11 00:54:11 (none) kernel: PCI: 00:0d.2 PCI cache line size set incorrectly (0 bytes) by BIOS/FW, correcting to 32
Apr 11 00:54:11 (none) kernel: ehci_hcd 00:0d.2: USB 2.0 enabled, EHCI 0.95, driver 2003-Dec-29/2.4
Apr 11 00:54:11 (none) kernel: hub.c: USB hub found
Apr 11 00:54:11 (none) kernel: hub.c: 5 ports detected
Apr 11 00:54:11 (none) kernel: usb.c: EHCI initialization succeeded
Apr 11 00:54:12 (none) kernel: hub.c: new USB device 00:0d.2-2, assigned address 2
Apr 11 00:54:12 (none) kernel: usb.c: USB device 2 (vend/prod 0x846/0x1040) is not claimed by any active driver.
Apr 11 00:54:12 (none) kernel: ehci-dummy: dummy EHCI module loaded
Apr 11 00:54:18 (none) kernel: usb-ohci.c: USB OHCI at membase 0xb4000000, IRQ 5
Apr 11 00:54:18 (none) kernel: usb-ohci.c: usb-00:0d.0, PCI device 1033:0035
Apr 11 00:54:19 (none) kernel: usb.c: new USB bus registered, assigned bus number 2
Apr 11 00:54:19 (none) kernel: hub.c: USB hub found
Apr 11 00:54:19 (none) kernel: hub.c: 3 ports detected
Apr 11 00:54:20 (none) kernel: usb-ohci.c: USB OHCI at membase 0xb4001000, IRQ 3
Apr 11 00:54:20 (none) kernel: usb-ohci.c: usb-00:0d.1, PCI device 1033:0035
Apr 11 00:54:20 (none) kernel: usb.c: new USB bus registered, assigned bus number 3
Apr 11 00:54:20 (none) kernel: hub.c: USB hub found
Apr 11 00:54:20 (none) kernel: hub.c: 2 ports detected
Apr 11 00:54:23 (none) kernel: usb.c: registered new driver usbnet
Apr 11 00:54:23 (none) kernel: eth0: register usbnet usb-00:0d.2-2, Netgear FA-120 USB Ethernet
Apr 11 00:54:24 (none) kernel: eth0: Medium status: 0x14
Apr 11 00:54:24 (none) kernel: eth0: open: enable queueing (rx 98, tx 98) mtu 1500 simple framing
Apr 11 00:54:25 (none) kernel: eth0: Medium status: 0x17
Apr 11 00:54:33 (none) kernel: ApgLogFrequency( 0 sec )
Apr 11 00:55:21 (none) kernel: Found binding for the hdmi semaphore
Apr 11 00:55:24 (none) kernel: ApgManager Transition from state INIT to BOOT
Apr 11 00:55:29 (none) kernel: <BroadcomAtscInputResetMuxedInputForDirectv> Set tuner mux pin state to DSS
Apr 11 00:55:29 (none) kernel: <BroadcomAtscInputResetMuxedInputForDirectv> Set record type to RecordDirecTV_ES
Apr 11 00:55:29 (none) kernel: PHOENIX GETS MORE CONTIGMEM: height=1349
Apr 11 00:55:33 (none) kernel: Logger not initialized! Logging to stdout:
Apr 11 00:55:33 (none) kernel: TmkLogger: <133>Apr 11 00:55:33 TmkServer[314]: Enabling port 5353, protocol udp
Apr 11 00:55:36 (none) kernel: Found binding for the hdmi semaphore
Apr 11 00:55:36 (none) kernel: PHOENIX GETS MORE CONTIGMEM: height=1349
Apr 11 00:55:42 (none) kernel: Scanning for phase4 repair scripts
Apr 11 00:55:42 (none) kernel: Running boot Stage G_PostApplication scripts
Apr 11 00:55:43 (none) kernel: making root filesystem writable; type 'rootread' to make read-only...
Apr 11 00:55:43 (none) kernel:
Apr 11 00:55:43 (none) kernel: executing mount -o remount,rw /
Apr 11 00:55:43 (none) kernel: EXT2-fs warning: mounting unchecked fs, running e2fsck is recommended
Apr 11 00:55:43 (none) kernel:
Apr 11 00:55:43 (none) kernel: done!
Apr 11 00:55:46 (none) kernel: cat: /var/log/*tivoweb.log: No such file or directory
Apr 11 00:55:46 (none) kernel: cat: /var/log/*tivoweb.log: No such file or directory
Apr 11 00:55:57 (none) kernel: ApgManager Transition from state BOOT to SECONDARY_BOOT
Apr 11 00:55:58 (none) kernel: ApgManager Transition from state SECONDARY_BOOT to FAST_LOAD
Apr 11 00:55:58 (none) kernel: "Call in" status updated.
Apr 11 00:55:59 (none) kernel: rc.sysinit.author has left the building
Apr 11 00:56:00 (none) kernel: rc.sysinit is complete
Apr 11 00:56:04 (none) kernel: *** No response from connection server ***
Apr 11 00:56:42 (none) kernel: ApgManager Transition from state FAST_LOAD to FAST_LOAD
Apr 11 00:57:17 (none) last message repeated 4 times
Apr 11 00:57:33 (none) kernel: ApgManager Transition from state FAST_LOAD to STEADY_STATE
Apr 11 00:58:06 (none) kernel: <BroadcomDirectvInputReinitialize> Set tuner mux pin state to DSS

[lgkahn]

logs are not helpfull you didnt show the crash.. just some errors and the restart

[jt1134]

Also if you tar-up the log and attach you won't have to post multiple LONG posts.

[geordi]

There was no crash message. the logs just stopped yesterday, until I rebooted it today when I noticed it was crashed.

[Narf54321]

Looking at your logs, I see you have two hard drives. Rather large ones at that, which tend to run hot.

I had one standalone unit which would lock up, the only one in the house with dual hard-drives. It was also behind a cabinet door. Normally it was fine, but using my Xbox (in the same cabinet) would generate a lot of heat and this particular Tivo would lock up, while the other Tivo and the Xbox continued to run fine.

I'd check for dust-bunnies/clogged vents/fan-failure as potential causes.

[geordi]

Oh, I know ALL about the heat issues. My Hd-Tivo (this unit) starts getting pissy if the temps start getting over 37*c... This unit hasn't had it's lid on since the day I bought it 14 months ago. Yes, it does have 2 drives, the B drive is the original 250gb disk, and the A drive is a 300gb.

Both disks are mounted in their own Thermaltake HDD cooler, the BIG 5.25" wide solid aluminum tray with dual full-length heatsinks and fans. The heatsink fans aren't plugged in, but I have 2 external fans blowing on the unit, one from either side. One is aimed at the mobo on the card side, the other is blowing across the power supply and the hard drives. Yea, they don't exactly fit in their normal position anymore, but they are insulated from the electrically-live heatsink on the power supply.

Yea, the big aluminum heatsink on the power supply has 120v running through it. I don't think that's a really great design "feature" but it's not anything that I ever created or modified, so it must be designed like that.

Anyway, most of the time the temps are right around 85* F or so. Last time it locked up before this logged one, was Sunday. The AC was off b/c it was so cold out, and I had windows open... The temp of the tivo's drives were BOTH in the 50's... So I don't think it crashed from being too hot! :lol: