Attachment 293571 Details for Bug 431038 – dmesg_eth0_threadlock.txt for 2.6.24-0.167

dmesg_eth0_threadlock.txt for 2.6.24-0.167

dmesg_eth0_threadlock.txt (text/plain), 15.60 KB, created by Andrew Farris on 2008-01-31 11:45:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Andrew Farris

Created: 2008-01-31 11:45:06 UTC

Size: 15.60 KB

patch

obsolete

>fs), uses task SIDs
>SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
>SELinux: initialized (dev proc, type proc), uses genfs_contexts
>SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
>SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
>SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
>SELinux: policy loaded with handle_unknown=allow
>audit(1201231315.769:3): policy loaded auid=4294967295
>sd 0:0:0:0: Attached scsi generic sg0 type 0
>sd 0:0:1:0: Attached scsi generic sg1 type 0
>scsi 1:0:0:0: Attached scsi generic sg2 type 5
>scsi 1:0:1:0: Attached scsi generic sg3 type 5
>Driver 'sr' needs updating - please use bus_type methods
>sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
>Uniform CD-ROM driver Revision: 3.20
>sr 1:0:0:0: Attached scsi CD-ROM sr0
>sr1: scsi3-mmc drive: 40x/40x writer cd/rw xa/form2 cdda tray
>sr 1:0:1:0: Attached scsi CD-ROM sr1
>input: Power Button (FF) as /devices/virtual/input/input6
>ACPI: Power Button (FF) [PWRF]
>input: Power Button (CM) as /devices/virtual/input/input7
>ACPI: Power Button (CM) [VBTN]
>gameport: EMU10K1 is pci0000:02:09.1/gameport0, io 0xecd8, speed 978kHz
>ACPI: PCI Interrupt 0000:02:09.2[B] -> GSI 19 (level, low) -> IRQ 19
>Linux Tulip driver version 1.1.15 (Feb 27, 2007)
>firewire_ohci: Added fw-ohci device 0000:02:09.2, OHCI version 1.10
>ACPI: PCI Interrupt 0000:02:0a.0[A] -> GSI 19 (level, low) -> IRQ 19
>tulip0:  MII transceiver #1 config 1000 status 786d advertising 01e1.
>eth0: ADMtek Comet rev 17 at MMIO 0xfe1fd800, 00:04:5a:7d:9f:01, IRQ 19.
>iTCO_vendor_support: vendor-support=0
>ACPI: PCI Interrupt 0000:02:08.0[A] -> GSI 17 (level, low) -> IRQ 18
>phy0: Selected rate control algorithm 'pid'
>iTCO_wdt: Intel TCO WatchDog Timer Driver v1.02 (26-Jul-2007)
>iTCO_wdt: failed to reset NO_REBOOT flag, reboot disabled by hardware
>iTCO_wdt: No card detected
>Intel 82802 RNG detected
>firewire_core: created new fw device fw0 (0 config rom retries, S400)
>firewire_core: created new fw device fw1 (0 config rom retries, S400)
>firewire_core: phy config: card 0, new root=ffc1, gap_count=5
>ACPI: PCI Interrupt 0000:02:0c.0[A] -> GSI 18 (level, low) -> IRQ 16
>3c59x: Donald Becker and others.
>0000:02:0c.0: 3Com PCI 3c905C Tornado at f8eb4c00.
>ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 17 (level, low) -> IRQ 18
>ACPI: PCI Interrupt 0000:02:09.0[A] -> GSI 18 (level, low) -> IRQ 16
>ALSA sound/pci/emu10k1/emu10k1_main.c:1671: vendor=0x1102, device=0x4, subsystem_vendor_id=0x511102, subsystem_id=0x51
>ALSA sound/pci/emu10k1/emu10k1_main.c:1696: Sound card name=Audigy 1 [SB0090]
>ACPI: PCI Interrupt 0000:00:1f.5[B] -> GSI 17 (level, low) -> IRQ 18
>PCI: Setting latency timer of device 0000:00:1f.5 to 64
>input: PC Speaker as /devices/platform/pcspkr/input/input8
>FDC 0 is a post-1991 82077
>rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0
>rtc0: alarms up to one day
>ALSA sound/pci/ac97/ac97_codec.c:2869: ac97 quirk for Dell (1028:010d)
>dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
>intel8x0_measure_ac97_clock: measured 50848 usecs
>intel8x0: measured clock 78 rejected
>intel8x0: clocking to 48000
>scsi2 : SBP-2 IEEE-1394
>firewire_sbp2: logged in to fw1.0 LUN 0000 (0 retries)
>scsi 2:0:0:0: Direct-Access-RBC Maxtor 6 L120P0                PQ: 0 ANSI: 4
>sd 2:0:0:0: [sdc] 240121728 512-byte hardware sectors (122942 MB)
>sd 2:0:0:0: [sdc] Write Protect is off
>sd 2:0:0:0: [sdc] Mode Sense: 11 00 00 00
>sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
>sd 2:0:0:0: [sdc] 240121728 512-byte hardware sectors (122942 MB)
>sd 2:0:0:0: [sdc] Write Protect is off
>sd 2:0:0:0: [sdc] Mode Sense: 11 00 00 00
>sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
> sdc: sdc1 sdc2 sdc3
>sd 2:0:0:0: [sdc] Attached SCSI disk
>sd 2:0:0:0: Attached scsi generic sg4 type 14
>device-mapper: uevent: version 1.0.3
>device-mapper: ioctl: 4.12.0-ioctl (2007-10-02) initialised: dm-devel@redhat.com
>device-mapper: multipath: version 1.0.5 loaded
>loop: module loaded
>EXT3 FS on sdb3, internal journal
>kjournald starting.  Commit interval 5 seconds
>EXT3 FS on sdb6, internal journal
>EXT3-fs: mounted filesystem with ordered data mode.
>SELinux: initialized (dev sdb6, type ext3), uses xattr
>SGI XFS with ACLs, security attributes, large block numbers, no debug enabled
>SGI XFS Quota Management subsystem
>XFS mounting filesystem sdb2
>Ending clean XFS mount for filesystem: sdb2
>SELinux: initialized (dev sdb2, type xfs), uses xattr
>kjournald starting.  Commit interval 5 seconds
>EXT3 FS on sdb1, internal journal
>EXT3-fs: mounted filesystem with ordered data mode.
>SELinux: initialized (dev sdb1, type ext3), uses xattr
>SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>SELinux: initialized (dev sdc3, type vfat), uses genfs_contexts
>audit(1201231336.282:4): avc:  denied  { read write } for  pid=1581 comm="modprobe" path="/dev/mapper/control" dev=tmpfs ino=6586 scontext=system_u:system_r:insmod_t:s0 tcontext=system_u:object_r:lvm_control_t:s0 tclass=chr_file
>fuse init (API version 7.9)
>SELinux: initialized (dev sdc2, type fuseblk), uses genfs_contexts
>kjournald starting.  Commit interval 5 seconds
>EXT3 FS on sdc1, internal journal
>EXT3-fs: mounted filesystem with ordered data mode.
>SELinux: initialized (dev sdc1, type ext3), uses xattr
>SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
>NET: Registered protocol family 10
>lo: Disabled Privacy Extensions
>audit(1201231342.683:5): avc:  denied  { create } for  pid=1598 comm="rhgb" name=".fontconfig" scontext=system_u:system_r:rhgb_t:s0 tcontext=system_u:object_r:root_t:s0 tclass=dir
>Adding 2867560k swap on /dev/sdb5.  Priority:-1 extents:1 across:2867560k
>SELinux: initialized (dev binfmt_misc, type binfmt_misc), uses genfs_contexts
>IA-32 Microcode Update Driver: v1.14a <tigran@aivazian.fsnet.co.uk>
>warning: process `kudzu' used the deprecated sysctl system call with 1.23.
>Loading iSCSI transport class v2.0-724.
>iscsi: registered transport (tcp)
>iscsi: registered transport (iser)
>ip6_tables: (C) 2000-2006 Netfilter Core Team
>nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc06c012 CSR6 0xff970111)
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc664010 CSR6 0xff972113)
>eth0: Setting full-duplex based on MII#1 link partner capability of cde1.
>eth1:  setting full-duplex.
>eth0: no IPv6 routers present
>RPC: Registered udp transport module.
>RPC: Registered tcp transport module.
>SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
>audit(1201231367.198:6): audit_pid=2600 old=0 by auid=4294967295 subj=system_u:system_r:auditd_t:s0
>eth1: no IPv6 routers present
>SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
>SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
>SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
>Bluetooth: Core ver 2.11
>NET: Registered protocol family 31
>Bluetooth: HCI device and connection manager initialized
>Bluetooth: HCI socket layer initialized
>Bluetooth: L2CAP ver 2.9
>Bluetooth: L2CAP socket layer initialized
>Bluetooth: RFCOMM socket layer initialized
>Bluetooth: RFCOMM TTY layer initialized
>Bluetooth: RFCOMM ver 1.8
>SELinux: initialized (dev sda1, type fuseblk), uses genfs_contexts
>SELinux: initialized (dev sda2, type fuseblk), uses genfs_contexts
>warning: process `/usr/sbin/syste' used the deprecated sysctl system call with 1.23.
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc06c012 CSR6 0xff970111)
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc664010 CSR6 0xff972113)
>eth0: Setting full-duplex based on MII#1 link partner capability of cde1.
>eth1:  setting full-duplex.
>eth0: no IPv6 routers present
>eth1: no IPv6 routers present
>
>=======================================================
>[ INFO: possible circular locking dependency detected ]
>2.6.24-0.167.rc8.git4.fc9 #1
>-------------------------------------------------------
>events/0/6 is trying to acquire lock:
> (rtnl_mutex){--..}, at: [<c05deea7>] rtnl_lock+0xf/0x11
>
>but task is already holding lock:
> ((linkwatch_work).work){--..}, at: [<c043d511>] run_workqueue+0x85/0x1ac
>
>which lock already depends on the new lock.
>
>
>the existing dependency chain (in reverse order) is:
>
>-> #2 ((linkwatch_work).work){--..}:
>       [<c044d13f>] __lock_acquire+0x9c5/0xb5f
>       [<c044d6e5>] lock_acquire+0x7b/0x9e
>       [<c043d560>] run_workqueue+0xd4/0x1ac
>       [<c043e082>] worker_thread+0xbb/0xc9
>       [<c0440e4f>] kthread+0x3b/0x64
>       [<c0405f3b>] kernel_thread_helper+0x7/0x10
>       [<ffffffff>] 0xffffffff
>
>-> #1 (events){--..}:
>       [<c044d13f>] __lock_acquire+0x9c5/0xb5f
>       [<c044d6e5>] lock_acquire+0x7b/0x9e
>       [<c043ddaa>] flush_workqueue+0x55/0x93
>       [<c043ddf5>] flush_scheduled_work+0xd/0xf
>       [<f8fa5158>] tulip_down+0x20/0x1a5 [tulip]
>       [<f8fa6213>] tulip_close+0x22/0xdb [tulip]
>       [<c05d6b97>] dev_close+0x4a/0x62
>       [<c05d6a3f>] dev_change_flags+0xa4/0x152
>       [<c0617590>] devinet_ioctl+0x211/0x518
>       [<c0617c3f>] inet_ioctl+0x86/0xa4
>       [<c05cb87b>] sock_ioctl+0x1ca/0x1eb
>       [<c049d04a>] do_ioctl+0x22/0x67
>       [<c049d2d8>] vfs_ioctl+0x249/0x25c
>       [<c049d32d>] sys_ioctl+0x42/0x5d
>       [<c040526e>] syscall_call+0x7/0xb
>       [<ffffffff>] 0xffffffff
>
>-> #0 (rtnl_mutex){--..}:
>       [<c044d02f>] __lock_acquire+0x8b5/0xb5f
>       [<c044d6e5>] lock_acquire+0x7b/0x9e
>       [<c063d7db>] mutex_lock_nested+0xec/0x282
>       [<c05deea7>] rtnl_lock+0xf/0x11
>       [<c05dffd2>] linkwatch_event+0x8/0x27
>       [<c043d565>] run_workqueue+0xd9/0x1ac
>       [<c043e082>] worker_thread+0xbb/0xc9
>       [<c0440e4f>] kthread+0x3b/0x64
>       [<c0405f3b>] kernel_thread_helper+0x7/0x10
>       [<ffffffff>] 0xffffffff
>
>other info that might help us debug this:
>
>2 locks held by events/0/6:
> #0:  (events){--..}, at: [<c043d511>] run_workqueue+0x85/0x1ac
> #1:  ((linkwatch_work).work){--..}, at: [<c043d511>] run_workqueue+0x85/0x1ac
>
>stack backtrace:
>Pid: 6, comm: events/0 Not tainted 2.6.24-0.167.rc8.git4.fc9 #1
> [<c04064b6>] show_trace_log_lvl+0x1a/0x2f
> [<c0406ce2>] show_trace+0x12/0x14
> [<c0407002>] dump_stack+0x6c/0x72
> [<c044b663>] print_circular_bug_tail+0x5f/0x68
> [<c044d02f>] __lock_acquire+0x8b5/0xb5f
> [<c044d6e5>] lock_acquire+0x7b/0x9e
> [<c063d7db>] mutex_lock_nested+0xec/0x282
> [<c05deea7>] rtnl_lock+0xf/0x11
> [<c05dffd2>] linkwatch_event+0x8/0x27
> [<c043d565>] run_workqueue+0xd9/0x1ac
> [<c043e082>] worker_thread+0xbb/0xc9
> [<c0440e4f>] kthread+0x3b/0x64
> [<c0405f3b>] kernel_thread_helper+0x7/0x10
> =======================
>ip_tables: (C) 2000-2006 Netfilter Core Team
>usb 1-4: new high speed USB device using ehci_hcd and address 2
>usb 1-4: configuration #1 chosen from 1 choice
>Initializing USB Mass Storage driver...
>scsi3 : SCSI emulation for USB Mass Storage devices
>usbcore: registered new interface driver usb-storage
>USB Mass Storage support registered.
>usb-storage: device found at 2
>usb-storage: waiting for device to settle before scanning
>usb-storage: device scan complete
>scsi 3:0:0:0: Direct-Access     External AL25744_12345678      PQ: 0 ANSI: 2
>sd 3:0:0:0: [sdd] 976773169 512-byte hardware sectors (500108 MB)
>sd 3:0:0:0: [sdd] Write Protect is off
>sd 3:0:0:0: [sdd] Mode Sense: 1a 0c 00 00
>sd 3:0:0:0: [sdd] Assuming drive cache: write through
>sd 3:0:0:0: [sdd] 976773169 512-byte hardware sectors (500108 MB)
>sd 3:0:0:0: [sdd] Write Protect is off
>sd 3:0:0:0: [sdd] Mode Sense: 1a 0c 00 00
>sd 3:0:0:0: [sdd] Assuming drive cache: write through
> sdd: sdd1
>sd 3:0:0:0: [sdd] Attached SCSI disk
>sd 3:0:0:0: Attached scsi generic sg5 type 0
>SELinux: initialized (dev sdd1, type fuseblk), uses genfs_contexts
>usb 5-1: USB disconnect, address 2
>usb 5-1.1: USB disconnect, address 4
>usb 5-1: new full speed USB device using uhci_hcd and address 5
>usb 5-1: not running at top speed; connect to a high speed hub
>usb 5-1: configuration #1 chosen from 1 choice
>hub 5-1:1.0: USB hub found
>hub 5-1:1.0: 4 ports detected
>usb 5-1.1: new low speed USB device using uhci_hcd and address 6
>usb 5-1.1: configuration #1 chosen from 1 choice
>input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1f.4/usb5/5-1/5-1.1/5-1.1:1.0/input/input9
>input,hidraw5: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1f.4-1.1
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc06c012 CSR6 0xff970111)
>ADDRCONF(NETDEV_UP): eth0: link is not ready
>warning: process `/usr/sbin/syste' used the deprecated sysctl system call with 1.23.
>eth1:  setting half-duplex.
>ADDRCONF(NETDEV_UP): eth1: link is not ready
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc664010 CSR6 0xff972113)
>eth0: Setting full-duplex based on MII#1 link partner capability of cde1.
>ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
>eth0: no IPv6 routers present
>0000:02:0a.0: tulip_stop_rxtx() failed (CSR5 0xfc06c012 CSR6 0xff970111)
>BUG: unable to handle kernel NULL pointer dereference at virtual address 000000a4
>printing eip: f8fa297c *pde = 027bb067 *pte = 00000000 
>Oops: 0000 [#1] SMP 
>Modules linked in: usb_storage rfcomm l2cap bluetooth autofs4 sunrpc xt_tcpudp nf_conntrack_ipv6 xt_state nf_conntrack ip6t_REJECT ip6table_filter ip6_tables x_tables ib_iser rdma_cm ib_cm iw_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi scsi_transport_iscsi ipv6 fuse vfat fat xfs loop dm_mirror dm_multipath dm_mod firewire_sbp2 dcdbas rtc_cmos floppy pcspkr snd_emu10k1_synth snd_emux_synth serio_raw snd_seq_virmidi snd_seq_midi_emul snd_intel8x0 snd_emu10k1 snd_rawmidi snd_ac97_codec ac97_bus snd_seq_dummy i2c_i801 i2c_core snd_seq_oss snd_seq_midi_event arc4 joydev snd_seq ecb blkcipher snd_pcm_oss snd_mixer_oss intel_rng snd_pcm iTCO_wdt 3c59x rt2500pci snd_seq_device iTCO_vendor_support rt2x00pci snd_timer rt2x00lib mii snd_page_alloc rfkill snd_util_mem input_polldev tulip firewire_ohci snd_hwdep firewire_core snd soundcore mac80211 emu10k1_gp crc_itu_t cfg80211 gameport eeprom_93cx6 button sr_mod cdrom sg pata_acpi ata_piix ata_generic libata sd_mod scsi_mod ext3 jbd mbcache uhci_hcd ohci_hcd ehci_hcd
>
>Pid: 8653, comm: ip Not tainted (2.6.24-0.167.rc8.git4.fc9 #1)
>EIP: 0060:[<f8fa297c>] EFLAGS: 00010006 CPU: 0
>EIP is at tulip_interrupt+0x2aa/0xbba [tulip]
>EAX: 00000000 EBX: f50a8f00 ECX: 00000017 EDX: f5823500
>ESI: 00000014 EDI: c254c6a2 EBP: c31c8afc ESP: c31c8a90
> DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
>Process ip (pid: 8653, ti=c31c8000 task=cf9ac000 task.ti=c31c8000)
>Stack: ffffffff 00000001 e07afaf0 c171ae48 c076a43c c31c8ad4 c048e6ad f887e828 
>       f5823000 f5823500 f887e800 fc69c0d0 00000000 00000000 00000000 00000019 
>       c1e598f0 005c8afc 00000000 0000007f f5823500 0000005c 00000020 c076a43c 
>Call Trace:
> [<c04064b6>] show_trace_log_lvl+0x1a/0x2f
> [<c0406566>] show_stack_log_lvl+0x9b/0xa3
> [<c0406615>] show_registers+0xa7/0x178
> [<c040681b>] die+0x135/0x220
> [<c06409cb>] do_page_fault+0x553/0x631
> [<c063f052>] error_code+0x72/0x78
> [<c04685fb>] request_irq+0xc1/0x112
> [<f8fa5392>] tulip_open+0x24/0x1ac [tulip]
> [<c05d7e11>] dev_open+0x45/0x7b
> [<c05d6a3f>] dev_change_flags+0xa4/0x152
> [<c05de169>] do_setlink+0x25e/0x317
> [<c05df6a9>] rtnl_newlink+0x246/0x3a3
> [<c05df062>] rtnetlink_rcv_msg+0x19a/0x1b4
> [<c05ec2cc>] netlink_rcv_skb+0x30/0x82
> [<c05deec0>] rtnetlink_rcv+0x17/0x1f
> [<c05ec0a0>] netlink_unicast+0x19c/0x205
> [<c05ec8cd>] netlink_sendmsg+0x279/0x285
> [<c05cc100>] sock_sendmsg+0xe0/0xfd
> [<c05cc26e>] sys_sendmsg+0x151/0x1af
> [<c05cd33d>] sys_socketcall+0x220/0x241
> [<c040526e>] syscall_call+0x7/0xb
> =======================
>Code: 00 00 00 8b bb a4 00 00 00 83 c0 12 83 c7 12 89 bb a4 00 00 00 89 83 98 00 00 00 8b 55 e4 8b 4d e8 8b 84 f2 18 01 00 00 c1 e9 02 <8b> b0 a4 00 00 00 f3 a5 8b 4d e8 83 e1 03 74 02 f3 a4 83 7b 54 
>EIP: [<f8fa297c>] tulip_interrupt+0x2aa/0xbba [tulip] SS:ESP 0068:c31c8a90
>---[ end trace c86db896f427fb7e ]---

Actions: View

Attachments on bug 431038: 293570 | 293571 | 293572 | 293574 | 295760 | 298138 | 299658