[mythtv-users] OT : Help! -- BUG: soft lockup detected on CPU#0!
Paul Fine
pfine at comcast.net
Tue Oct 31 18:29:46 UTC 2006
Hopefully some of you more knowledgeable users (more knowledgeable than
me, which doesn't take much) can give me some debugging advice.
I have had my mythtv for several months running 0.19 on FC4. Earlier
this month I upgraded to 0.20 and FC5. Last week I bought a new drive
(250 GB SATA) and I installed the drive this weekend, although I never
even got as far as even putting a partition on the new disk.
Each time I boot the computer, it starts up fine. I start the
vncserver. Some time after, usually around 100 minutes later but the
last time it was 20 minutes after reboot, the vnc remote session becomes
unresponsive, and the direct display (my television through a FX5200
S-video connection) goes blank. The computer is then locked up. I
found the very scary "BUG: soft lockup detected on CPU#0!" message in
the /var/log/messages file when I rebooted.
This is what I am running.
**** ******
ASUS P4S800D-X Socket 478 SiS 655FX ATX Intel Motherboard
Intel Pentium 4 2.4A Prescott 2.4 GHz Processor
Hauppauge PVR-350
SeaSonic S12-380 ATX12V 380W Power Supply
FX5200 AGP video card
NetGear WLAN PCI card
2 IDE Hard Disks
Sony DVD Burner
SILVERSTONE SST-LC01 case
My first thought was that the SATA drive was the problem, so I
disconnected the drive, both the power and the data cables, but the
computer showed the exact same symptoms on the next reboot.
I checked to make sure all the fans were running and tried rebooting
with the case open.
Last night I ran the memtestx86 from the FC installation DVD. I ran it
over night. I stopped it this morning after 23 passes. It showed no
failure.
Can you give me some advice as to what to try next?
------
In my first attempt to post this, I included a too large portion of the
/var/log/messages file, and it got bounced from the mail list as greater
than 40 KB. So here is an excerpt of the /var/log/messages file with
what I think is the offending error.
<snip>
Oct 29 20:02:51 localhost xinetd[1904]: xinetd Version 2.3.13 started
with libwrap loadavg options compiled in.
Oct 29 20:02:51 localhost xinetd[1904]: Started working: 1 available
service
Oct 29 20:03:02 localhost ntpdate[1917]: step time server 192.36.143.234
offset -0.526829 sec
Oct 29 20:03:02 localhost ntpd[1921]: ntpd 4.2.0a at 1.1196-r Thu May 11
09:19:35 EDT 2006 (1)
Oct 29 20:03:02 localhost ntpd[1921]: precision = 1.000 usec
Oct 29 20:03:02 localhost ntpd[1921]: Listening on interface wildcard,
0.0.0.0#123
Oct 29 20:03:02 localhost ntpd[1921]: Listening on interface wildcard,
::#123
Oct 29 20:03:02 localhost ntpd[1921]: Listening on interface lo,
127.0.0.1#123
Oct 29 20:03:02 localhost ntpd[1921]: Listening on interface wlan0,
192.168.2.210#123
Oct 29 20:03:02 localhost ntpd[1921]: kernel time sync status 0040
Oct 29 20:03:02 localhost exportfs[1935]: /etc/exports [1]: No 'sync' or
'async' option specified for export "*:/var/video". Assuming default
behaviour ('sync'). NOTE: this default has changed from previous versions
Oct 29 20:03:02 localhost ntpd[1921]: frequency initialized -24.297 PPM
from /var/lib/ntp/drift
Oct 29 20:03:02 localhost kernel: Installing knfsd (copyright (C) 1996
okir at monad.swb.de).
Oct 29 20:03:02 localhost kernel: NFSD: Using /var/lib/nfs/v4recovery as
the NFSv4 state recovery directory
Oct 29 20:03:02 localhost kernel: NFSD: unable to find recovery
directory /var/lib/nfs/v4recovery
Oct 29 20:03:02 localhost kernel: NFSD: starting 90-second grace period
Oct 29 20:03:05 localhost dhcpd: Internet Systems Consortium DHCP Server
V3.0.3-RedHat
Oct 29 20:03:05 localhost dhcpd: Copyright 2004-2005 Internet Systems
Consortium.
Oct 29 20:03:05 localhost dhcpd: All rights reserved.
Oct 29 20:03:05 localhost dhcpd: For info, please visit
http://www.isc.org/sw/dhcp/
Oct 29 20:03:05 localhost dhcpd: Wrote 0 deleted host decls to leases file.
Oct 29 20:03:05 localhost dhcpd: Wrote 0 new dynamic host decls to
leases file.
Oct 29 20:03:05 localhost dhcpd: Wrote 0 leases to leases file.
Oct 29 20:03:05 localhost dhcpd: Listening on
LPF/wlan0/00:14:6c:2c:2f:23/192.168.2/24
Oct 29 20:03:05 localhost dhcpd: Sending on
LPF/wlan0/00:14:6c:2c:2f:23/192.168.2/24
Oct 29 20:03:05 localhost dhcpd: Sending on Socket/fallback/fallback-net
Oct 29 20:03:05 localhost dhcpd: dhcpd startup succeeded
Oct 29 20:03:05 localhost gpm[2136]: *** info [startup.c(95)]:
Oct 29 20:03:05 localhost gpm[2136]: Started gpm successfully. Entered
daemon mode.
Oct 29 20:03:11 localhost lircd-0.8.1-CVS[2184]: lircd(userspace) ready
Oct 29 20:03:13 localhost avahi-daemon[2310]: Found user 'avahi' (UID
70) and group 'avahi' (GID 70).
Oct 29 20:03:13 localhost avahi-daemon[2310]: Successfully dropped root
privileges.
Oct 29 20:03:13 localhost avahi-daemon[2310]: avahi-daemon 0.6.10
starting up.
Oct 29 20:03:13 localhost avahi-daemon[2310]: WARNING: No NSS support
for mDNS detected, consider installing nss-mdns!
Oct 29 20:03:13 localhost avahi-daemon[2310]: Successfully called chroot().
Oct 29 20:03:13 localhost avahi-daemon[2310]: Successfully dropped
remaining capabilities.
Oct 29 20:03:13 localhost avahi-daemon[2310]: No service found in
/etc/avahi/services.
Oct 29 20:03:13 localhost avahi-daemon[2310]: New relevant interface
wlan0.IPv4 for mDNS.
Oct 29 20:03:13 localhost avahi-daemon[2310]: Joining mDNS multicast
group on interface wlan0.IPv4 with address 192.168.2.210.
Oct 29 20:03:13 localhost avahi-daemon[2310]: Network interface
enumeration completed.
Oct 29 20:03:13 localhost avahi-daemon[2310]: Registering new address
record for 192.168.2.210 on wlan0.
Oct 29 20:03:13 localhost avahi-daemon[2310]: Registering HINFO record
with values 'I686'/'LINUX'.
Oct 29 20:03:14 localhost avahi-daemon[2310]: Server startup complete.
Host name is oscar.local. Local service cookie is 2272087666.
Oct 29 20:03:52 localhost lircd-0.8.1-CVS[2184]: accepted new client on
/dev/lircd
Oct 29 20:03:52 localhost lircd-0.8.1-CVS[2184]: could not get file
information for /dev/lirc
Oct 29 20:03:52 localhost lircd-0.8.1-CVS[2184]: default_init(): No such
file or directory
Oct 29 20:03:52 localhost lircd-0.8.1-CVS[2184]: caught signal
Oct 29 20:06:01 localhost kernel: lirc_dev: IR Remote Control driver
registered, at major 61
Oct 29 20:06:01 localhost kernel: bttv: driver version 0.9.16 loaded
Oct 29 20:06:01 localhost kernel: bttv: using 8 buffers with 2080k (520
pages) each for capture
Oct 29 20:06:01 localhost kernel: cx2388x v4l2 driver version 0.0.5 loaded
Oct 29 20:06:01 localhost kernel: lirc_i2c: chip found @ 0x18 (Hauppauge
IR)
Oct 29 20:06:01 localhost kernel: lirc_dev: lirc_register_plugin:
sample_rate: 10
Oct 29 20:06:10 localhost lircd-0.8.1-CVS[2861]: lircd(userspace) ready
Oct 29 20:06:19 localhost ntpd[1921]: synchronized to LOCAL(0), stratum 10
Oct 29 20:06:19 localhost ntpd[1921]: kernel time sync disabled 0041
Oct 29 20:07:24 localhost ntpd[1921]: synchronized to 192.36.143.234,
stratum 1
Oct 29 20:07:24 localhost ntpd[1921]: kernel time sync enabled 0001
Oct 29 20:26:51 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:26:51 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:26:51 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:26:51 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:26:51 localhost kernel: =======================
Oct 29 20:26:51 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c0405047> do_IRQ+0x75/0x80
Oct 29 20:26:51 localhost kernel: <c04036f2>
common_interrupt+0x1a/0x20 <c040e0d7> delay_pmtmr+0xb/0x13
Oct 29 20:26:51 localhost kernel: <c04d8221> __delay+0x9/0xa
<f9d91199> KeStallExecutionProcessor+0x9/0xc [ndiswrapper]
Oct 29 20:26:51 localhost kernel: <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper] <c04206ba>
tasklet_action+0x37/0x57
Oct 29 20:26:51 localhost kernel: <c042070f> __do_softirq+0x35/0x7f
<c040508a> do_softirq+0x38/0x42
Oct 29 20:26:51 localhost kernel: =======================
Oct 29 20:26:51 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:26:51 localhost kernel: <c0401f7c> mwait_idle+0x1a/0x2e
<c0401f4d> cpu_idle+0x3a/0x4f
Oct 29 20:26:51 localhost kernel: <c0720724> start_kernel+0x2d7/0x2db
<c0720249> unknown_bootoption+0x0/0x204
Oct 29 20:27:02 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:27:02 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:27:02 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:27:02 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:27:02 localhost kernel: =======================
Oct 29 20:27:02 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:27:02 localhost kernel: <c040e0d5> delay_pmtmr+0x9/0x13
<c04d8221> __delay+0x9/0xa
Oct 29 20:27:02 localhost kernel: <f9d91199>
KeStallExecutionProcessor+0x9/0xc [ndiswrapper] <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper]
Oct 29 20:27:02 localhost kernel: <c04206ba> tasklet_action+0x37/0x57
<c042070f> __do_softirq+0x35/0x7f
Oct 29 20:27:02 localhost kernel: <c040508a> do_softirq+0x38/0x42
Oct 29 20:27:02 localhost kernel: =======================
Oct 29 20:27:02 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:27:02 localhost kernel: <c0401f7c> mwait_idle+0x1a/0x2e
<c0401f4d> cpu_idle+0x3a/0x4f
Oct 29 20:27:02 localhost kernel: <c0720724> start_kernel+0x2d7/0x2db
<c0720249> unknown_bootoption+0x0/0x204
Oct 29 20:27:43 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:27:43 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:27:43 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:27:43 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:27:43 localhost kernel: =======================
Oct 29 20:27:43 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c0405047> do_IRQ+0x75/0x80
Oct 29 20:27:43 localhost kernel: <c04036f2>
common_interrupt+0x1a/0x20 <c040e0d7> delay_pmtmr+0xb/0x13
Oct 29 20:27:43 localhost kernel: <c04d8221> __delay+0x9/0xa
<f9d91199> KeStallExecutionProcessor+0x9/0xc [ndiswrapper]
Oct 29 20:27:43 localhost kernel: <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper] <c04206ba>
tasklet_action+0x37/0x57
Oct 29 20:27:43 localhost kernel: <c042070f> __do_softirq+0x35/0x7f
<c040508a> do_softirq+0x38/0x42
Oct 29 20:27:43 localhost kernel: =======================
Oct 29 20:27:43 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:27:43 localhost kernel: <c0401f7c> mwait_idle+0x1a/0x2e
<c0401f4d> cpu_idle+0x3a/0x4f
Oct 29 20:27:43 localhost kernel: <c0720724> start_kernel+0x2d7/0x2db
<c0720249> unknown_bootoption+0x0/0x204
Oct 29 20:27:43 localhost shutdown[2973]: shutting down for system halt
Oct 29 20:27:43 localhost init: Switching to runlevel: 0
Oct 29 20:28:33 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:28:33 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:28:33 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:28:33 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:28:33 localhost kernel: =======================
Oct 29 20:28:33 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:28:33 localhost kernel: <c040e0d7> delay_pmtmr+0xb/0x13
<c04d8221> __delay+0x9/0xa
Oct 29 20:28:33 localhost kernel: <f9d91199>
KeStallExecutionProcessor+0x9/0xc [ndiswrapper] <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper]
Oct 29 20:28:33 localhost kernel: <c04206ba> tasklet_action+0x37/0x57
<c042070f> __do_softirq+0x35/0x7f
Oct 29 20:28:33 localhost kernel: <c040508a> do_softirq+0x38/0x42
Oct 29 20:28:33 localhost kernel: =======================
Oct 29 20:28:33 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:28:48 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:28:48 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:28:48 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:28:48 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:28:48 localhost kernel: =======================
Oct 29 20:28:48 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:28:48 localhost kernel: <c040e0d7> delay_pmtmr+0xb/0x13
<c04d8221> __delay+0x9/0xa
Oct 29 20:28:48 localhost kernel: <f9d91199>
KeStallExecutionProcessor+0x9/0xc [ndiswrapper] <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper]
Oct 29 20:28:48 localhost kernel: <c04206ba> tasklet_action+0x37/0x57
<c042070f> __do_softirq+0x35/0x7f
Oct 29 20:28:48 localhost kernel: <c040508a> do_softirq+0x38/0x42
Oct 29 20:28:48 localhost kernel: =======================
Oct 29 20:28:48 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:29:03 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:29:03 localhost avahi-daemon[2310]: Got SIGTERM, quitting.
Oct 29 20:29:18 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:29:18 localhost shutdown[3033]: shutting down for system halt
Oct 29 20:29:18 localhost avahi-daemon[2310]: Leaving mDNS multicast
group on interface wlan0.IPv4 with address 192.168.2.210.
Oct 29 20:29:18 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:29:18 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:29:18 localhost kernel: =======================
Oct 29 20:29:18 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c0405047> do_IRQ+0x75/0x80
Oct 29 20:29:18 localhost kernel: <c04036f2>
common_interrupt+0x1a/0x20 <c040e0d7> delay_pmtmr+0xb/0x13
Oct 29 20:29:18 localhost kernel: <c04d8221> __delay+0x9/0xa
<f9d91199> KeStallExecutionProcessor+0x9/0xc [ndiswrapper]
Oct 29 20:29:18 localhost kernel: <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper] <c04206ba>
tasklet_action+0x37/0x57
Oct 29 20:29:18 localhost kernel: <c042070f> __do_softirq+0x35/0x7f
<c040508a> do_softirq+0x38/0x42
Oct 29 20:29:18 localhost kernel: =======================
Oct 29 20:29:18 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:29:18 localhost kernel: BUG: soft lockup detected on CPU#0!
Oct 29 20:29:18 localhost kernel: <c043dacd> softlockup_tick+0x90/0xa1
<c0423d50> update_process_times+0x35/0x57
Oct 29 20:29:18 localhost kernel: <c0406134> timer_interrupt+0x60/0x98
<c043db6a> handle_IRQ_event+0x23/0x4c
Oct 29 20:29:18 localhost kernel: <c043dc0f> __do_IRQ+0x7c/0xd1
<c0405035> do_IRQ+0x63/0x80
Oct 29 20:29:18 localhost kernel: =======================
Oct 29 20:29:18 localhost kernel: <c04036f2>
common_interrupt+0x1a/0x20 <c040e0d7> delay_pmtmr+0xb/0x13
Oct 29 20:29:18 localhost kernel: <c04d8221> __delay+0x9/0xa
<f9d91199> KeStallExecutionProcessor+0x9/0xc [ndiswrapper]
Oct 29 20:29:19 localhost kernel: <f9d95bc2>
ndis_irq_handler_shared+0x1a/0x1d [ndiswrapper] <c04206ba>
tasklet_action+0x37/0x57
Oct 29 20:29:19 localhost kernel: <c042070f> __do_softirq+0x35/0x7f
<c040508a> do_softirq+0x38/0x42
Oct 29 20:29:19 localhost kernel: =======================
Oct 29 20:29:19 localhost kernel: <c0405047> do_IRQ+0x75/0x80
<c04036f2> common_interrupt+0x1a/0x20
Oct 29 20:29:19 localhost lircd-0.8.1-CVS[2861]: caught signal
Oct 29 20:29:21 localhost mountd[1963]: Caught signal 15, un-registering
and exiting.
Oct 29 20:29:25 localhost kernel: nfsd: last server has exited
Oct 29 20:29:25 localhost kernel: nfsd: unexporting all filesystems
Oct 29 20:29:25 localhost kernel: RPC: failed to contact portmap (errno
-5).
Oct 29 20:29:26 localhost dhcpd: dhcpd shutdown succeeded
Oct 29 20:29:29 localhost smartd[1851]: smartd received signal 15:
Terminated
Oct 29 20:29:29 localhost smartd[1851]: smartd is exiting (exit status 0)
Oct 29 20:29:29 localhost xinetd[1904]: Exiting...
Oct 29 20:29:30 localhost ntpd[1921]: ntpd exiting on signal 15
Oct 29 20:29:30 localhost hcid[1696]: Got disconnected from the system
message bus
Oct 29 20:29:30 localhost kernel: setitimer: hcid (pid = 1696) provided
invalid timeval it_value: tv_sec = 0 tv_usec = 5000000
Oct 29 20:29:30 localhost kernel: setitimer: hcid (pid = 1696) provided
invalid timeval it_interval: tv_sec = 0 tv_usec = 5000000
Oct 29 20:29:30 localhost rpc.statd[1645]: Caught signal 15,
un-registering and exiting.
Oct 29 20:29:31 localhost kernel: Kernel logging (proc) stopped.
Oct 29 20:29:31 localhost kernel: Kernel log daemon terminating.
Oct 29 20:29:32 localhost exiting on signal 15
</snip>
More information about the mythtv-users
mailing list