Ticket #31392

vm1のFreeBSDを再起動すると、AHCIの初期化に失敗する

Open Date: 2013-05-19 21:54 Last Update: 2014-09-23 22:15

Reporter:
Owner:
Type:
Status:
Closed
Component:
(None)
MileStone:
(None)
Priority:
5 - Medium
Severity:
5 - Medium
Resolution:
Fixed
File:
None

Details

vm1のFreeBSDを再起動するとAHCIの初期化に失敗し、ストールします。

ahcich0: AHCI reset...^M                                                                                                                                     
ahcich0: SATA connect time=0us status=00000123^M                                                                                                             
ahcich0: AHCI reset: device found^M                                                                                                                          
ahcich1: AHCI reset...^M                                                                                                                                     
ahcich1: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich1: AHCI reset: device not found^M                                                                                                                      
ahcich2: AHCI reset...^M                                                                                                                                     
ahcich2: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich2: AHCI reset: device not found^M                                                                                                                      
ahcich3: AHCI reset...^M                                                                                                                                     
ahcich3: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich3: AHCI reset: device not found^M                                                                                                                      
ahcich4: AHCI reset...^M                                                                                                                                     
ahcich4: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich4: AHCI reset: device not found^M                                                                                                                      
ahcich5: AHCI reset...^M                                                                                                                                     
ahcich5: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich5: AHCI reset: device not found^M                                                                                                                      
ahcich6: AHCI reset...^M                                                                                                                                     
ahcich6: SATA connect timeout time=10000us status=00000000^M                                                                                                 
ahcich6: AHCI reset: device not found^M                                                                                                                      
ahcich7: AHCI reset...^M                                                                                                                                     
ahcich7: SATA connect time=0us status=00000113^M                                                                                                             
ahcich7: AHCI reset: device found^M                                                                                                                          
ahcich7: AHCI reset: device ready after 0ms^M                                                                                                                
(aprobe1:ahcich7:0:0:0): SIGNATURE: eb14^M  

障害発生リビジョン: r92

Ticket History (3/20 Histories)

2013-05-19 21:54 Updated by: yuichi_xy
  • New Ticket "vm1のFreeBSDを再起動するとAHCIの初期化に失敗する" created
2013-05-19 21:56 Updated by: yuichi_xy
  • Summary Updated
2013-08-08 22:34 Updated by: yuichi_xy
Comment

FreeBSD 9.1-RELEASEをvm1で起動し、再起動してみたところ、起動中にストールしました。

ahci0: <Marvell 88SE912x AHCI SATA controller> port 0xcf00-0xcf07,0xce00-0xce03,0xcd00-0xcd07,0xcc00-0xcc03,0xcb00-0xcb0f mem 0xfbdff000-0xfbdff7ff at device 0.0 on pci4
ahci0: AHCI v1.20 with 8 6Gbps ports, Port Multiplier not supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
ahcich2: <AHCI channel> at channel 2 on ahci0
ahcich3: <AHCI channel> at channel 3 on ahci0
ahcich4: <AHCI channel> at channel 4 on ahci0
ahcich5: <AHCI channel> at channel 5 on ahci0
ahcich6: <AHCI channel> at channel 6 on ahci0
ahcich7: <AHCI channel> at channel 7 on ahci0
pcib3: <PCI-PCI bridge> at device 28.3 on pci0
pci5: <PCI bus> on pcib3
xhci0: <NEC uPD720200 USB 3.0 controller> mem 0xfbcfe000-0xfbcfffff at device 0.0 on pci5
xhci0: 32 byte context size.
usbus0 on xhci0
pcib4: <PCI-PCI bridge> at device 28.4 on pci0
pci6: <PCI bus> on pcib4
vgapci0: <VGA-compatible display> port 0xbf00-0xbf7f mem 0xf8000000-0xf8ffffff,0xe0000000-0xefffffff,0xf6000000-0xf7ffffff at device 0.0 on pci6
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <8250 or 16450 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
ctl: CAM Target Layer loaded
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 16
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 16
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
usbus0: 5.0Gbps Super Speed USB v3.0
2013-08-25 20:34 Updated by: yuichi_xy
Comment

r107 でも再現しました。

ahci0: <Marvell 88SE912x AHCI SATA controller> port 0xcf00-0xcf07,0xce00-0xce03,0xcd00-0xcd07,0xcc00-0xcc03,0xcb00-0xcb0f mem 0xfbdff000-0xfbdff7ff at device 0.0 on pci4
ahci0: AHCI v1.20 with 8 6Gbps ports, Port Multiplier not supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
ahcich2: <AHCI channel> at channel 2 on ahci0
ahcich3: <AHCI channel> at channel 3 on ahci0
ahcich4: <AHCI channel> at channel 4 on ahci0
ahcich5: <AHCI channel> at channel 5 on ahci0
ahcich6: <AHCI channel> at channel 6 on ahci0
ahcich7: <AHCI channel> at channel 7 on ahci0
pcib3: <PCI-PCI bridge> at device 28.3 on pci0
pci5: <PCI bus> on pcib3
xhci0: <NEC uPD720200 USB 3.0 controller> mem 0xfbcfe000-0xfbcfffff at device 0.0 on pci5
xhci0: 32 byte context size.
usbus0 on xhci0
pcib4: <PCI-PCI bridge> at device 28.4 on pci0
pci6: <PCI bus> on pcib4
vgapci0: <VGA-compatible display> port 0xbf00-0xbf7f mem 0xf8000000-0xf8ffffff,0xe0000000-0xefffffff,0xf6000000-0xf7ffffff at device 0.0 on pci6
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <8250 or 16450 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
ctl: CAM Target Layer loaded
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 16
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 16
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
usbus0: 5.0Gbps Super Speed USB v3.0
2013-08-25 22:30 Updated by: yuichi_xy
Comment

IvyBridgeマシンでは、再現せず、vm1のFreeBSDの再起動に成功しました。

2013-08-25 22:36 Updated by: yuichi_xy
Comment

yuichi_xy への返信

IvyBridgeマシンでは、再現せず、vm1のFreeBSDの再起動に成功しました。

間違いでした。 IvyBridgeマシンでも、問題が起きました。

ahci0: <ASMedia ASM1061 AHCI SATA controller> port 0xd050-0xd057,0xd040-0xd043,0xd030-0xd037,0xd020-0xd023,0xd000-0xd01f mem 0xf7c00000-0xf7c001ff at device 0.0 on pci5
ahci0: AHCI v1.20 with 2 6Gbps ports, Port Multiplier supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <8250 or 16450 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
ctl: CAM Target Layer loaded
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 21f200002000
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 21f200002000
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
SMP: AP CPU #1 Launched!
Trying to mount root from ufs:/dev/ada0p2 [rw]...
mountroot: waiting for device /dev/ada0p2 ...
Mounting from ufs:/dev/ada0p2 failed with error 19.

Loader variables:
  vfs.root.mountfrom=ufs:/dev/ada0p2
  vfs.root.mountfrom.options=rw

Manual root filesystem specification:
  <fstype>:<device> [options]
      Mount <device> using filesystem <fstype>
      and with the specified (optional) option list.

    eg. ufs:/dev/da0s1a
        zfs:tank
        cd9660:/dev/acd0 ro
          (which is equivalent to: mount -t cd9660 -o ro /dev/acd0 /)

  ?               List valid disk boot devices
  .               Yield 1 second (for background tasks)
  <empty line>    Abort manual input

mountroot> 
2013-08-31 23:18 Updated by: yuichi_xy
Comment

yuichi_xy への返信

yuichi_xy への返信

IvyBridgeマシンでは、再現せず、vm1のFreeBSDの再起動に成功しました。

間違いでした。 IvyBridgeマシンでも、問題が起きました。

r110 でも AHCI の初期化に失敗(タイムアウト)しました。

ahcich1: <AHCI channel> at channel 1 on ahci0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <8250 or 16450 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
ctl: CAM Target Layer loaded
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 21f200002000
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 21f200002000
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
SMP: AP CPU #1 Launched!
Trying to mount root from ufs:/dev/ada0p2 [rw]...
mountroot: waiting for device /dev/ada0p2 ...
Mounting from ufs:/dev/ada0p2 failed with error 19.

Loader variables:
  vfs.root.mountfrom=ufs:/dev/ada0p2
  vfs.root.mountfrom.options=rw

Manual root filesystem specification:
  <fstype>:<device> [options]
      Mount <device> using filesystem <fstype>
      and with the specified (optional) option list.

    eg. ufs:/dev/da0s1a
        zfs:tank
        cd9660:/dev/acd0 ro
          (which is equivalent to: mount -t cd9660 -o ro /dev/acd0 /)

  ?               List valid disk boot devices
  .               Yield 1 second (for background tasks)
  <empty line>    Abort manual input

mountroot> 
2013-09-15 21:17 Updated by: yuichi_xy
  • Owner Update from (None) to yuichi_xy
2013-11-03 13:07 Updated by: yuichi_xy
Comment

r116とFree BSD 9.2でも、再現しました。

ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
SMP: AP CPU #1 Launched!
Trying to mount root from ufs:/dev/ada0p2 [rw]...
mountroot: waiting for device /dev/ada0p2 ...
Mounting from ufs:/dev/ada0p2 failed with error 19.

Loader variables:
  vfs.root.mountfrom=ufs:/dev/ada0p2
  vfs.root.mountfrom.options=rw

Manual root filesystem specification:
  <fstype>:<device> [options]
      Mount <device> using filesystem <fstype>
      and with the specified (optional) option list.

    eg. ufs:/dev/da0s1a
        zfs:tank
        cd9660:/dev/acd0 ro
          (which is equivalent to: mount -t cd9660 -o ro /dev/acd0 /)

  ?               List valid disk boot devices
  .               Yield 1 second (for background tasks)
  <empty line>    Abort manual input

mountroot> 
2013-11-23 20:33 Updated by: yuichi_xy
Comment

HBAをリセットしても効果がありませんでした。

Index: bios/src/config.h                                                        ===================================================================
--- bios/src/config.h   (リビジョン 123)
+++ bios/src/config.h   (作業コピー)
@@ -24,6 +24,7 @@
 #define CONFIG_GET_ACPI_PM_BASE_FROM_RTC 1
 #define CONFIG_DISABLE_APM_PORT 1
 #define CONFIG_SCREEN_AND_DEBUG 0
+#define CONFIG_AHCI_HBA_RESET 1
 
 // Maximum number of map entries in the e820 map
 #define BUILD_MAX_E820 32
Index: bios/src/ahci.c
===================================================================
--- bios/src/ahci.c     (リビジョン 123)
+++ bios/src/ahci.c     (作業コピー)
@@ -597,9 +597,7 @@
     struct ahci_port_s *port;
     u16 bdf = pci->bdf;
     u32 val, pnr, max;
-#ifdef AHCI_HBA_RESET
     u64 end;
-#endif
 
     if (!ctrl) {
         warn_noalloc();
@@ -624,20 +622,20 @@
     pci_config_maskw(bdf, PCI_COMMAND, 0,
                      PCI_COMMAND_IO | PCI_COMMAND_MEMORY | PCI_COMMAND_MASTER);
 
-#if AHCI_HBA_RESET
-    /* HBA Reset */
-    val = ahci_ctrl_readl(ctrl, HOST_CTL);
-    ahci_ctrl_writel(ctrl, HOST_CTL, val | HOST_CTL_AHCI_EN |
-                     HOST_CTL_RESET);
-    end = calc_future_tsc(AHCI_HBA_RESET_TIMEOUT);
-    while (ahci_ctrl_readl(ctrl, HOST_CTL) & HOST_CTL_AHCI_EN) {
-        if (check_tsc(end)) {
-            warn_timeout();
-            return;
+    if (CONFIG_AHCI_HBA_RESET) {
+        /* HBA Reset */
+        val = ahci_ctrl_readl(ctrl, HOST_CTL);
+        ahci_ctrl_writel(ctrl, HOST_CTL, val | HOST_CTL_AHCI_EN |
+                         HOST_CTL_RESET);
+        end = calc_future_tsc(AHCI_HBA_RESET_TIMEOUT);
+        while (ahci_ctrl_readl(ctrl, HOST_CTL) & HOST_CTL_AHCI_EN) {
+            if (check_tsc(end)) {
+                warn_timeout();
+                return;
+            }
+            yield();
         }
-        yield();
     }
-#endif
 
     /* Enable AHCI mode */
     val = ahci_ctrl_readl(ctrl, HOST_CTL);
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
ahcich1: AHCI reset...
ahcich1: SATA connect time=11600us status=00000123
ahcich1: AHCI reset: device found
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
ahcich1: AHCI reset: device ready after 100ms
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 0004c017
ahcich1: AHCI reset...
ahcich1: SATA connect time=8900us status=00000123
ahcich1: AHCI reset: device found
ahcich1: AHCI reset: device ready after 0ms
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
SMP: AP CPU #1 Launched!
cpu1 AP:
     ID: 0x06000000   VER: 0x01060015 LDR: 0x00000000 DFR: 0xffffffff
  lint0: 0x00010000 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff
  timer: 0x000100ef therm: 0x00010000 err: 0x000000f0 pmc: 0x00010400
   cmci: 0x000100f2
ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 6 vector 48
msi: Assigning MSI-X IRQ 257 to local APIC 6 vector 49
msi: Assigning MSI-X IRQ 265 to local APIC 6 vector 50
Timecounter "TSC" frequency 111659064 Hz quality -100
Trying to mount root from ufs:/dev/ada0p2 [rw]...
mountroot: waiting for device /dev/ada0p2 ...
Mounting from ufs:/dev/ada0p2 failed with error 19.
2013-11-23 20:55 Updated by: yuichi_xy
Comment

HOST_CTL_AHCI_ENビットが1の間待っていた処理を、HOST_CTL_RESETビットに変えてみたが、効果はなかった。

Index: bios/src/config.h
===================================================================
--- bios/src/config.h   (リビジョン 123)
+++ bios/src/config.h   (作業コピー)
@@ -24,6 +24,7 @@
 #define CONFIG_GET_ACPI_PM_BASE_FROM_RTC 1
 #define CONFIG_DISABLE_APM_PORT 1
 #define CONFIG_SCREEN_AND_DEBUG 0
+#define CONFIG_AHCI_HBA_RESET 1
 
 // Maximum number of map entries in the e820 map
 #define BUILD_MAX_E820 32
Index: bios/src/ahci.c
===================================================================
--- bios/src/ahci.c     (リビジョン 123)
+++ bios/src/ahci.c     (作業コピー)
@@ -597,9 +597,7 @@
     struct ahci_port_s *port;
     u16 bdf = pci->bdf;
     u32 val, pnr, max;
-#ifdef AHCI_HBA_RESET
     u64 end;
-#endif
 
     if (!ctrl) {
         warn_noalloc();
@@ -624,20 +622,21 @@
     pci_config_maskw(bdf, PCI_COMMAND, 0,
                      PCI_COMMAND_IO | PCI_COMMAND_MEMORY | PCI_COMMAND_MASTER);
 
-#if AHCI_HBA_RESET
-    /* HBA Reset */
-    val = ahci_ctrl_readl(ctrl, HOST_CTL);
-    ahci_ctrl_writel(ctrl, HOST_CTL, val | HOST_CTL_AHCI_EN |
-                     HOST_CTL_RESET);
-    end = calc_future_tsc(AHCI_HBA_RESET_TIMEOUT);
-    while (ahci_ctrl_readl(ctrl, HOST_CTL) & HOST_CTL_AHCI_EN) {
-        if (check_tsc(end)) {
-            warn_timeout();
-            return;
+    if (CONFIG_AHCI_HBA_RESET) {
+        /* HBA Reset */
+        dprintf(1, "AHCI HBA Reset\n");
+        val = ahci_ctrl_readl(ctrl, HOST_CTL);
+        ahci_ctrl_writel(ctrl, HOST_CTL, val | HOST_CTL_AHCI_EN |
+                         HOST_CTL_RESET);
+        end = calc_future_tsc(AHCI_HBA_RESET_TIMEOUT);
+        while (ahci_ctrl_readl(ctrl, HOST_CTL) & HOST_CTL_RESET) {
+            if (check_tsc(end)) {
+                warn_timeout();
+                return;
+            }
+            yield();
         }
-        yield();
     }
-#endif
 
     /* Enable AHCI mode */
     val = ahci_ctrl_readl(ctrl, HOST_CTL);
2013-11-23 22:34 Updated by: yuichi_xy
Comment

r127で、AHCI HBA resetを実行するコードをコミットしました。

2013-11-23 22:35 Updated by: yuichi_xy
Comment

逆に、AHCI HBA resetとSecondary Bus Resetを無効化してみましたが、効果がありませんでした。

Index: bios/src/config.h
===================================================================
--- bios/src/config.h   (リビジョン 127)
+++ bios/src/config.h   (作業コピー)
@@ -24,7 +24,7 @@
 #define CONFIG_GET_ACPI_PM_BASE_FROM_RTC 1
 #define CONFIG_DISABLE_APM_PORT 1
 #define CONFIG_SCREEN_AND_DEBUG 0
-#define CONFIG_AHCI_HBA_RESET 1
+#define CONFIG_AHCI_HBA_RESET 0
 
 // Maximum number of map entries in the e820 map
 #define BUILD_MAX_E820 32
Index: vmm/io/pci_assign.c
===================================================================
--- vmm/io/pci_assign.c (リビジョン 123)
+++ vmm/io/pci_assign.c (作業コピー)
@@ -490,6 +490,7 @@
        return 0;
 }
 
+#if 0
 static void
 pci_reprogram_device(struct pci_device *dev)
 {
@@ -563,3 +564,4 @@
 }
 
 BUS_RESETVM(pci_reset_assigned_device);
+#endif
2013-12-28 17:04 Updated by: yuichi_xy
Comment

yuichi_xy への返信

r127で、AHCI HBA resetを実行するコードをコミットしました。

#32658 の問題が発生したので、r141で、AHCI HBA resetを無効化しました。

2013-12-28 17:07 Updated by: yuichi_xy
Comment

デバイスを一旦D3状態に遷移させてD0に復帰させるパッチを作ってみましたが、効果はありませんでした。 パッチは #32660 に添付しました。

Reset vm1
Reset cpu 0x6
Reset cpu 0x4
Execute secondary bus reset on 00:01.0
Execute secondary bus reset on 00:1c.5
Execute secondary bus reset on 00:1c.7
Reset 01:00.0 PM cap 0x60
Reset 05:00.0 PM cap 0x40
Reset 06:00.0 PM cap 0x78
Reprogram 01:00.0
Reprogram 05:00.0
Reprogram 06:00.0

...

ahci0: AHCI v1.20 with 2 6Gbps ports, Port Multiplier supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <8250 or 16450 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 214e00002000
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 214e00002000
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
usbus0: 5.0Gbps Super Speed USB v3.0
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
uhub0: 8 ports with 8 removable, self powered
xhci_do_command: Command timeout!
usb_alloc_device: device init 2 failed (USB_ERR_TIMEOUT, ignored)
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
xhci_do_command: Command timeout!
usb_alloc_device: device init 2 failed (USB_ERR_TIMEOUT, ignored)
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
ahcich1: Timeout on slot 0 port 0
ahcich1: is 00000002 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cm
d 0004c017
(aprobe0:ahcich1:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich1:0:0:0): CAM status: Command timeout
(aprobe0:ahcich1:0:0:0): Error 5, Retry was blocked
2013-12-28 17:46 Updated by: yuichi_xy
Comment

yuichi_xy への返信

デバイスを一旦D3状態に遷移させてD0に復帰させるパッチを作ってみましたが、効果はありませんでした。 パッチは #32660 に添付しました。

ASmediaのAHCIは、NoSoftRstビットが立っているので、D3からの復帰時にリセットされるはずです。 しかし効果がないので、リセットの問題ではないかもしれません。

06:00.0 SATA controller: ASMedia Technology Inc. ASM1062 Serial ATA Controller (rev 01) (prog-if 01 [AHCI 1.0])
        Subsystem: ASRock Incorporation Device 0612
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
        Latency: 0, Cache Line Size: 64 bytes
        Interrupt: pin A routed to IRQ 41
        Region 0: I/O ports at c050 [size=8]
        Region 1: I/O ports at c040 [size=4]
        Region 2: I/O ports at c030 [size=8]
        Region 3: I/O ports at c020 [size=4]
        Region 4: I/O ports at c000 [size=32]
        Region 5: Memory at f7800000 (32-bit, non-prefetchable) [size=512]
        Capabilities: [50] MSI: Enable+ Count=1/1 Maskable- 64bit-
                Address: fee06000  Data: 4041
        Capabilities: [78] Power Management version 3
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)
                Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
2014-02-18 23:55 Updated by: yuichi_xy
Comment

r171 で拡張カードの AHCI を VM1 に割り当てても、再現しました。

Reset vm1
Reset cpu 0x6
Reset cpu 0x4
Execute secondary bus reset on 00:01.0
Execute secondary bus reset on 00:1c.0
Execute secondary bus reset on 00:1c.5
Reprogram 01:00.0
Reprogram 02:00.0
Reprogram 05:00.0
...
ahci0: <Marvell 88SE912x AHCI SATA controller> port 0xd040-0xd047,0xd030-0xd033,
0xd020-0xd027,0xd010-0xd013,0xd000-0xd00f mem 0xf7910000-0xf79107ff at device 0.
0 on pci2
ahci0: AHCI v1.20 with 8 6Gbps ports, Port Multiplier not supported
ahci0: quirks=0x800<ALTSIG>
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
ahcich2: <AHCI channel> at channel 2 on ahci0
ahcich3: <AHCI channel> at channel 3 on ahci0
ahcich4: <AHCI channel> at channel 4 on ahci0
ahcich5: <AHCI channel> at channel 5 on ahci0
ahcich6: <AHCI channel> at channel 6 on ahci0
ahcich7: <AHCI channel> at channel 7 on ahci0
pcib3: <PCI-PCI bridge> at device 28.5 on pci0
pci5: <PCI bus> on pcib3
re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F PCIe Gigabit Ethernet> port 0xc000-0xc0ff mem 0xf0004000-0xf0004fff,0xf0000000-0xf0003fff at device 0.0 on pci5
re0: Using 1 MSI-X message
re0: Chip rev. 0x2c800000
re0: MAC rev. 0x00000000
miibus0: <MII bus> on re0
rgephy0: <RTL8169S/8110S/8211 1000BASE-T media interface> PHY 1 on miibus0
rgephy0:  none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
re0: Ethernet address: bc:5f:f4:97:58:96
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
uart0: <Non-standard ns8250 class UART with FIFOs> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 214e00002000
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 214e00002000
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Timecounters tick every 10.000 msec
usbus0: 5.0Gbps Super Speed USB v3.0
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
uhub0: 8 ports with 8 removable, self powered
xhci_do_command: Command timeout!
usb_alloc_device: device init 2 failed (USB_ERR_TIMEOUT, ignored)
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
xhci_do_command: Command timeout!
usb_alloc_device: device init 2 failed (USB_ERR_TIMEOUT, ignored)
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
ahcich7: Timeout on slot 0 port 0
ahcich7: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
(aprobe1:ahcich7:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
(aprobe1:ahcich7:0:0:0): CAM status: Command timeout
(aprobe1:ahcich7:0:0:0): Error 5, Retry was blocked
ahcich0: Timeout on slot 0 port 0
ahcich0: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
(aprobe0:ahcich0:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich0:0:0:0): CAM status: Command timeout
(aprobe0:ahcich0:0:0:0): Error 5, Retry was blocked

2014-04-20 10:57 Updated by: yuichi_xy
Comment

r184とFreeBSD 10.0の組み合わせでも発生しました。

ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
ahcich1: AHCI reset...
ahcich1: SATA connect timeout time=10000us status=00000000
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ahcich1: AHCI reset: device not found
ahcich2: AHCI reset...
ahcich2: SATA connect timeout time=10000us status=00000000
ahcich2: AHCI reset: device not found
ahcich3: AHCI reset...
ahcich3: SATA connect timeout time=10000us status=00000000
ahcich3: AHCI reset: device not found
ahcich4: AHCI reset...
ahcich4: SATA connect timeout time=10000us status=00000000
ahcich4: AHCI reset: device not found
ahcich5: AHCI reset...
ahcich5: SATA connect timeout time=10000us status=00000000
ahcich5: AHCI reset: device not found
ahcich6: AHCI reset...
ahcich6: SATA connect timeout time=10000us status=00000000
ahcich6: AHCI reset: device not found
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
random: unblocking device.
ahcich0: AHCI reset: device ready after 300ms
uhub0: 8 ports with 8 removable, self powered
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
ahcich7: Timeout on slot 0 port 0
ahcich7: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
(aprobe1:ahcich7:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
(aprobe1:ahcich7:0:0:0): CAM status: Command timeout
(aprobe1:ahcich7:0:0:0): Error 5, Retry was blocked
ahcich0: Timeout on slot 0 port 0
ahcich0: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
(aprobe0:ahcich0:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich0:0:0:0): CAM status: Command timeout
(aprobe0:ahcich0:0:0:0): Error 5, Retry was blocked
ahcich0: AHCI reset: device ready after 300ms
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich7: Timeout on slot 0 port 0
ahcich7: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
(aprobe1:ahcich7:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
(aprobe1:ahcich7:0:0:0): CAM status: Command timeout
(aprobe1:ahcich7:0:0:0): Error 5, Retry was blocked
ahcich0: Timeout on slot 0 port 0
ahcich0: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
(aprobe0:ahcich0:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich0:0:0:0): CAM status: Command timeout
(aprobe0:ahcich0:0:0:0): Error 5, Retry was blocked
Netvsc initializing... SMP: AP CPU #1 Launched!
cpu1 AP:
     ID: 0x06000000   VER: 0x01060015 LDR: 0x00000000 DFR: 0xffffffff
  lint0: 0x00010000 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff
  timer: 0x000100ef therm: 0x00010000 err: 0x000000f0 pmc: 0x00010400
   cmci: 0x000100f2
ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 6 vector 48
msi: Assigning MSI-X IRQ 257 to local APIC 6 vector 49
msi: Assigning MSI IRQ 265 to local APIC 6 vector 50
Timecounter "TSC" frequency 84918628 Hz quality -100
Root mount waiting for: usbus0
ahcich0: AHCI reset: device ready after 300ms
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_TIMEOUT
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
Root mount waiting for: usbus0
2014-05-18 21:10 Updated by: yuichi_xy
Comment

r200では、VM1に割り当てたAHCIをD3Hot D0リセットするようになりましたが、再現しました。

ahci0: attempting to allocate 1 MSI vectors (1 supported)
msi: routing MSI IRQ 265 to local APIC 4 vector 59
ahci0: using IRQ 265 for MSI
ahci0: AHCI v1.20 with 8 6Gbps ports, Port Multiplier not supported
ahci0: quirks=0x800<ALTSIG>
ahci0: Caps: 64bit NCQ 6Gbps PMD 32cmd 8ports
ahci0: Caps2:
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich0: Caps:
ahcich1: <AHCI channel> at channel 1 on ahci0
ahcich1: Caps:
ahcich2: <AHCI channel> at channel 2 on ahci0
ahcich2: Caps:
ahcich3: <AHCI channel> at channel 3 on ahci0
ahcich3: Caps:
ahcich4: <AHCI channel> at channel 4 on ahci0
ahcich4: Caps:
ahcich5: <AHCI channel> at channel 5 on ahci0
ahcich5: Caps:
ahcich6: <AHCI channel> at channel 6 on ahci0
ahcich6: Caps:
ahcich7: <AHCI channel> at channel 7 on ahci0
ahcich7: Caps:
pcib3: <PCI-PCI bridge> at device 28.5 on pci0
pcib0: allocated type 4 (0xc000-0xcfff) for rid 1c of pcib3
pcib0: allocated type 3 (0xf0000000-0xf00fffff) for rid 24 of pcib3
pcib3:   domain            0
pcib3:   secondary bus     5
pcib3:   subordinate bus   5
pcib3:   I/O decode        0xc000-0xcfff
pcib3:   prefetched decode 0xf0000000-0xf00fffff
pci5: <PCI bus> on pcib3
pci5: domain=0, physical bus=5
found-> vendor=0x10ec, dev=0x8168, revid=0x06
        domain=0, bus=5, slot=0, func=0
        class=02-00-00, hdrtype=0x00, mfdev=0
        cmdreg=0x0407, statreg=0x0010, cachelnsz=16 (dwords)
        lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
        intpin=a, irq=255
        powerspec 3  supports D0 D1 D2 D3  current D0
        MSI supports 1 message, 64 bit
        MSI-X supports 4 messages in map 0x20
        map[10]: type I/O Port, range 32, base 0xc000, size  8, enabled
pcib3: allocated I/O port range (0xc000-0xc0ff) for rid 10 of pci0:5:0:0
        map[18]: type Prefetchable Memory, range 64, base 0xf0004000, size 12, enabled
pcib3: allocated prefetch range (0xf0004000-0xf0004fff) for rid 18 of pci0:5:0:0
        map[20]: type Prefetchable Memory, range 64, base 0xf0000000, size 14, enabled
pcib3: allocated prefetch range (0xf0000000-0xf0003fff) for rid 20 of pci0:5:0:0
re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F/G PCIe Gigabit Ethernet> port 0xc000-0xc0ff mem 0xf0004000-0xf0004fff,0xf0000000-0xf0003fff at device 0.0 on pci5
re0: MSI count : 1
re0: MSI-X count : 4
re0: attempting to allocate 1 MSI-X vectors (4 supported)
msi: routing MSI-X IRQ 266 to local APIC 4 vector 60
re0: using IRQ 266 for MSI-X
re0: Using 1 MSI-X message
re0: Chip rev. 0x2c800000
re0: MAC rev. 0x00100000
miibus0: <MII bus> on re0
rgephy0: <RTL8169S/8110S/8211 1000BASE-T media interface> PHY 1 on miibus0
rgephy0: OUI 0x00e04c, model 0x0011, rev. 5
rgephy0:  none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
re0: bpf attached
re0: Ethernet address: bc:5f:f4:97:58:96
atkbdc0: AT keyboard controller not found
psmcpnp0: <PS/2 mouse port> irq 12 on acpi0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: does not respond
device_attach: fdc0 attach returned 6
ppc0: using extended I/O port range
uart0: <Non-standard ns8250 class UART with FIFOs> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
ioapic0: routing intpin 4 (ISA IRQ 4) to lapic 4 vector 61
uart0: fast interrupt
ACPI: Enabled 16 GPEs in block 00 to 0F
acpi0: wakeup code va 0xfffffe017efe0000 pa 0x4000
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 20fc00002000
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 20fc00002000
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
Device configuration finished.
procfs registered
lapic: Divisor 2, Frequency 1144759 Hz
Timecounters tick every 10.000 msec
vlan: initialized, using hash tables with chaining
tcp_init: net.inet.tcp.tcbhashsize auto tuned to 65536
lo0: bpf attached
hptnr: no controller detected.
hpt27xx: no controller detected.
hptrr: no controller detected.
usbus0: 5.0Gbps Super Speed USB v3.0
ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
ahcich1: AHCI reset...
ahcich1: SATA connect timeout time=10000us status=00000000
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ahcich1: AHCI reset: device not found
ahcich2: AHCI reset...
ahcich2: SATA connect timeout time=10000us status=00000000
ahcich2: AHCI reset: device not found
ahcich3: AHCI reset...
ahcich3: SATA connect timeout time=10000us status=00000000
ahcich3: AHCI reset: device not found
ahcich4: AHCI reset...
ahcich4: SATA connect timeout time=10000us status=00000000
ahcich4: AHCI reset: device not found
ahcich5: AHCI reset...
ahcich5: SATA connect timeout time=10000us status=00000000
ahcich5: AHCI reset: device not found
ahcich6: AHCI reset...
ahcich6: SATA connect timeout time=10000us status=00000000
ahcich6: AHCI reset: device not found
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
random: unblocking device.
ahcich0: AHCI reset: device ready after 300ms
uhub0: 8 ports with 8 removable, self powered
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
ahcich7: Timeout on slot 0 port 0
ahcich7: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
(aprobe1:ahcich7:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
(aprobe1:ahcich7:0:0:0): CAM status: Command timeout
(aprobe1:ahcich7:0:0:0): Error 5, Retry was blocked
ahcich0: Timeout on slot 0 port 0
ahcich0: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
(aprobe0:ahcich0:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich0:0:0:0): CAM status: Command timeout
(aprobe0:ahcich0:0:0:0): Error 5, Retry was blocked
ahcich0: AHCI reset: device ready after 300ms
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
ugen0.2: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
run_interrupt_driven_hooks: still waiting after 60 seconds for xpt_config
ahcich7: Timeout on slot 0 port 0
ahcich7: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
(aprobe1:ahcich7:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
(aprobe1:ahcich7:0:0:0): CAM status: Command timeout
(aprobe1:ahcich7:0:0:0): Error 5, Retry was blocked
ahcich0: Timeout on slot 0 port 0
ahcich0: is 00000001 cs 00000000 ss 00000000 rs 00000001 tfd 50 serr 00000000 cmd 10008017
ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
(aprobe0:ahcich0:0:0:0): ATA_IDENTIFY. ACB: ec 00 00 00 00 40 00 00 00 00 00 00
(aprobe0:ahcich0:0:0:0): CAM status: Command timeout
(aprobe0:ahcich0:0:0:0): Error 5, Retry was blocked
Netvsc initializing... SMP: AP CPU #1 Launched!
cpu1 AP:
     ID: 0x06000000   VER: 0x01060015 LDR: 0x00000000 DFR: 0xffffffff
  lint0: 0x00010000 lint1: 0x00010000 TPR: 0x00000000 SVR: 0x000001ff
  timer: 0x000100ef therm: 0x00010000 err: 0x000000f0 pmc: 0x00010400
   cmci: 0x000100f2
ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 6 vector 48
msi: Assigning MSI-X IRQ 257 to local APIC 6 vector 49
msi: Assigning MSI IRQ 265 to local APIC 6 vector 50
Timecounter "TSC" frequency 73250424 Hz quality -100
Root mount waiting for: usbus0
ahcich0: AHCI reset: device ready after 300ms
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_TIMEOUT
Root mount waiting for: usbus0
Root mount waiting for: usbus0
2014-09-23 22:15 Updated by: yuichi_xy
  • Resolution Update from None to Fixed
  • Status Update from Open to Closed
  • Ticket Close date is changed to 2014-09-23 22:15
Comment

r284 と FreeBSD 10 の組み合わせでは、VM1のFreeBSDを再起動した時に、AHCI の初期化に成功しました。

しかし、NIC で通信できません。#34365 を作成しました。

ahcich0: AHCI reset...
ahcich0: SATA connect time=0us status=00000123
ahcich0: AHCI reset: device found
ahcich1: AHCI reset...
ahcich1: SATA connect timeout time=10000us status=00000000
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ahcich1: AHCI reset: device not found   
ahcich2: AHCI reset...
ahcich2: SATA connect timeout time=10000us status=00000000
ahcich2: AHCI reset: device not found   
ahcich3: AHCI reset...
ahcich3: SATA connect timeout time=10000us status=00000000
ahcich3: AHCI reset: device not found   
ahcich4: AHCI reset...
ahcich4: SATA connect timeout time=10000us status=00000000
ahcich4: AHCI reset: device not found   
ahcich5: AHCI reset...
ahcich5: SATA connect timeout time=10000us status=00000000
ahcich5: AHCI reset: device not found   
ahcich6: AHCI reset...
ahcich6: SATA connect timeout time=10000us status=00000000
ahcich6: AHCI reset: device not found   
ahcich7: AHCI reset...
ahcich7: SATA connect time=0us status=00000113
ahcich7: AHCI reset: device found
ahcich7: AHCI reset: device ready after 0ms
ahcich0: AHCI reset: device ready after 300ms
ada0 at ahcich0 bus 0 scbus0 target 0 lun 0
ada0: <Hitachi HDS721032CLA362 JPFOA39C> ATA-8 SATA 2.x device
ada0: Serial Number JP1421HN08675C
ada0: 300.000MB/s transfers (SATA 2.x, UDMA6, GEOM: new disk ada0
PIO 8192bytes)
ada0: Command Queueing enabled
ada0: 305245MB (625142448 512 byte sectors: 16H 63S/T 16383C)
ada0: Previously was known as ad4
pass0 at ahcich0 bus 0 scbus0 target 0 lun 0
pass0: <Hitachi HDS721032CLA362 JPFOA39C> ATA-8 SATA 2.x device
pass0: Serial Number JP1421HN08675C
pass0: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
pass0: Command Queueing enabled
pass1 at ahcich7 bus 0 scbus7 target 0 lun 0
pass1: <Marvell 91xx Config 1.01> Removable Processor SCSI-0 device
pass1: Serial Number HKDP221516WL
pass1: 150.000MB/s transfers (SATA 1.x, UDMA4, ATAPI 12bytes, PIO 8192bytes)
]}}

Attachment File List

No attachments

Edit

You are not logged in. I you are not logged in, your comment will be treated as an anonymous post. » Login