FC4 locks up on my T42

[byron]

randomly my thinkpad just locks up when i'm in X. usually just when i'm browsing the web or something. when it locks up.. its a hard lockup and nothing works (CTRL+ALT+F1...nothing).i really don't have anything special going on... basically what i have is this:


tpb (thinkpad buttons app)
thunderbird
firefox
madwifi (for my a/b/g card)
wpa_supplicant
xmms
gaim
azureus
adobe reader

and i added the lines below to my xorg.conf file to enable the scroll button...

Option "EmulateWheel"
Option "EmulateWheelButton" "2"
Option "Emulate3Buttons" "true"

i just added acpi=off apm=off to my grub.conf file.... so i'm going to run it like that for a while and see if that helps... if it does i need to figure out which one is causing the problem and enable the other. i don't like the fact that I can't see my battery status in X. which module controls that? ACPI or APM?

if it isn't a power management problem, my next idea was to update the video drivers..

anyone else have any ideas?

[chubes]

ACPI and APM are both power management utilities, with APM being much older and slowly being superceded by ACPI. Older machines (pre 1998) usually don't support ACPI, and are disabled during bootime by linux.

Has it locked up since you disabled ACPI and APM?

I would recommend you disable APM and keep ACPI going. At least in fedora, there are two /etc/init.d scripts that control these.

#>> /sbin/chkconfig --list | grep apm
apmd 0:off 1:off 2:off 3:off 4:off 5:off 6:off
#>> /sbin/chkconfig --list | grep acpid
acpid 0:off 1:off 2:off 3:on 4:on 5:on 6:off

Issue as root:
#>> /sbin/chkconfig apmd off

With ACPI, if you
#>> cat /proc/acpi/battery/BAT0/state
present: yes
capacity state: ok
charging state: charged
present rate: 0 mW
remaining capacity: 35230 mWh
present voltage: 12482 mV

You can see more information. Have you updated to the latest kernel using yum -y update?

[byron]

ya.. it still locked up when them both disabled. next i updated to livna.org ATI drivers/kernel modules.

i recompiled the most recent kernel yesterday and the jury is still out. i will report back in a couple days.

[chubes]

Turn logging up and see if the syslog (/var/log/messages) has anything useful in it.

[byron]

i've looked in there and i can't find anything useful... what do you mean buy "turn it up"?

[Volker]

Did it just recently start locking up, or do you have the problem since purchase? If you always had the problem I'd suggest running memtest for a day or two. I had a similar problem and changing the ram fixed it.

Since you apparently use binary-only X drivers, you could try the open source one and see if the problem persists.

[byron]

ive always ran windows on my thinkpad. i recently bought a bigger hdd, which is the reason i am able to do a dual boot now.

since recompiling the latest kernel it hasn't bombed yet. (knocks on wood).

[byron]

still no luck. when it locked up today.. i wasn't even doing anything.. just had firefox and gaim running. it was just sitting on the coffee table in front of me while i was watching football.

anyone care to suggest something that might help me narrow down the problem.

here are a few things that are in my /var/log/mesages. this one gets dumped in the log every time i log into X.

Code: Select all

Jan  8 14:28:19 localhost gconfd (byron-3075): Resolved address "xml:readonly:/etc/gconf/gconf.xml.mandatory" to a read-only configuration source at position 0
Jan  8 14:28:19 localhost gconfd (byron-3075): Resolved address "xml:readwrite:/home/byron/.gconf" to a writable configuration source at position 1
Jan  8 14:28:19 localhost gconfd (byron-3075): Resolved address "xml:readonly:/etc/gconf/gconf.xml.defaults" to a read-only configuration source at position 2
Jan  8 14:28:28 localhost gconfd (byron-3075): Resolved address "xml:readwrite:/home/byron/.gconf" to a writable configuration source at position 0

this was the last thing in /var/log/messages before the last lock up:

Code: Select all

Jan  8 14:09:56 localhost kernel: inode_doinit_with_dentry:  context_to_sid() returned 22 for dev=hda6 ino=1407870
Jan  8 14:09:56 localhost kernel: inode_doinit_with_dentry:  context_to_sid() returned 22 for dev=hda6 ino=1407871

recently i replaced the hdd in my thinkpad.. i bought a new 80gig hitachi travelstar 5400rpm drive.... any chance a bad drive could the be cause? any utilities you guys know of i could run to test the drive?

[christopher_wolf]

You could run the smartctrl utility to check out the HDD.

[byron]

installed the smartmontools.... any advice on tests to run... it looks so powerful that i could spend weeks reading documentation on how to use it.

[smugiri]

Two possible causes, I suspect a hardware problem and / or video blanking and I am probably wrong BUT ... it does not hurt to try something new as long as it is not a hammer, right? Try re-distributing your IRQs, some people have problems with the default setting since thinkpads have all the hardware on irq 11. See this link for a suggested distribution. To do this, you go into the BIOS through F1 at boot and go into the config menu. This page has much more specific info about the 2 potential problems. Be sure to read the section on video blanking. YMMV

[byron]

change all my IRQs this morning.... it locked up tonight.

it was wierd though.. i'm not sure i didn't notice this before b/c i didn't have anything running that was chewing up any bandwidth but i had azureus running and i was on AIM and it locked up and i noticed the WLAN LED on the bottom of the display continued to flash, but the keyboard, trackpoint, and LCD was completely unresponsive. i got on a different computer and logged onto a different AIM s/n and my username contineued to stay online for 5 minutes or so.. after that i just did a hard power down and i'm back in windows for now.

im starting to run out of ideas... i've been reading/posting at fedoraforum.org and it looks like i'm not the only one having problems... but with something like this its hard to tell if our problems are actually the same b/c there are no logs to report what is actually happening.

anyone more ideas?

[byron]

to add insult to injury... things are just getting worse.

while using azureus again tonight in Fedora it popped up lots of notices about how it couldn't write the files (what i was downloading) because suddenly they are now read only. this is after it had been downloading with no problems for 15 min or so.... my user had RWX permissions to the entire directory (it was saving to my home directory anyhow), so i know the directory really isn't read only.

that really had me worried so i downloaded hitachi's drive fitness test to run on my drive (hitachi travelstar). booted to the drive fitness disk and ran the most extensive test and all tests passed with no issue.

so now i reboot into fedora and as its checking file consistency it throws up "UNEXPECTED INCONSISTENCY: run fsck manually"

so i run fsck and let it does its thing, restarted and i can get in... but this is the second time today i've seen this darn "UNEXPECTED INCONSISTENCY" message... here are some pieces from my logs...

anyone??? i'm really close to kicking fedora out the door... as this is becoming extremely frustrating. my windows side shoudn't be the stable side of my dual boot.

Code: Select all

Jan 10 23:36:53 localhost gconfd (byron-3867): starting (version 2.10.0), pid 3867 user 'byron'
Jan 10 23:36:53 localhost gconfd (byron-3867): Resolved address "xml:readonly:/etc/gconf/gconf.xml.mandatory" to a read-only configuration source at position 0
Jan 10 23:36:53 localhost gconfd (byron-3867): Resolved address "xml:readwrite:/home/byron/.gconf" to a writable configuration source at position 1
Jan 10 23:36:53 localhost gconfd (byron-3867): Resolved address "xml:readonly:/etc/gconf/gconf.xml.defaults" to a read-only configuration source at position 2
Jan 10 23:37:05 localhost gconfd (byron-3867): Resolved address "xml:readwrite:/home/byron/.gconf" to a writable configuration source at position 0
Jan 10 23:37:10 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1707193
Jan 10 23:37:41 localhost gconfd (root-4018): starting (version 2.10.0), pid 4018 user 'root'
Jan 10 23:37:41 localhost gconfd (root-4018): Resolved address "xml:readonly:/etc/gconf/gconf.xml.mandatory" to a read-only configuration source at position 0
Jan 10 23:37:41 localhost gconfd (root-4018): Resolved address "xml:readwrite:/root/.gconf" to a writable configuration source at position 1
Jan 10 23:37:41 localhost gconfd (root-4018): Resolved address "xml:readonly:/etc/gconf/gconf.xml.defaults" to a read-only configuration source at position 2
Jan 10 23:37:41 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1504406
Jan 10 23:37:42 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1508813
Jan 10 23:37:52 localhost dhclient: DHCPREQUEST on ath0 to 255.255.255.255 port 67
Jan 10 23:38:01 localhost last message repeated 2 times
Jan 10 23:38:13 localhost dhclient: DHCPDISCOVER on ath0 to 255.255.255.255 port 67 interval 5
Jan 10 23:38:18 localhost dhclient: DHCPDISCOVER on ath0 to 255.255.255.255 port 67 interval 9
Jan 10 23:38:27 localhost dhclient: DHCPDISCOVER on ath0 to 255.255.255.255 port 67 interval 10
Jan 10 23:38:36 localhost su(pam_unix)[4202]: session opened for user root by (uid=500)
Jan 10 23:38:37 localhost dhclient: DHCPDISCOVER on ath0 to 255.255.255.255 port 67 interval 21
Jan 10 23:38:37 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1504389
Jan 10 23:38:37 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1504390
Jan 10 23:38:37 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1514019



Jan 10 19:11:20 localhost kernel: inode_doinit_with_dentry:  context_to_sid(^O^A^^C^O^A) returned 22 for dev=hda6 ino=1707193
Jan 10 19:32:26 localhost kernel: Inbound IN=ath0 OUT= MAC=ff:ff:ff:ff:ff:ff:00:0e:35:53:aa:4a:08:00:45:00:01:56:97:e0:00:00:80:11:df:fd:c0:a8:01:11:ff:ff:ff:ff:00:44:00:43:01:42:54:02:01:01:06:00:15:41:47:bd:00:00:00:00:c0:a8:01:11:00:00:00:00:00:00:00:00:00:00:00:00:00:0e:35:53:aa:4a:00:00:00:00:00:00:00:00:00:00:00:00 SRC=192.168.1.17 DST=255.255.255.255 LEN=342 TOS=0x00 PREC=0x00 TTL=128 ID=38880 PROTO=UDP SPT=68 DPT=67 LEN=322
Jan 10 19:38:09 localhost kernel: Inbound IN=ath0 OUT= MAC=00:05:4e:50:26:4e:00:12:17:1a:0e:e6:08:00:45:00:00:30:01:7a:40:00:6f:06:a8:ed:40:22:5f:83:c0:a8:01:13:0b:49:13:23:31:2f:1d:f9:00:00:00:00:70:02:ff:ff:b4:2a:00:00:02:04:05:b4:01:01:04:02:f2:0d:63:fc:76:7b:c0:61:24:cc:c5:d4:b7:74:d0:4d:0b:3b:8c:4f:7d:74:f6:28:76:9e SRC=64.34.95.131 DST=192.168.1.19 LEN=48 TOS=0x00 PREC=0x00 TTL=111 ID=378 DF PROTO=TCP SPT=2889 DPT=4899 WINDOW=65535 RES=0x00 SYN URGP=0
Jan 10 19:38:12 localhost kernel: Inbound IN=ath0 OUT= MAC=00:05:4e:50:26:4e:00:12:17:1a:0e:e6:08:00:45:00:00:30:06:ce:40:00:6f:06:a3:99:40:22:5f:83:c0:a8:01:13:0b:49:13:23:31:2f:1d:f9:00:00:00:00:70:02:ff:ff:b4:2a:00:00:02:04:05:b4:01:01:04:02:a0:d4:fc:a8:cc:56:81:58:5a:bc:e9:67:82:bc:b3:fc:68:d7:93:b8:63:4f:5b:65:ec:77 SRC=64.34.95.131 DST=192.168.1.19 LEN=48 TOS=0x00 PREC=0x00 TTL=111 ID=1742 DF PROTO=TCP SPT=2889 DPT=4899 WINDOW=65535 RES=0x00 SYN URGP=0
Jan 10 19:38:18 localhost kernel: Inbound IN=ath0 OUT= MAC=00:05:4e:50:26:4e:00:12:17:1a:0e:e6:08:00:45:00:00:30:11:ff:40:00:6f:06:98:68:40:22:5f:83:c0:a8:01:13:0b:49:13:23:31:2f:1d:f9:00:00:00:00:70:02:ff:ff:b4:2a:00:00:02:04:05:b4:01:01:04:02:51:4e:9b:a8:14:cd:99:25:ed:f7:a1:17:d0:9d:e9:49:5e:c5:3e:f0:c6:b9:ae:f3:47:1c SRC=64.34.95.131 DST=192.168.1.19 LEN=48 TOS=0x00 PREC=0x00 TTL=111 ID=4607 DF PROTO=TCP SPT=2889 DPT=4899 WINDOW=65535 RES=0x00 SYN URGP=0
Jan 10 19:40:25 localhost kernel: Inbound IN=ath0 OUT= MAC=ff:ff:ff:ff:ff:ff:00:0e:35:53:aa:4a:08:00:45:00:01:56:97:e8:00:00:80:11:df:f5:c0:a8:01:11:ff:ff:ff:ff:00:44:00:43:01:42:53:56:01:01:06:00:40:4d:1d:5d:00:00:00:00:c0:a8:01:11:00:00:00:00:00:00:00:00:00:00:00:00:00:0e:35:53:aa:4a:00:00:00:00:00:00:00:00:00:00:00:00 SRC=192.168.1.17 DST=255.255.255.255 LEN=342 TOS=0x00 PREC=0x00 TTL=128 ID=38888 PROTO=UDP SPT=68 DPT=67 LEN=322
Jan 10 19:46:16 localhost kernel: Inbound IN=ath0 OUT= MAC=00:05:4e:50:26:4e:00:12:17:1a:0e:e6:08:00:45:00:01:76:00:00:40:00:27:11:09:69:de:d0:a8:82:c0:a8:01:13:ba:77:10:a1:01:62:ee:44:04:00:28:00:10:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:f8:91:7b:5a:00:ff:d0:11:a9:b2:00:c0:4f:b6:e6:fc:00:00:00:00:00:00 SRC=222.208.168.130 DST=192.168.1.19 LEN=374 TOS=0x00 PREC=0x00 TTL=39 ID=0 DF PROTO=UDP SPT=47735 DPT=4257 LEN=354



Jan 10 19:10:42 localhost kernel: cs: IO port probe 0xc00-0xcff: clean.
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0xc00-0xcff: clean.
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0x800-0x8ff: clean.
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0x800-0x8ff: clean.
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0x100-0x4ff: excluding 0x3b8-0x3df 0x4d0-0x4d7
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0x100-0x4ff: excluding 0x3b8-0x3df 0x4d0-0x4d7
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0xa00-0xaff: clean.
Jan 10 19:10:42 localhost kernel: cs: IO port probe 0xa00-0xaff: clean.
Jan 10 19:10:42 localhost kernel: audit(1136938220.871:17): avc:  denied  

{ getattr } for  pid=1972 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:18): avc:  denied  { execute } for  pid=1973 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:19): avc:  denied  { getattr } for  pid=1973 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:20): avc:  denied  { getattr } for  pid=1973 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:21): avc:  denied  { execute } for  pid=1974 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:22): avc:  denied  { getattr } for  pid=1974 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.879:23): avc:  denied  { getattr } for  pid=1974 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.883:24): avc:  denied  { execute } for  pid=1975 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.883:25): avc:  denied  { getattr } for  pid=1975 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.883:26): avc:  denied  { getattr } for  pid=1975 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.883:27): avc:  denied  { execute } for  pid=1976 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file
Jan 10 19:10:42 localhost kernel: audit(1136938220.883:28): avc:  denied  { getattr } for  pid=1976 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:42 localhost kernel: audit(1136938220.883:29): avc:  denied  { getattr } for  pid=1976 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:42 localhost kernel: audit(1136938220.883:30): avc:  denied  { execute } for  pid=1977 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:42 localhost kernel: audit(1136938220.883:31): avc:  denied  { getattr } for  pid=1977 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.883:32): avc:  denied  { getattr } for  pid=1977 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:33): avc:  denied  { execute } for  pid=1978 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:34): avc:  denied  { getattr } for  pid=1978 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:35): avc:  denied  { getattr } for  pid=1978 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:36): avc:  denied  { execute } for  pid=1979 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:37): avc:  denied  { getattr } for  pid=1979 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.887:38): avc:  denied  { getattr } for  pid=1979 comm="sh" name="modprobe" dev=hda6 ino=2223924 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:insmod_exec_t tclass=file Jan 10 19:10:43 localhost kernel: audit(1136938220.919:39): avc:  denied  { create } for  pid=1980 comm="iptables" scontext=system_u:system_r:dhcpc_t tcontext=system_u:system_r:dhcpc_t tclass=rawip_socket Jan 10 19:10:43 localhost kernel: audit(1136938220.919:40): avc:  denied  { read } for  pid=1980 comm="iptables" name="modprobe" dev=proc ino=-268435400 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:sysctl_modprobe_t tclass=file Jan 10 19:10:44 localhost kernel: audit(1136938220.919:41): avc:  denied  { create } for  pid=1981 comm="iptables" scontext=system_u:system_r:dhcpc_t tcontext=system_u:system_r:dhcpc_t tclass=rawip_socket Jan 10 19:10:44 localhost kernel: audit(1136938220.919:42): avc:  denied  { read } for  pid=1981 comm="iptables" name="modprobe" dev=proc ino=-268435400 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:sysctl_modprobe_t tclass=file Jan 10 19:10:44 localhost kernel: audit(1136938220.939:43): avc:  denied  { create } for  pid=1983 comm="iptables" scontext=system_u:system_r:dhcpc_t tcontext=system_u:system_r:dhcpc_t tclass=rawip_socket Jan 10 19:10:44 localhost kernel: audit(1136938220.939:44): avc:  denied  { read } for  pid=1983 comm="iptables" name="modprobe" dev=proc ino=-268435400 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:sysctl_modprobe_t tclass=file 

[smugiri]

Another suggestion: try turning off write caching. See here for some info. As always, YMMV.