From owner-freebsd-bugs@FreeBSD.ORG Tue Feb 1 11:00:17 2011 Return-Path: Delivered-To: freebsd-bugs@hub.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id CBF5B1065672 for ; Tue, 1 Feb 2011 11:00:17 +0000 (UTC) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [IPv6:2001:4f8:fff6::28]) by mx1.freebsd.org (Postfix) with ESMTP id A31BE8FC12 for ; Tue, 1 Feb 2011 11:00:17 +0000 (UTC) Received: from freefall.freebsd.org (localhost [127.0.0.1]) by freefall.freebsd.org (8.14.4/8.14.4) with ESMTP id p11B0HtC060569 for ; Tue, 1 Feb 2011 11:00:17 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.14.4/8.14.4/Submit) id p11B0H16060555; Tue, 1 Feb 2011 11:00:17 GMT (envelope-from gnats) Resent-Date: Tue, 1 Feb 2011 11:00:17 GMT Resent-Message-Id: <201102011100.p11B0H16060555@freefall.freebsd.org> Resent-From: FreeBSD-gnats-submit@FreeBSD.org (GNATS Filer) Resent-To: freebsd-bugs@FreeBSD.org Resent-Reply-To: FreeBSD-gnats-submit@FreeBSD.org, Robert Nagy Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 2FC1B1065672 for ; Tue, 1 Feb 2011 10:55:24 +0000 (UTC) (envelope-from nobody@FreeBSD.org) Received: from red.freebsd.org (red.freebsd.org [IPv6:2001:4f8:fff6::22]) by mx1.freebsd.org (Postfix) with ESMTP id 1D6E48FC23 for ; Tue, 1 Feb 2011 10:55:24 +0000 (UTC) Received: from red.freebsd.org (localhost [127.0.0.1]) by red.freebsd.org (8.14.4/8.14.4) with ESMTP id p11AtNil011066 for ; Tue, 1 Feb 2011 10:55:23 GMT (envelope-from nobody@red.freebsd.org) Received: (from nobody@localhost) by red.freebsd.org (8.14.4/8.14.4/Submit) id p11AtN6b011065; Tue, 1 Feb 2011 10:55:23 GMT (envelope-from nobody) Message-Id: <201102011055.p11AtN6b011065@red.freebsd.org> Date: Tue, 1 Feb 2011 10:55:23 GMT From: Robert Nagy To: freebsd-gnats-submit@FreeBSD.org X-Send-Pr-Version: www-3.1 Cc: Subject: kern/154432: run_interrupt_driven_hooks: still waiting after 60-300 seconds for xpt_config X-BeenThere: freebsd-bugs@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Bug reports List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 01 Feb 2011 11:00:17 -0000 >Number: 154432 >Category: kern >Synopsis: run_interrupt_driven_hooks: still waiting after 60-300 seconds for xpt_config >Confidential: no >Severity: serious >Priority: high >Responsible: freebsd-bugs >State: open >Quarter: >Keywords: >Date-Required: >Class: sw-bug >Submitter-Id: current-users >Arrival-Date: Tue Feb 01 11:00:17 UTC 2011 >Closed-Date: >Last-Modified: >Originator: Robert Nagy >Release: 8.2-PRERELEASE >Organization: >Environment: FreeBSD siva.hu 8.2-PRERELEASE FreeBSD 8.2-PRERELEASE #4: Tue Feb 1 10:49:21 CET 2011 root@siva.hu:/usr/obj/usr/src/sys/GENERIC amd64 >Description: There is a 900 second wait loop in the kernel while booting. run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 120 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 180 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 240 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 300 seconds for xpt_config -- Copyright (c) 1992-2011 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 8.2-PRERELEASE #4: Tue Feb 1 10:49:21 CET 2011 root@siva.hu:/usr/obj/usr/src/sys/GENERIC amd64 Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: Intel(R) Xeon(R) CPU E5410 @ 2.33GHz (2341.48-MHz K8-class CPU) Origin = "GenuineIntel" Id = 0x10676 Family = 6 Model = 17 Stepping = 6 Features=0xbfebfbff Features2=0xce3bd AMD Features=0x20100800 AMD Features2=0x1 TSC: P-state invariant real memory = 17179869184 (16384 MB) avail memory = 16534269952 (15768 MB) ACPI APIC Table: FreeBSD/SMP: Multiprocessor System Detected: 8 CPUs FreeBSD/SMP: 2 package(s) x 4 core(s) cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 1 cpu2 (AP): APIC ID: 2 cpu3 (AP): APIC ID: 3 cpu4 (AP): APIC ID: 4 cpu5 (AP): APIC ID: 5 cpu6 (AP): APIC ID: 6 cpu7 (AP): APIC ID: 7 ioapic0 irqs 0-23 on motherboard ioapic1 irqs 24-47 on motherboard lapic0: Forcing LINT1 to edge trigger kbd1 at kbdmux0 acpi0: on motherboard acpi0: [ITHREAD] acpi0: Power Button (fixed) acpi0: reservation of 0, a0000 (3) failed Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0 cpu0: on acpi0 cpu1: on acpi0 cpu2: on acpi0 cpu3: on acpi0 cpu4: on acpi0 cpu5: on acpi0 cpu6: on acpi0 cpu7: on acpi0 acpi_button0: on acpi0 pcib0: port 0xca2,0xca3,0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: at device 2.0 on pci0 pci1: on pcib1 pcib2: irq 16 at device 0.0 on pci1 pci2: on pcib2 pcib3: irq 16 at device 0.0 on pci2 pci3: on pcib3 isp0: port 0x4400-0x44ff mem 0xf8a00000-0xf8a03fff irq 16 at device 0.0 on pci3 isp0: [ITHREAD] isp1: port 0x4000-0x40ff mem 0xf8a04000-0xf8a07fff irq 17 at device 0.1 on pci3 isp1: [ITHREAD] pcib4: irq 17 at device 1.0 on pci2 pci4: on pcib4 mfi0: port 0x3000-0x30ff mem 0xf8900000-0xf893ffff,0xf8940000-0xf897ffff irq 17 at device 0.0 on pci4 mfi0: Megaraid SAS driver Ver 3.00 mfi0: 6722 (349874230s/0x0020/info) - Shutdown command received from host mfi0: 6723 (boot + 3s/0x0020/info) - Firmware initialization started (PCI ID 0060/1000/1010/8086) mfi0: 6724 (boot + 3s/0x0020/info) - Firmware version 1.10.02-0180 mfi0: 6725 (boot + 4s/0x0008/info) - Battery temperature is normal mfi0: 6726 (boot + 4s/0x0008/info) - Battery Present mfi0: 6727 (boot + 10s/0x0002/info) - Inserted: PD 08(e0/s8) mfi0: 6728 (boot + 10s/0x0002/info) - Inserted: PD 08(e0/s8) Info: enclPd=ffff, scsiType=0, portMap=00, sasAddr=0a241a02538e6c3d,0000000000000000 mfi0: 6729 (boot + 10s/0x0002/info) - Inserted: PD 09(e0/s9) mfi0: 6730 (boot + 10s/0x0002/info) - Inserted: PD 09(e0/s9) Info: enclPd=ffff, scsiType=0, portMap=01, sasAddr=0a241a0253747235,0000000000000000 mfi0: 6731 (boot + 10s/0x0002/info) - Inserted: PD 0a(e0/s10) mfi0: 6732 (boot + 10s/0x0002/info) - Inserted: PD 0a(e0/s10) Info: enclPd=ffff, scsiType=0, portMap=02, sasAddr=0a241a0253736839,0000000000000000 mfi0: 6733 (boot + 10s/0x0042/info) - Global Hot Spare created on PD 08(e0/s8) (global) mfi0: 6734 (boot + 10s/0x0002/info) - State change on PD 08(e0/s8) from UNCONFIGURED_GOOD(0) to HOT SPARE(2) mfi0: 6735 (349874276s/0x0020/info) - Time established as 02/01/11 11:17:56; (42 seconds since power on) mfi0: 6736 (boot + 3s/0x0020/info) - Firmware initialization started (PCI ID 0060/1000/1010/8086) mfi0: 6737 (boot + 3s/0x0020/info) - Firmware version 1.10.02-0180 mfi0: 6738 (boot + 4s/0x0008/info) - Battery temperature is normal mfi0: 6739 (boot + 4s/0x0008/info) - Battery Present mfi0: 6740 (boot + 10s/0x0002/info) - Inserted: PD 08(e0/s8) mfi0: 6741 (boot + 10s/0x0002/info) - Inserted: PD 08(e0/s8) Info: enclPd=ffff, scsiType=0, portMap=00, sasAddr=0a241a02538e6c3d,0000000000000000 mfi0: 6742 (boot + 10s/0x0002/info) - Inserted: PD 09(e0/s9) mfi0: 6743 (boot + 10s/0x0002/info) - Inserted: PD 09(e0/s9) Info: enclPd=ffff, scsiType=0, portMap=01, sasAddr=0a241a0253747235,0000000000000000 mfi0: 6744 (boot + 10s/0x0002/info) - Inserted: PD 0a(e0/s10) mfi0: 6745 (boot + 10s/0x0002/info) - Inserted: PD 0a(e0/s10) Info: enclPd=ffff, scsiType=0, portMap=02, sasAddr=0a241a0253736839,0000000000000000 mfi0: 6746 (boot + 10s/0x0042/info) - Global Hot Spare created on PD 08(e0/s8) (global) mfi0: 6747 (boot + 10s/0x0002/info) - State change on PD 08(e0/s8) from UNCONFIGURED_GOOD(0) to HOT SPARE(2) mfi0: [ITHREAD] pcib5: irq 18 at device 2.0 on pci2 pci5: on pcib5 em0: port 0x2020-0x203f mem 0xf8820000-0xf883ffff,0xf8400000-0xf87fffff irq 18 at device 0.0 on pci5 em0: Using an MSI interrupt em0: [FILTER] em0: Ethernet address: 00:15:17:52:dd:48 em1: port 0x2000-0x201f mem 0xf8800000-0xf881ffff,0xf8000000-0xf83fffff irq 19 at device 0.1 on pci5 em1: Using an MSI interrupt em1: [FILTER] em1: Ethernet address: 00:15:17:52:dd:49 pcib6: at device 0.3 on pci1 pci6: on pcib6 pcib7: at device 3.0 on pci0 pci7: on pcib7 pci0: at device 8.0 (no driver attached) pcib8: irq 16 at device 28.0 on pci0 pci8: on pcib8 uhci0: port 0x5080-0x509f irq 23 at device 29.0 on pci0 uhci0: [ITHREAD] uhci0: LegSup = 0x2f00 usbus0: on uhci0 uhci1: port 0x5060-0x507f irq 22 at device 29.1 on pci0 uhci1: [ITHREAD] uhci1: LegSup = 0x2f00 usbus1: on uhci1 uhci2: port 0x5040-0x505f irq 23 at device 29.2 on pci0 uhci2: [ITHREAD] uhci2: LegSup = 0x2f00 usbus2: on uhci2 uhci3: port 0x5020-0x503f irq 22 at device 29.3 on pci0 uhci3: [ITHREAD] uhci3: LegSup = 0x2f00 usbus3: on uhci3 ehci0: mem 0xf8d00400-0xf8d007ff irq 23 at device 29.7 on pci0 ehci0: [ITHREAD] usbus4: EHCI version 1.0 usbus4: on ehci0 pcib9: at device 30.0 on pci0 pci9: on pcib9 vgapci0: port 0x1000-0x10ff mem 0xf0000000-0xf7ffffff,0xf8c00000-0xf8c0ffff irq 17 at device 12.0 on pci9 isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x50b0-0x50bf irq 20 at device 31.1 on pci0 ata0: on atapci0 ata0: [ITHREAD] atapci1: port 0x50c8-0x50cf,0x50e4-0x50e7,0x50c0-0x50c7,0x50e0-0x50e3,0x50a0-0x50af mem 0xf8d00000-0xf8d003ff irq 20 at device 31.2 on pci0 atapci1: [ITHREAD] ata2: on atapci1 ata2: [ITHREAD] ata3: on atapci1 ata3: [ITHREAD] pci0: at device 31.3 (no driver attached) atrtc0: port 0x70-0x71,0x74-0x77 irq 8 on acpi0 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] psm0: irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: [ITHREAD] psm0: model IntelliMouse, device ID 3 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart0: [FILTER] uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0 uart1: [FILTER] acpi_hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 14318180 Hz quality 900 orm0: at iomem 0xc0000-0xc8fff,0xd1000-0xd1fff,0xd2000-0xd2fff on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 ppc0: cannot reserve I/O port range est0: on cpu0 p4tcc0: on cpu0 est1: on cpu1 p4tcc1: on cpu1 est2: on cpu2 p4tcc2: on cpu2 est3: on cpu3 p4tcc3: on cpu3 est4: on cpu4 p4tcc4: on cpu4 est5: on cpu5 p4tcc5: on cpu5 est6: on cpu6 p4tcc6: on cpu6 est7: on cpu7 p4tcc7: on cpu7 RTC BIOS diagnostic error 5 Timecounters tick every 1.000 msec mfi0: 6748 (349874359s/0x0020/info) - Time established as 02/01/11 11:19:19; (42 seconds since power on) usbus0: 12Mbps Full Speed USB v1.0 usbus1: 12Mbps Full Speed USB v1.0 usbus2: 12Mbps Full Speed USB v1.0 usbus3: 12Mbps Full Speed USB v1.0 usbus4: 480Mbps High Speed USB v2.0 ugen0.1: at usbus0 uhub0: on usbus0 ugen1.1: at usbus1 uhub1: on usbus1 ugen2.1: at usbus2 uhub2: on usbus2 ugen3.1: at usbus3 uhub3: on usbus3 ugen4.1: at usbus4 uhub4: on usbus4 uhub0: 2 ports with 2 removable, self powered uhub1: 2 ports with 2 removable, self powered uhub2: 2 ports with 2 removable, self powered uhub3: 2 ports with 2 removable, self powered mfid0: on mfi0 mfid0: 237464MB (486326272 sectors) RAID volume '' is optimal uhub4: 8 ports with 8 removable, self powered ugen0.2: at usbus0 umass0: on usbus0 umass0: SCSI over Bulk-Only; quirks = 0x0000 ugen2.2: at usbus2 umass1: on usbus2 umass1: UFI over CBI; quirks = 0x0000 umass0:2:0:-1: Attached to scbus2 (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:umass-sim0:0:0:0): CAM status: SCSI Status Error (probe0:umass-sim0:0:0:0): SCSI status: Check Condition (probe0:umass-sim0:0:0:0): SCSI sense: NOT READY asc:3a,0 (Medium not present) umass1:3:1:-1: Attached to scbus3 (probe0:umass-sim1:1:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:umass-sim1:1:0:0): CAM status: SCSI Status Error (probe0:umass-sim1:1:0:0): SCSI status: Check Condition (probe0:umass-sim1:1:0:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred) (probe0:umass-sim1:1:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:umass-sim1:1:0:0): CAM status: SCSI Status Error (probe0:umass-sim1:1:0:0): SCSI status: Check Condition (probe0:umass-sim1:1:0:0): SCSI sense: NOT READY asc:3a,0 (Medium not present) run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 120 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 180 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 240 seconds for xpt_config run_interrupt_driven_hooks: still waiting after 300 seconds for xpt_config ses0 at isp0 bus 0 scbus0 target 0 lun 5 ses0: Fixed Enclosure Services SCSI-4 device ses0: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 ses0: Command Queueing enabled ses0: SCSI-3 SES Device ses1 at isp1 bus 0 scbus1 target 0 lun 0 ses1: Fixed Enclosure Services SCSI-4 device ses1: 400.000MB/s transfers WWNN 0x200000d02317bed9 WWPN 0x210000d02317bed9 PortID 0x10023 ses1: Command Queueing enabled ses1: SCSI-3 SES Device da0 at isp0 bus 0 scbus0 target 0 lun 0 da0: Fixed Direct Access SCSI-4 device da0: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 da0: Command Queueing enabled da0: 953609MB (1952991232 512 byte sectors: 255H 63S/T 121568C) da1 at isp0 bus 0 scbus0 target 0 lun 1 da1: Fixed Direct Access SCSI-4 device da1: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 da1: Command Queueing enabled da1: 15360MB (31457280 512 byte sectors: 255H 63S/T 1958C) da2 at isp0 bus 0 scbus0 target 0 lun 2 da2: Fixed Direct Access SCSI-4 device da2: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 da2: Command Queueing enabled da2: 15360MB (31457280 512 byte sectors: 255H 63S/T 1958C) da3 at isp0 bus 0 scbus0 target 0 lun 3 da3: Fixed Direct Access SCSI-4 device da3: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 da3: Command Queueing enabled da3: 199135MB (407828480 512 byte sectors: 255H 63S/T 25386C) da4 at isp0 bus 0 scbus0 target 0 lun 4 da4: Fixed Direct Access SCSI-4 device da4: 400.000MB/s transfers WWNN 0x200000d02307bed9 WWPN 0x210000d02307bed9 PortID 0x10025 da4: Command Queueing enabled da4: 199136MB (407830528 512 byte sectors: 255H 63S/T 25386C) lapic7: Forcing LINT1 to edge trigger cd0 at umass-sim0 bus 0 scbus2 target 0 lun 0 cd0: Removable CD-ROM SCSI-3 device cd0: 1.000MB/s transfers cd0: Attempt to query device size failed: NOT READY, Medium not presentSMP: AP CPU #7 Launched! lapic5: Forcing LINT1 to edge trigger SMP: AP CPU #5 Launched! lapic6: Forcing LINT1 to edge trigger SMP: AP CPU #6 Launched! lapic4: Forcing LINT1 to edge trigger SMP: AP CPU #4 Launched! lapic1: Forcing LINT1 to edge trigger SMP: AP CPU #1 Launched! lapic3: Forcing LINT1 to edge trigger SMP: AP CPU #3 Launched! lapic2: Forcing LINT1 to edge trigger SMP: AP CPU #2 Launched! da5 at umass-sim1 bus 1 scbus3 target 0 lun 0 da5: Removable Direct Access SCSI-0 device da5: 1.000MB/s transfers da5: Attempt to query device size failed: NOT READY, Medium not present GEOM: da0: partition 3 does not start on a track boundary. GEOM: da0: partition 3 does not end on a track boundary. GEOM: da0: partition 2 does not start on a track boundary. GEOM: da0: partition 2 does not end on a track boundary. GEOM: da0: partition 1 does not start on a track boundary. GEOM: da0: partition 1 does not end on a track boundary. Trying to mount root from ufs:/dev/mfid0s1a em0: link state changed to UP >How-To-Repeat: >Fix: >Release-Note: >Audit-Trail: >Unformatted: