From owner-freebsd-stable@FreeBSD.ORG Fri Jul 23 07:25:55 2010 Return-Path: Delivered-To: freebsd-stable@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id CAE131065677 for ; Fri, 23 Jul 2010 07:25:55 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from kabab.cs.huji.ac.il (kabab.cs.huji.ac.il [132.65.16.84]) by mx1.freebsd.org (Postfix) with ESMTP id 5BB7D8FC15 for ; Fri, 23 Jul 2010 07:25:55 +0000 (UTC) Received: from pampa.cs.huji.ac.il ([132.65.80.32]) by kabab.cs.huji.ac.il with esmtp id 1OcCdl-00079z-BZ; Fri, 23 Jul 2010 10:25:53 +0300 X-Mailer: exmh version 2.7.2 01/07/2005 with nmh-1.2 To: Jeremy Chadwick In-reply-to: <20100723064421.GA40830@icarus.home.lan> References: <20100723064421.GA40830@icarus.home.lan> Comments: In-reply-to Jeremy Chadwick message dated "Thu, 22 Jul 2010 23:44:22 -0700." Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Date: Fri, 23 Jul 2010 10:25:53 +0300 From: Daniel Braniss Message-ID: Cc: freebsd-stable@freebsd.org Subject: Re: latest 8.1 hangs on xpt_config X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 23 Jul 2010 07:25:55 -0000 > On Fri, Jul 23, 2010 at 09:35:55AM +0300, Daniel Braniss wrote: > > It seems that the latest changes (last 7 days) introduced this problem: > > ... > > 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 > > ... > > > > i'll try to hunt this down, but any help is welcome. > > Recent to semi-recent commits relevant to xpt that I can find The > problem might not be even in xpt though. Which xpt piece pertains to > you probably depends on your system setup/configuration. Dates/times > are in PDT/UTC-0700: > > -rw-r--r-- 1 root wheel 6037 1 Mar 22:48 /usr/src/sys/cam/cam_xpt_internal.h > -rw-r--r-- 1 root wheel 124773 9 May 10:19 /usr/src/sys/cam/cam_xpt.c > -rw-r--r-- 1 root wheel 72556 23 May 10:41 /usr/src/sys/cam/scsi/scsi_xpt.c > -rw-r--r-- 1 root wheel 56663 19 Jul 05:28 /usr/src/sys/cam/ata/ata_xpt.c > > http://www.freebsd.org/cgi/cvsweb.cgi/src/sys/cam/cam_xpt_internal.h > http://www.freebsd.org/cgi/cvsweb.cgi/src/sys/cam/cam_xpt.c > http://www.freebsd.org/cgi/cvsweb.cgi/src/sys/cam/scsi/scsi_xpt.c > http://www.freebsd.org/cgi/cvsweb.cgi/src/sys/cam/ata/ata_xpt.c thanks Jeremy, i'll try and make some sence of the changes. here is some more info: there are one disk and one dvd connected via SATA CPU: Intel(R) Core(TM) i5 CPU 660 @ 3.33GHz (3325.02-MHz K8-class CPU)^M Origin = "GenuineIntel" Id = 0x20652 Family = 6 Model = 25 Stepping = 2^M Features=0xbfebfbff^M Features2=0x298e3ff^M AMD Features=0x28100800^M AMD Features2=0x1^M ... atapci0: port 0xf0f0-0xf0f7,0xf0e0-0xf0e3,0xf0d0-0xf0d7,0xf0c0-0xf0c3,0xf0b0-0xf0bf irq 18 at device 22.2 on pci0^M atapci0: Reserved 0x10 bytes for rid 0x20 type 4 at 0xf0b0^M ioapic0: routing intpin 18 (PCI IRQ 18) to lapic 0 vector 49^M atapci0: [MPSAFE]^M atapci0: [ITHREAD]^M ata2: on atapci0^M atapci0: Reserved 0x8 bytes for rid 0x10 type 4 at 0xf0f0^M atapci0: Reserved 0x4 bytes for rid 0x14 type 4 at 0xf0e0^M ata2: reset tp1 mask=03 ostat0=7f ostat1=7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: stat1=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata2: reset tp2 stat0=ff stat1=ff devices=0x0^M ata2: [MPSAFE]^M ata2: [ITHREAD]^M ata3: on atapci0^M atapci0: Reserved 0x8 bytes for rid 0x18 type 4 at 0xf0d0^M atapci0: Reserved 0x4 bytes for rid 0x1c type 4 at 0xf0c0^M ata3: reset tp1 mask=03 ostat0=7f ostat1=7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat0=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: stat1=0x7f err=0x7f lsb=0x7f msb=0x7f^M ata3: reset tp2 stat0=ff stat1=ff devices=0x0^M ata3: [MPSAFE]^M ata3: [ITHREAD]^M ... ahci0: port 0xf090-0xf097,0xf080-0xf083,0xf070-0xf077,0xf060-0xf063,0xf020-0xf03f mem 0xfe425000-0xfe4257ff irq 19 at device 31.2 on pci0^M ahci0: Reserved 0x800 bytes for rid 0x24 type 3 at 0xfe425000^M ahci0: attempting to allocate 1 MSI vectors (1 supported)^M msi: routing MSI IRQ 257 to local APIC 0 vector 53^M ahci0: using IRQ 257 for MSI^M ahci0: [MPSAFE]^M ahci0: [ITHREAD]^M ahci0: AHCI v1.30 with 6 3Gbps ports, Port Multiplier not supported^M ahci0: Caps: 64bit NCQ SNTF MPS ALP AL CLO 3Gbps PMD SSC PSC 32cmd EM eSATA 6ports^M ahci0: Caps2: APST^M ahci0: EM Caps: ALHD XMT SMB LED^M ahcich0: at channel 0 on ahci0^M ahcich0: [MPSAFE]^M ahcich0: [ITHREAD]^M ahcich0: Caps:^M ahcich1: at channel 1 on ahci0^M ahcich1: [MPSAFE]^M ahcich1: [ITHREAD]^M ahcich1: Caps:^M ahcich2: at channel 4 on ahci0^M ahcich2: [MPSAFE]^M ahcich2: [ITHREAD]^M ahcich2: Caps: HPCP ESP^M ... ata2: Identifying devices: 00000000^M ata2: New devices: 00000000^M ata3: Identifying devices: 00000000^M ata3: New devices: 00000000^M usbus0: 480Mbps High Speed USB v2.0^M usbus1: 480Mbps High Speed USB v2.0^M ahcich0: AHCI reset...^M ugen0.1: at usbus0^M uhub0: on usbus0^M ugen1.1: at usbus1^M uhub1: on usbus1^M ahcich0: SATA connect time=0ms status=00000123^M ahcich0: ready wait time=0ms^M ahcich0: AHCI reset done: device found^M (aprobe0:ahcich0:0:0:0): SIGNATURE: 0000^M ...