2008-03-09 18:32:56

by Volker Armin Hemmann

[permalink] [raw]
Subject: 2.6.24.X: SATA/AHCI related boot delay.

Hi,

I have an Alive -NF5-esata2+. AM2 board with dual core AMD64 cpu.

Problem exists with latest bios 1.90 and its predecessor 1.80.

This board with nforce 520 chipset (MCP65) has three possible settings for the
sata controller:
non-raid
raid
ahci.

My default is non-raid.

With 2.6.24 based kernels I get this on cold boot:

[ 49.176044] Driver 'sd' needs updating - please use bus_type methods
[ 49.176117] ahci 0000:00:0a.0: version 3.0
[ 49.176291] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 49.176335] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 50.176871] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 50.176922] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 50.176962] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 50.177163] scsi0 : ahci
[ 50.177259] scsi1 : ahci
[ 50.177325] scsi2 : ahci
[ 50.177392] scsi3 : ahci
[ 50.177480] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 50.177528] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 50.177575] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 50.177623] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 50.808824] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 80.743754] ata1.00: qc timeout (cmd 0xec)
[ 80.743792] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 80.743831] ata1: failed to recover some devices, retrying in 5 secs
[ 86.368194] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 86.368803] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 86.368846] ata1.00: 312581808 sectors, multi 16: LBA48
[ 86.369491] ata1.00: configured for UDMA/133
[ 87.000153] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 87.020703] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 87.020742] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 87.022737] ata2.00: configured for UDMA/133
[ 87.339415] ata3: SATA link down (SStatus 0 SControl 300)
[ 87.658721] ata4: SATA link down (SStatus 0 SControl 300)
[ 87.658810] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 87.658917] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 87.658962] sd 0:0:0:0: [sda] Write Protect is off
[ 87.659000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 87.659008] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.659078] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 87.659121] sd 0:0:0:0: [sda] Write Protect is off
[ 87.659159] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 87.659166] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.659215] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 87.696698] sd 0:0:0:0: [sda] Attached SCSI disk
[ 87.696832] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 87.696940] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 87.696985] sd 1:0:0:0: [sdb] Write Protect is off
[ 87.697022] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 87.697031] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.697101] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 87.697144] sd 1:0:0:0: [sdb] Write Protect is off
[ 87.697182] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 87.697189] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.697238] sdb: sdb1 sdb2
[ 87.720300] sd 1:0:0:0: [sdb] Attached SCSI disk
---------------------------------------------------------------------------------------

and this on reboots:

30.984720] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 30.984771] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 30.984811] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 30.985012] scsi0 : ahci
[ 30.985108] scsi1 : ahci
[ 30.985174] scsi2 : ahci
[ 30.985241] scsi3 : ahci
[ 30.985329] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 30.985377] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 30.985425] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 30.985472] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 36.619135] ata1: port is slow to respond, please be patient (Status 0xd0)
[ 40.982984] ata1: softreset failed (device not ready)
[ 41.614947] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 41.615582] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 41.615625] ata1.00: 312581808 sectors, multi 16: LBA48
[ 41.616251] ata1.00: configured for UDMA/133
[ 42.246907] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 42.250073] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 42.250113] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 42.252112] ata2.00: configured for UDMA/133
[ 42.569539] ata3: SATA link down (SStatus 0 SControl 300)
[ 42.888845] ata4: SATA link down (SStatus 0 SControl 300)
[ 42.888934] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 42.889041] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 42.889086] sd 0:0:0:0: [sda] Write Protect is off
[ 42.889124] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 42.889132] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.889202] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 42.889246] sd 0:0:0:0: [sda] Write Protect is off
[ 42.889284] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 42.889291] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.889340] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 42.925936] sd 0:0:0:0: [sda] Attached SCSI disk
[ 42.926071] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 42.926180] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 42.926224] sd 1:0:0:0: [sdb] Write Protect is off
[ 42.926262] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 42.926271] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.926346] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 42.926389] sd 1:0:0:0: [sdb] Write Protect is off
[ 42.926427] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 42.926434] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.926484] sdb: sdb1 sdb2
[ 42.955833] sd 1:0:0:0: [sdb] Attached SCSI disk
----------------------------------------------------------------------------------------

with 2.6.23.11 it looks like this:

[ 90.956853] ide: Assuming 33MHz system bus speed for PIO modes; override
with idebus=xx
[ 90.957166] NFORCE-MCP65: IDE controller at PCI slot 0000:00:09.0
[ 90.957335] NFORCE-MCP65: chipset revision 161
[ 90.957494] NFORCE-MCP65: not 100% native mode: will probe irqs later
[ 90.957658] NFORCE-MCP65: 0000:00:09.0 (rev a1) UDMA133 controller
[ 90.957820] ide0: BM-DMA at 0xffa0-0xffa7, BIOS settings: hda:DMA,
hdb:pio
[ 90.958166] Probing IDE interface ide0...
[ 91.246150] hda: SAMSUNG SP1213N, ATA DISK drive
[ 91.888005] hda: selected mode 0x45
[ 91.888070] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 91.890652] hda: max request size: 512KiB
[ 91.890861] hda: 234493056 sectors (120060 MB) w/8192KiB Cache,
CHS=16383/255/63, UDMA(100)
[ 91.891601] hda: cache flushes supported
[ 91.891777] hda: hda1 hda2 hda3 < hda5 hda6 hda7 >
[ 91.931121] ahci 0000:00:0a.0: version 2.3
[ 91.931299] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 91.931465] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 92.935591] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 92.935888] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 92.936049] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 92.936263] scsi0 : ahci
[ 92.936486] scsi1 : ahci
[ 92.936677] scsi2 : ahci
[ 92.936867] scsi3 : ahci
[ 92.937048] ata1: SATA max UDMA/133 cmd 0xffffc20000020100 ctl
0x0000000000000000 bmdma 0x0000000000000000 irq 315
[ 92.937342] ata2: SATA max UDMA/133 cmd 0xffffc20000020180 ctl
0x0000000000000000 bmdma 0x0000000000000000 irq 315
[ 92.937636] ata3: SATA max UDMA/133 cmd 0xffffc20000020200 ctl
0x0000000000000000 bmdma 0x0000000000000000 irq 315
[ 92.937930] ata4: SATA max UDMA/133 cmd 0xffffc20000020280 ctl
0x0000000000000000 bmdma 0x0000000000000000 irq 315
[ 93.411080] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 93.411883] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 93.412045] ata1.00: 312581808 sectors, multi 16: LBA48
[ 93.412797] ata1.00: configured for UDMA/133
[ 93.727032] ata2: SATA link down (SStatus 0 SControl 300)
[ 94.042987] ata3: SATA link down (SStatus 0 SControl 300)
[ 94.358941] ata4: SATA link down (SStatus 0 SControl 300)
[ 94.359249] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 94.359599] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 94.359765] sd 0:0:0:0: [sda] Write Protect is off
[ 94.359925] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 94.359933] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 94.360249] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 94.360414] sd 0:0:0:0: [sda] Write Protect is off
[ 94.360573] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 94.360581] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 94.360873] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 94.402906] sd 0:0:0:0: [sda] Attached SCSI disk
[ 94.403107] sd 0:0:0:0: Attached scsi generic sg0 type 0
---------------------------------------------------------------------------------------

I removed the pata-disk and the drivers some days ago - but that has had no
influence on the problem. The delay was there with the pata-drive and drivers
too. There are some changes in the config - but even with the 2.6.3.11 config
on a 2.6.24 and make oldconfig showed the delays.

If I change from 'non raid' to AHCI (because the handbook says that is the way
to get NCQ working) boot hangs for looong time and no harddisks are found:
(dmesg from systemrescuecd 1.0)
ahci 0000:00:0a.0: version 3.0
ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23 (level, low) ->
IRQ 18
ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
PCI: Setting latency timer of device 0000:00:0a.0 to 64
scsi3 : ahci
scsi4 : ahci
scsi5 : ahci
scsi6 : ahci
ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100 irq 218
ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180 irq 218
ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200 irq 218
ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280 irq 218
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 00(40)
scsi_scan_2 used greatest stack depth: 2868 bytes left
ata1.00: qc timeout (cmd 0xec)
ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata1: failed to recover some devices, retrying in 5 secs
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 40(40)
ata1.00: qc timeout (cmd 0xec)
ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata1: failed to recover some devices, retrying in 5 secs
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 40(40)
ata1.00: qc timeout (cmd 0xec)
ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata1: failed to recover some devices, retrying in 5 secs
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 40(40)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata2: failed to recover some devices, retrying in 5 secs
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 40(40)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata2: failed to recover some devices, retrying in 5 secs
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
APIC error on CPU0: 40(40)
ata2.00: qc timeout (cmd 0xec)
ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata2: failed to recover some devices, retrying in 5 secs
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata3: SATA link down (SStatus 0 SControl 300)
ata4: SATA link down (SStatus 0 SControl 300)
pata_amd 0000:00:09.0: version 0.3.10
PCI: Setting latency timer of device 0000:00:09.0 to 64
scsi7 : pata_amd
scsi8 : pata_amd
ata5: PATA max UDMA/133 cmd 0x1f0 ctl 0x3f6 bmdma 0xffa0 irq 14
ata6: PATA max UDMA/133 cmd 0x170 ctl 0x376 bmdma 0xffa8 irq 15

I tried systemrescuecd with the 'noapic' option - same result. Long hangs, no
disks found.

lspci
00:00.0 RAM memory: nVidia Corporation MCP65 Memory Controller (rev a1)
00:01.0 ISA bridge: nVidia Corporation MCP65 LPC Bridge (rev a2)
00:01.1 SMBus: nVidia Corporation MCP65 SMBus (rev a1)
00:01.2 RAM memory: nVidia Corporation MCP65 Memory Controller (rev a1)
00:02.0 USB Controller: nVidia Corporation MCP65 USB Controller (rev a1)
00:02.1 USB Controller: nVidia Corporation MCP65 USB Controller (rev a1)
00:08.0 PCI bridge: nVidia Corporation MCP65 PCI bridge (rev a1)
00:09.0 IDE interface: nVidia Corporation MCP65 IDE (rev a1)
00:0a.0 IDE interface: nVidia Corporation MCP65 SATA Controller (rev a1)
00:0b.0 PCI bridge: nVidia Corporation Unknown device 045b (rev a1)
00:0c.0 PCI bridge: nVidia Corporation MCP65 PCI Express bridge (rev a1)
00:0d.0 PCI bridge: nVidia Corporation MCP65 PCI Express bridge (rev a1)
00:0e.0 PCI bridge: nVidia Corporation MCP65 PCI Express bridge (rev a1)
00:18.0 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
HyperTransport Technology Configuration
00:18.1 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Address Map
00:18.2 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] DRAM
Controller
00:18.3 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Miscellaneous Control
01:00.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168B PCI
Express Gigabit Ethernet controller (rev 01)
02:08.0 SCSI storage controller: Adaptec AHA-2944UW / AIC-7884U (rev 01)
02:09.0 Multimedia audio controller: Creative Labs SB0400 Audigy2 Value
04:00.0 VGA compatible controller: nVidia Corporation Unknown device 0402 (rev
a1)

dmesg
[ 0.000000] Linux version 2.6.24.2reiser4 (root@energy) (gcc version 4.2.3
(Gentoo 4.2.3 p1.0)) #6 SMP Tue Mar 4 17:28:59 CET 2008
[ 0.000000] Command line: root=/dev/sda3 nmi_watchdog=0 console=tty6
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[ 0.000000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000e6000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 00000000cffb0000 (usable)
[ 0.000000] BIOS-e820: 00000000cffb0000 - 00000000cffc0000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000cffc0000 - 00000000cfff0000 (ACPI NVS)
[ 0.000000] BIOS-e820: 00000000cfff0000 - 00000000d0000000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[ 0.000000] BIOS-e820: 00000000fee00000 - 00000000fef00000 (reserved)
[ 0.000000] BIOS-e820: 00000000ff380000 - 0000000100000000 (reserved)
[ 0.000000] BIOS-e820: 0000000100000000 - 0000000130000000 (usable)
[ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 256 used
[ 0.000000] Entering add_active_range(0, 256, 851888) 1 entries of 256 used
[ 0.000000] Entering add_active_range(0, 1048576, 1245184) 2 entries of 256
used
[ 0.000000] end_pfn_map = 1245184
[ 0.000000] DMI present.
[ 0.000000] ACPI: RSDP 000F98E0, 0014 (r0 ACPIAM)
[ 0.000000] ACPI: RSDT CFFB0000, 003C (r1 A M I OEMRSDT 12000731 MSFT
97)
[ 0.000000] ACPI: FACP CFFB0200, 0084 (r2 A_M_I OEMFACP 12000601 MSFT
97)
[ 0.000000] ACPI: DSDT CFFB0450, 4FFA (r1 ASR19 ASR19186 186 INTL
20051117)
[ 0.000000] ACPI: FACS CFFC0000, 0040
[ 0.000000] ACPI: APIC CFFB0390, 0080 (r1 A M I OEMAPIC 12000731 MSFT
97)
[ 0.000000] ACPI: MCFG CFFB0410, 003C (r1 A M I OEMMCFG 12000731 MSFT
97)
[ 0.000000] ACPI: OEMB CFFC0040, 0060 (r1 A M I AMI_OEM 12000731 MSFT
97)
[ 0.000000] ACPI: HPET CFFB5450, 0038 (r1 A M I OEMHPET0 12000731 MSFT
97)
[ 0.000000] ACPI: SSDT CFFB5490, 030E (r1 A M I POWERNOW 1 AMD
1)
[ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 256 used
[ 0.000000] Entering add_active_range(0, 256, 851888) 1 entries of 256 used
[ 0.000000] Entering add_active_range(0, 1048576, 1245184) 2 entries of 256
used
[ 0.000000] No mptable found.
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] DMA32 4096 -> 1048576
[ 0.000000] Normal 1048576 -> 1245184
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[3] active PFN ranges
[ 0.000000] 0: 0 -> 159
[ 0.000000] 0: 256 -> 851888
[ 0.000000] 0: 1048576 -> 1245184
[ 0.000000] On node 0 totalpages: 1048399
[ 0.000000] DMA zone: 56 pages used for memmap
[ 0.000000] DMA zone: 856 pages reserved
[ 0.000000] DMA zone: 3087 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 14280 pages used for memmap
[ 0.000000] DMA32 zone: 833512 pages, LIFO batch:31
[ 0.000000] Normal zone: 2688 pages used for memmap
[ 0.000000] Normal zone: 193920 pages, LIFO batch:31
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] ACPI: PM-Timer IO Port: 0x2008
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[ 0.000000] Processor #0 (Bootup-CPU)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[ 0.000000] Processor #1
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x82] disabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x83] disabled)
[ 0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 2, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 14 global_irq 14 high edge)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 15 global_irq 15 high edge)
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] ACPI: IRQ2 used by override.
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] ACPI: IRQ14 used by override.
[ 0.000000] ACPI: IRQ15 used by override.
[ 0.000000] Setting APIC routing to flat
[ 0.000000] ACPI: HPET id: 0x10de8201 base: 0xfed00000
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] swsusp: Registered nosave memory region: 000000000009f000 -
00000000000a0000
[ 0.000000] swsusp: Registered nosave memory region: 00000000000a0000 -
00000000000e6000
[ 0.000000] swsusp: Registered nosave memory region: 00000000000e6000 -
0000000000100000
[ 0.000000] swsusp: Registered nosave memory region: 00000000cffb0000 -
00000000cffc0000
[ 0.000000] swsusp: Registered nosave memory region: 00000000cffc0000 -
00000000cfff0000
[ 0.000000] swsusp: Registered nosave memory region: 00000000cfff0000 -
00000000d0000000
[ 0.000000] swsusp: Registered nosave memory region: 00000000d0000000 -
00000000fec00000
[ 0.000000] swsusp: Registered nosave memory region: 00000000fec00000 -
00000000fec01000
[ 0.000000] swsusp: Registered nosave memory region: 00000000fec01000 -
00000000fee00000
[ 0.000000] swsusp: Registered nosave memory region: 00000000fee00000 -
00000000fef00000
[ 0.000000] swsusp: Registered nosave memory region: 00000000fef00000 -
00000000ff380000
[ 0.000000] swsusp: Registered nosave memory region: 00000000ff380000 -
0000000100000000
[ 0.000000] Allocating PCI resources starting at d4000000 (gap:
d0000000:2ec00000)
[ 0.000000] SMP: Allowing 2 CPUs, 0 hotplug CPUs
[ 0.000000] PERCPU: Allocating 31408 bytes of per cpu data
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total
pages: 1030519
[ 0.000000] Kernel command line: root=/dev/sda3 nmi_watchdog=0 console=tty6
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[ 0.000000] hpet clockevent registered
[ 0.000000] TSC calibrated against HPET
[ 48.747446] Marking TSC unstable due to TSCs unsynchronized
[ 48.747447] time.c: Detected 3013.888 MHz processor.
[ 48.751279] Console: colour VGA+ 80x25
[ 48.751281] console [tty6] enabled
[ 48.755890] Dentry cache hash table entries: 524288 (order: 10, 4194304
bytes)
[ 48.758116] Inode-cache hash table entries: 262144 (order: 9, 2097152
bytes)
[ 48.758884] Checking aperture...
[ 48.758922] CPU 0: aperture @ ecc8000000 size 32 MB
[ 48.758959] Aperture too small (32 MB)
[ 48.764827] No AGP bridge found
[ 48.764863] Your BIOS doesn't leave a aperture memory hole
[ 48.764901] Please enable the IOMMU option in the BIOS setup
[ 48.764939] This costs you 64 MB of RAM
[ 48.790106] Mapping aperture over 65536 KB of RAM @ 8000000
[ 48.813687] Memory: 4049936k/4980736k available (2204k kernel code, 143440k
reserved, 681k data, 172k init)
[ 48.813756] SLUB: Genslabs=11, HWalign=64, Order=0-1, MinObjects=4, CPUs=2,
Nodes=1
[ 48.893771] Calibrating delay using timer specific routine.. 6034.65
BogoMIPS (lpj=10053840)
[ 48.893864] Mount-cache hash table entries: 256
[ 48.893972] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64
bytes/line)
[ 48.894012] CPU: L2 Cache: 1024K (64 bytes/line)
[ 48.894050] CPU: Physical Processor ID: 0
[ 48.894086] CPU: Processor Core ID: 0
[ 48.894139] SMP alternatives: switching to UP code
[ 48.894504] ACPI: Core revision 20070126
[ 48.929680] Using local APIC timer interrupts.
[ 48.962824] APIC timer calibration result 12557873
[ 48.962826] Detected 12.557 MHz APIC timer.
[ 48.962910] SMP alternatives: switching to SMP code
[ 48.963141] Booting processor 1/2 APIC 0x1
[ 48.973258] Initializing CPU#1
[ 49.056020] Calibrating delay using timer specific routine.. 6030.92
BogoMIPS (lpj=10046281)
[ 49.056024] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64
bytes/line)
[ 49.056026] CPU: L2 Cache: 1024K (64 bytes/line)
[ 49.056027] CPU: Physical Processor ID: 0
[ 49.056028] CPU: Processor Core ID: 1
[ 49.056109] AMD Athlon(tm) 64 X2 Dual Core Processor 6000+ stepping 03
[ 49.056167] Brought up 2 CPUs
[ 49.056624] net_namespace: 120 bytes
[ 49.056866] NET: Registered protocol family 16
[ 49.056978] ACPI: bus type pci registered
[ 49.057060] PCI: BIOS Bug: MCFG area at e0000000 is not E820-reserved
[ 49.057099] PCI: Not using MMCONFIG.
[ 49.057135] PCI: Using configuration type 1
[ 49.059381] ACPI: EC: Look up EC in DSDT
[ 49.062825] ACPI: Interpreter enabled
[ 49.062863] ACPI: (supports S0 S1 S3 S4 S5)
[ 49.063033] ACPI: Using IOAPIC for interrupt routing
[ 49.063242] Error attaching device data
[ 49.063281] Error attaching device data
[ 49.063320] Error attaching device data
[ 49.063359] Error attaching device data
[ 49.068281] ACPI: PCI Root Bridge [PCI0] (0000:00)
[ 49.068803] PCI: Transparent bridge - 0000:00:08.0
[ 49.069095] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 49.069228] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P1._PRT]
[ 49.069326] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.BR10._PRT]
[ 49.069382] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.BR11._PRT]
[ 49.069438] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.BR13._PRT]
[ 49.069494] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.BR12._PRT]
[ 49.073455] ACPI: PCI Interrupt Link [LNKA] (IRQs 16 17 18 19) *11
[ 49.073793] ACPI: PCI Interrupt Link [LNKB] (IRQs 16 17 18 19) *5
[ 49.074127] ACPI: PCI Interrupt Link [LNKC] (IRQs 16 17 18 19) *0,
disabled.
[ 49.074487] ACPI: PCI Interrupt Link [LNKD] (IRQs 16 17 18 19) *0,
disabled.
[ 49.074847] ACPI: PCI Interrupt Link [LNEA] (IRQs 16 17 18 19) *0,
disabled.
[ 49.075207] ACPI: PCI Interrupt Link [LNEB] (IRQs 16 17 18 19) *10
[ 49.075541] ACPI: PCI Interrupt Link [LNEC] (IRQs 16 17 18 19) *0,
disabled.
[ 49.075902] ACPI: PCI Interrupt Link [LNED] (IRQs 16 17 18 19) *10
[ 49.076239] ACPI: PCI Interrupt Link [LUB0] (IRQs 20 21 22 23) *5
[ 49.076573] ACPI: PCI Interrupt Link [LMAD] (IRQs 20 21 22 23) *0,
disabled.
[ 49.076933] ACPI: PCI Interrupt Link [LUB2] (IRQs 20 21 22 23) *7
[ 49.077267] ACPI: PCI Interrupt Link [LMAC] (IRQs 20 21 22 23) *0,
disabled.
[ 49.077628] ACPI: PCI Interrupt Link [LAZA] (IRQs 20 21 22 23) *0,
disabled.
[ 49.077988] ACPI: PCI Interrupt Link [LSMB] (IRQs 20 21 22 23) *11
[ 49.078322] ACPI: PCI Interrupt Link [LPMU] (IRQs 20 21 22 23) *0,
disabled.
[ 49.078683] ACPI: PCI Interrupt Link [LSA0] (IRQs 20 21 22 23) *15
[ 49.079017] ACPI: PCI Interrupt Link [LSA1] (IRQs 20 21 22 23) *0,
disabled.
[ 49.079415] ACPI: PCI Interrupt Link [LATA] (IRQs 20 21 22 23) *0,
disabled.
[ 49.079776] ACPI: PCI Interrupt Link [UB11] (IRQs 20 21 22 23) *0,
disabled.
[ 49.080137] ACPI: PCI Interrupt Link [UB12] (IRQs 20 21 22 23) *0,
disabled.
[ 49.080456] Linux Plug and Play Support v0.97 (c) Adam Belay
[ 49.080509] pnp: PnP ACPI init
[ 49.080548] ACPI: bus type pnp registered
[ 49.083088] pnp: PnP ACPI: found 13 devices
[ 49.083125] ACPI: ACPI bus type pnp unregistered
[ 49.083244] SCSI subsystem initialized
[ 49.083300] libata version 3.00 loaded.
[ 49.083353] usbcore: registered new interface driver usbfs
[ 49.083407] usbcore: registered new interface driver hub
[ 49.083464] usbcore: registered new device driver usb
[ 49.083546] PCI: Using ACPI for IRQ routing
[ 49.083584] PCI: If a device doesn't work, try "pci=routeirq". If it
helps, post a report
[ 49.092621] PCI-DMA: Disabling AGP.
[ 49.093359] PCI-DMA: aperture base @ 8000000 size 65536 KB
[ 49.093401] PCI-DMA: using GART IOMMU.
[ 49.093441] PCI-DMA: Reserving 64MB of IOMMU area in the AGP aperture
[ 49.093600] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 31
[ 49.093739] hpet0: 3 32-bit timers, 25000000 Hz
[ 49.095903] Time: hpet clocksource has been installed.
[ 49.095951] Switched to high resolution mode on CPU 0
[ 49.096038] Switched to high resolution mode on CPU 1
[ 49.102635] system 00:05: ioport range 0x4d0-0x4d1 has been reserved
[ 49.102675] system 00:05: ioport range 0x800-0x80f has been reserved
[ 49.102714] system 00:05: ioport range 0x2000-0x207f has been reserved
[ 49.102754] system 00:05: ioport range 0x2080-0x20ff has been reserved
[ 49.106152] system 00:05: ioport range 0x2400-0x247f has been reserved
[ 49.106192] system 00:05: ioport range 0x2480-0x24ff has been reserved
[ 49.106231] system 00:05: ioport range 0x2800-0x287f has been reserved
[ 49.106270] system 00:05: ioport range 0x2880-0x28ff has been reserved
[ 49.106309] system 00:05: ioport range 0x2c00-0x2c7f has been reserved
[ 49.106349] system 00:05: ioport range 0x2c80-0x2cff has been reserved
[ 49.106389] system 00:05: iomem range 0x0-0x0 could not be reserved
[ 49.106428] system 00:05: iomem range 0xfee01000-0xfeefffff has been
reserved
[ 49.106472] system 00:07: iomem range 0xfec00000-0xfec00fff could not be
reserved
[ 49.106520] system 00:07: iomem range 0xfee00000-0xfee00fff could not be
reserved
[ 49.106570] system 00:0a: ioport range 0x290-0x29f has been reserved
[ 49.106613] system 00:0b: iomem range 0xe0000000-0xefffffff has been
reserved
[ 49.106658] system 00:0c: iomem range 0x0-0x9ffff could not be reserved
[ 49.106698] system 00:0c: iomem range 0xc0000-0xcffff has been reserved
[ 49.106740] system 00:0c: iomem range 0xe0000-0xfffff could not be reserved
[ 49.106780] system 00:0c: iomem range 0x100000-0xcfffffff could not be
reserved
[ 49.106827] system 00:0c: iomem range 0xff380000-0xffffffff could not be
reserved
[ 49.107108] PCI: Bridge: 0000:00:08.0
[ 49.107145] IO window: d000-dfff
[ 49.107182] MEM window: f9f00000-f9ffffff
[ 49.107219] PREFETCH window: disabled.
[ 49.107257] PCI: Bridge: 0000:00:0b.0
[ 49.107293] IO window: c000-cfff
[ 49.107330] MEM window: f9e00000-f9efffff
[ 49.107367] PREFETCH window: disabled.
[ 49.107404] PCI: Bridge: 0000:00:0c.0
[ 49.107440] IO window: disabled.
[ 49.107476] MEM window: disabled.
[ 49.107512] PREFETCH window: disabled.
[ 49.107549] PCI: Bridge: 0000:00:0d.0
[ 49.107585] IO window: e000-efff
[ 49.107622] MEM window: fa000000-febfffff
[ 49.107659] PREFETCH window: d0000000-dfffffff
[ 49.107697] PCI: Bridge: 0000:00:0e.0
[ 49.107733] IO window: disabled.
[ 49.107769] MEM window: disabled.
[ 49.107805] PREFETCH window: disabled.
[ 49.107848] PCI: Setting latency timer of device 0000:00:08.0 to 64
[ 49.107857] PCI: Setting latency timer of device 0000:00:0b.0 to 64
[ 49.107862] PCI: Setting latency timer of device 0000:00:0c.0 to 64
[ 49.107866] PCI: Setting latency timer of device 0000:00:0d.0 to 64
[ 49.107871] PCI: Setting latency timer of device 0000:00:0e.0 to 64
[ 49.107877] NET: Registered protocol family 2
[ 49.139202] IP route cache hash table entries: 131072 (order: 8, 1048576
bytes)
[ 49.139805] TCP established hash table entries: 262144 (order: 10, 4194304
bytes)
[ 49.141228] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[ 49.141693] TCP: Hash tables configured (established 262144 bind 65536)
[ 49.141733] TCP reno registered
[ 49.149967] Loading Reiser4. See http://www.namesys.com for a description of
Reiser4.
[ 49.150075] Block layer SCSI generic (bsg) driver version 0.4 loaded (major
254)
[ 49.150123] io scheduler noop registered
[ 49.150161] io scheduler cfq registered (default)
[ 49.150855] Boot video device is 0000:04:00.0
[ 49.150958] PCI: Setting latency timer of device 0000:00:0b.0 to 64
[ 49.150975] assign_interrupt_mode Found MSI capability
[ 49.151029] Allocate Port Service[0000:00:0b.0:pcie00]
[ 49.151071] PCI: Setting latency timer of device 0000:00:0c.0 to 64
[ 49.151087] assign_interrupt_mode Found MSI capability
[ 49.151135] Allocate Port Service[0000:00:0c.0:pcie00]
[ 49.151172] PCI: Setting latency timer of device 0000:00:0d.0 to 64
[ 49.151187] assign_interrupt_mode Found MSI capability
[ 49.151235] Allocate Port Service[0000:00:0d.0:pcie00]
[ 49.151274] PCI: Setting latency timer of device 0000:00:0e.0 to 64
[ 49.151290] assign_interrupt_mode Found MSI capability
[ 49.151338] Allocate Port Service[0000:00:0e.0:pcie00]
[ 49.151515] input: Power Button (FF) as /class/input/input0
[ 49.151553] ACPI: Power Button (FF) [PWRF]
[ 49.151638] input: Power Button (CM) as /class/input/input1
[ 49.151676] ACPI: Power Button (CM) [PWRB]
[ 49.151812] ACPI Exception (processor_core-0816): AE_NOT_FOUND, Processor
Device is not present [20070126]
[ 49.151917] ACPI Exception (processor_core-0816): AE_NOT_FOUND, Processor
Device is not present [20070126]
[ 49.175824] Real Time Clock Driver v1.12ac
[ 49.175942] hpet_resources: 0xfed00000 is busy
[ 49.175960] Linux agpgart interface v0.102
[ 49.176044] Driver 'sd' needs updating - please use bus_type methods
[ 49.176117] ahci 0000:00:0a.0: version 3.0
[ 49.176291] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 49.176335] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 50.176871] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 50.176922] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 50.176962] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 50.177163] scsi0 : ahci
[ 50.177259] scsi1 : ahci
[ 50.177325] scsi2 : ahci
[ 50.177392] scsi3 : ahci
[ 50.177480] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 50.177528] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 50.177575] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 50.177623] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 50.808824] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 80.743754] ata1.00: qc timeout (cmd 0xec)
[ 80.743792] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 80.743831] ata1: failed to recover some devices, retrying in 5 secs
[ 86.368194] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 86.368803] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 86.368846] ata1.00: 312581808 sectors, multi 16: LBA48
[ 86.369491] ata1.00: configured for UDMA/133
[ 87.000153] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 87.020703] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 87.020742] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 87.022737] ata2.00: configured for UDMA/133
[ 87.339415] ata3: SATA link down (SStatus 0 SControl 300)
[ 87.658721] ata4: SATA link down (SStatus 0 SControl 300)
[ 87.658810] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 87.658917] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 87.658962] sd 0:0:0:0: [sda] Write Protect is off
[ 87.659000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 87.659008] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.659078] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 87.659121] sd 0:0:0:0: [sda] Write Protect is off
[ 87.659159] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 87.659166] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.659215] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 87.696698] sd 0:0:0:0: [sda] Attached SCSI disk
[ 87.696832] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 87.696940] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 87.696985] sd 1:0:0:0: [sdb] Write Protect is off
[ 87.697022] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 87.697031] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.697101] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 87.697144] sd 1:0:0:0: [sdb] Write Protect is off
[ 87.697182] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 87.697189] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 87.697238] sdb: sdb1 sdb2
[ 87.720300] sd 1:0:0:0: [sdb] Attached SCSI disk
[ 87.720614] ACPI: PCI Interrupt Link [LUB2] enabled at IRQ 22
[ 87.720659] ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [LUB2] -> GSI 22
(level, low) -> IRQ 22
[ 87.720952] PCI: Setting latency timer of device 0000:00:02.1 to 64
[ 87.720956] ehci_hcd 0000:00:02.1: EHCI Host Controller
[ 87.721072] ehci_hcd 0000:00:02.1: new USB bus registered, assigned bus
number 1
[ 87.721146] ehci_hcd 0000:00:02.1: debug port 1
[ 87.721185] PCI: cache line size of 64 is not supported by device
0000:00:02.1
[ 87.721193] ehci_hcd 0000:00:02.1: irq 22, io mem 0xf9dfec00
[ 87.729429] ehci_hcd 0000:00:02.1: USB 2.0 started, EHCI 1.00, driver 10
Dec 2004
[ 87.729591] usb usb1: configuration #1 chosen from 1 choice
[ 87.729657] hub 1-0:1.0: USB hub found
[ 87.729698] hub 1-0:1.0: 10 ports detected
[ 87.831809] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64
irq 1,12
[ 87.834655] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 87.834694] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 87.890390] input: AT Translated Set 2 keyboard as /class/input/input2
[ 88.057853] usb 1-3: new high speed USB device using ehci_hcd and address 2
[ 88.182269] usb 1-3: configuration #1 chosen from 1 choice
[ 88.182606] hub 1-3:1.0: USB hub found
[ 88.182959] hub 1-3:1.0: 4 ports detected
[ 88.510234] usb 1-5: new high speed USB device using ehci_hcd and address 3
[ 88.639370] usb 1-5: configuration #1 chosen from 1 choice
[ 88.826522] usb 1-3.1: new low speed USB device using ehci_hcd and address
4
[ 88.928973] usb 1-3.1: configuration #1 chosen from 1 choice
[ 89.122483] usb 1-3.4: new high speed USB device using ehci_hcd and address
5
[ 89.195695] usb 1-3.4: device descriptor read/64, error -71
[ 89.371925] usb 1-3.4: device descriptor read/64, error -71
[ 89.541544] usb 1-3.4: new high speed USB device using ehci_hcd and address
6
[ 89.614755] usb 1-3.4: device descriptor read/64, error -71
[ 89.790985] usb 1-3.4: device descriptor read/64, error -71
[ 89.960730] usb 1-3.4: new high speed USB device using ehci_hcd and address
7
[ 90.366204] usb 1-3.4: device not accepting address 7, error -71
[ 90.432921] usb 1-3.4: new high speed USB device using ehci_hcd and address
8
[ 90.838511] usb 1-3.4: device not accepting address 8, error -71
[ 90.843129] input: Logitech USB Receiver as /class/input/input3
[ 90.859316] input: USB HID v1.11 Mouse [Logitech USB Receiver] on
usb-0000:00:02.1-3.1
[ 90.865040] input: Logitech USB Receiver as /class/input/input4
[ 90.885926] input: USB HID v1.11 Device [Logitech USB Receiver] on
usb-0000:00:02.1-3.1
[ 90.886029] usbcore: registered new interface driver usbhid
[ 90.886068] drivers/hid/usbhid/hid-core.c: v2.6:USB HID core driver
[ 90.886113] TCP cubic registered
[ 90.886152] NET: Registered protocol family 1
[ 90.886209] powernow-k8: Found 1 AMD Athlon(tm) 64 X2 Dual Core Processor
6000+ processors (2 cpu cores) (version 2.20.00)
[ 90.886262] powernow-k8: 0 : fid 0x16 (3000 MHz), vid 0x6
[ 90.886301] powernow-k8: 1 : fid 0x14 (2800 MHz), vid 0x8
[ 90.886339] powernow-k8: 2 : fid 0x12 (2600 MHz), vid 0xa
[ 90.886378] powernow-k8: 3 : fid 0x10 (2400 MHz), vid 0xc
[ 90.886416] powernow-k8: 4 : fid 0xe (2200 MHz), vid 0xe
[ 90.886454] powernow-k8: 5 : fid 0xc (2000 MHz), vid 0x10
[ 90.889850] powernow-k8: 6 : fid 0xa (1800 MHz), vid 0x10
[ 90.889889] powernow-k8: 7 : fid 0x2 (1000 MHz), vid 0x12
[ 90.900783] ReiserFS: sda3: found reiserfs format "3.6" with standard
journal
[ 90.900825] ReiserFS: sda3: using ordered data mode
[ 90.910333] ReiserFS: sda3: journal params: device sda3, size 8192, journal
first block 18, max trans len 1024, max batch 900, max commit age 30, max
trans age 30
[ 90.911226] ReiserFS: sda3: checking transaction log (sda3)
[ 90.954640] ReiserFS: sda3: Using r5 hash to sort names
[ 90.954690] VFS: Mounted root (reiserfs filesystem) readonly.
[ 90.954740] Freeing unused kernel memory: 172k freed
[ 91.040511] Clocksource tsc unstable (delta = -144182848 ns)
[ 92.705141] ReiserFS: sda3: switching to journaled data mode
[ 92.875659] r8169 Gigabit Ethernet driver 2.2LK loaded
[ 92.875868] ACPI: PCI Interrupt Link [LNEB] enabled at IRQ 19
[ 92.875873] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link [LNEB] -> GSI 19
(level, low) -> IRQ 19
[ 92.875881] PCI: Setting latency timer of device 0000:01:00.0 to 64
[ 92.876029] eth0: RTL8168b/8111b at 0xffffc20000172000, 00:19:66:47:f3:cb,
XID 38000000 IRQ 314
[ 93.020810] reiser4: sdb2: found disk format 4.0.0.
[ 101.258255] reiser4: sda6: found disk format 4.0.0.
[ 101.716150] reiser4: sdb1: found disk format 4.0.0.
[ 102.062717] Adding 1951888k swap on /dev/sda2. Priority:-1 extents:1
across:1951888k
[ 103.594035] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 18
[ 103.594041] ACPI: PCI Interrupt 0000:02:09.0[A] -> Link [LNKB] -> GSI 18
(level, low) -> IRQ 18
[ 103.595129] Audigy2 value: Special config.
[ 105.122084] w83627ehf: Found W83627EHG chip at 0x290
[ 106.260422] r8169: eth0: link up
[ 106.260427] r8169: eth0: link up
[ 106.747221] NET: Registered protocol family 17
[ 110.231622] nvidia: module license 'NVIDIA' taints kernel.
[ 110.403019] ACPI: PCI Interrupt Link [LNED] enabled at IRQ 17
[ 110.403027] ACPI: PCI Interrupt 0000:04:00.0[A] -> Link [LNED] -> GSI 17
(level, low) -> IRQ 17
[ 110.403032] PCI: Setting latency timer of device 0000:04:00.0 to 64
[ 110.403117] NVRM: loading NVIDIA UNIX x86_64 Kernel Module 171.06 Wed Feb
20 09:02:26 PST 2008
[ 299.415650] usb 1-3.2: new full speed USB device using ehci_hcd and address
9
[ 299.483823] usb 1-3.2: configuration #1 chosen from 2 choices
[ 299.509674] Initializing USB Mass Storage driver...
[ 299.509771] scsi4 : SCSI emulation for USB Mass Storage devices
[ 299.509315] usb-storage: device found at 9
[ 299.509318] usb-storage: waiting for device to settle before scanning
[ 299.509821] usbcore: registered new interface driver usb-storage
[ 299.509823] USB Mass Storage support registered.
[ 302.973619] scsi 4:0:0:0: Direct-Access Motorola Motorola Phone 2.31
PQ: 0 ANSI: 2
[ 303.092568] sd 4:0:0:0: [sdc] 979456 512-byte hardware sectors (501 MB)
[ 303.100559] sd 4:0:0:0: [sdc] Write Protect is off
[ 303.100563] sd 4:0:0:0: [sdc] Mode Sense: 0b 00 00 08
[ 303.100574] sd 4:0:0:0: [sdc] Assuming drive cache: write through
[ 303.130402] sd 4:0:0:0: [sdc] 979456 512-byte hardware sectors (501 MB)
[ 303.138545] sd 4:0:0:0: [sdc] Write Protect is off
[ 303.138548] sd 4:0:0:0: [sdc] Mode Sense: 0b 00 00 08
[ 303.138550] sd 4:0:0:0: [sdc] Assuming drive cache: write through
[ 303.138552] sdc: sdc1
[ 303.161436] sd 4:0:0:0: [sdc] Attached SCSI removable disk
[ 303.161600] usb-storage: device scan complete
[ 303.176188] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 303.176213] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 303.176238] sd 4:0:0:0: Attached scsi generic sg2 type 0

cat /proc/interrupts
CPU0 CPU1
0: 56 1 IO-APIC-edge timer
1: 11 4589 IO-APIC-edge i8042
8: 0 1 IO-APIC-edge rtc
9: 0 1 IO-APIC-fasteoi acpi
12: 0 3 IO-APIC-edge i8042
17: 26 70891 IO-APIC-fasteoi nvidia
18: 0 0 IO-APIC-fasteoi EMU10K1
22: 49 30631 IO-APIC-fasteoi ehci_hcd:usb1
314: 9 4439 PCI-MSI-edge eth0
315: 32 27847 PCI-MSI-edge ahci
NMI: 0 0 Non-maskable interrupts
LOC: 105026 170031 Local timer interrupts
RES: 27983 12360 Rescheduling interrupts
CAL: 1902 237 function call interrupts
TLB: 5129 4330 TLB shootdowns
TRM: 0 0 Thermal event interrupts
THR: 0 0 Threshold APIC interrupts
SPU: 0 0 Spurious interrupts
ERR: 0

cat /proc/cpuinfo
processor : 0
vendor_id : AuthenticAMD
cpu family : 15
model : 67
model name : AMD Athlon(tm) 64 X2 Dual Core Processor 6000+
stepping : 3
cpu MHz : 2000.000
cache size : 1024 KB
physical id : 0
siblings : 2
core id : 0
cpu cores : 2
fpu : yes
fpu_exception : yes
cpuid level : 1
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca
cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt rdtscp
lm 3dnowext 3dnow rep_good pni cx16 lahf_lm cmp_legacy svm extapic cr8_legacy
bogomips : 4023.10
TLB size : 1024 4K pages
clflush size : 64
cache_alignment : 64
address sizes : 40 bits physical, 48 bits virtual
power management: ts fid vid ttp tm stc

processor : 1
vendor_id : AuthenticAMD
cpu family : 15
model : 67
model name : AMD Athlon(tm) 64 X2 Dual Core Processor 6000+
stepping : 3
cpu MHz : 2000.000
cache size : 1024 KB
physical id : 0
siblings : 2
core id : 1
cpu cores : 2
fpu : yes
fpu_exception : yes
cpuid level : 1
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca
cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt rdtscp
lm 3dnowext 3dnow rep_good pni cx16 lahf_lm cmp_legacy svm extapic cr8_legacy
bogomips : 4023.10
TLB size : 1024 4K pages
clflush size : 64
cache_alignment : 64
address sizes : 40 bits physical, 48 bits virtual
power management: ts fid vid ttp tm stc

sh /usr/src/linux/scripts/ver_linux
If some fields are empty or look unusual you may have an old version.
Compare to the current minimal requirements in Documentation/Changes.

Linux energy 2.6.24.2reiser4 #6 SMP Tue Mar 4 17:28:59 CET 2008 x86_64 AMD
Athlon(tm) 64 X2 Dual Core Processor 6000+ AuthenticAMD GNU/Linux

Gnu C 4.2.3
Gnu make 3.81
binutils 2.18.50.0.4.20080208
util-linux 2.13.1
mount 2.13.1
module-init-tools 3.4
e2fsprogs 1.40.6
reiserfsprogs 3.6.19
reiser4progs 1.0.6
PPP 2.4.4
Linux C Library 2.7
Dynamic linker (ldd) 2.7
Procps 3.2.7
Net-tools 1.60
Kbd 1.13
Sh-utils 6.10
udev 118
Modules Loaded nls_iso8859_15 nls_cp850 vfat fat nls_base sg
usb_storage nvidia af_packet k8temp w83627ehf hwmon_vid hwmon i2c_core
snd_seq_midi snd_emu10k1_synth snd_emux_synth snd_seq_virmidi
snd_seq_midi_emul snd_pcm_oss snd_mixer_oss snd_seq_oss snd_seq_midi_event
snd_seq snd_emu10k1 snd_rawmidi firmware_class snd_ac97_codec ac97_bus
snd_pcm snd_seq_device snd_timer snd_page_alloc snd_util_mem snd_hwdep snd
soundcore r8169

If you want/need more information or want me to do something please ask me
directly - I am not subscribed to any of the two lists. If I need to RTFM
please point me to the right FM.

Gl?ck Auf,
Volker


Attachments:
(No filename) (45.66 kB)
.config (37.48 kB)
Download all attachments

2008-03-10 13:05:36

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay.

---
drivers/ata/ahci.c | 1 +
1 file changed, 1 insertion(+)

Index: tree2/drivers/ata/ahci.c
===================================================================
--- tree2.orig/drivers/ata/ahci.c
+++ tree2/drivers/ata/ahci.c
@@ -397,6 +397,7 @@ static const struct ata_port_operations
static const struct ata_port_info ahci_port_info[] = {
/* board_ahci */
{
+ AHCI_HFLAGS (AHCI_HFLAG_NO_PMP),
.flags = AHCI_FLAG_COMMON,
.link_flags = AHCI_LFLAG_COMMON,
.pio_mask = 0x1f, /* pio0-4 */


Attachments:
debug (506.00 B)

2008-03-10 16:58:18

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay.

On Montag, 10. M?rz 2008, Tejun Heo wrote:

> >
> > With 2.6.24 based kernels I get this on cold boot:
> >
> > [ 50.808824] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> > [ 80.743754] ata1.00: qc timeout (cmd 0xec)
> > [ 80.743792] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
>
> Does "irqpoll" make any difference? Also, does the attached patch help?

irqpoll doesn't make any difference on cold boot or reboot (just adding
irqpoll to kernel boot line, right?).

The attached patch does not change anything (except this:
ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
as you can see in the dmesg parts below).

Here are several dmesg-parts:

dmesg part of cold boot with irqpoll but no patch:

[ 28.256323] Driver 'sd' needs updating - please use bus_type methods
[ 28.256396] ahci 0000:00:0a.0: version 3.0
[ 28.256570] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 28.256614] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 29.257150] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 29.257202] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 29.257241] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 29.257442] scsi0 : ahci
[ 29.257538] scsi1 : ahci
[ 29.257604] scsi2 : ahci
[ 29.257671] scsi3 : ahci
[ 29.257760] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 29.257808] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 29.257855] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 29.257903] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 29.889103] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 59.824033] ata1.00: qc timeout (cmd 0xec)
[ 59.824072] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 59.824111] ata1: failed to recover some devices, retrying in 5 secs
[ 65.448472] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 65.449133] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 65.449176] ata1.00: 312581808 sectors, multi 16: LBA48
[ 65.449814] ata1.00: configured for UDMA/133
[ 66.080432] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 66.083950] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 66.083989] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 66.085988] ata2.00: configured for UDMA/133
[ 66.403064] ata3: SATA link down (SStatus 0 SControl 300)
[ 66.722369] ata4: SATA link down (SStatus 0 SControl 300)
[ 66.722458] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 66.722566] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 66.722611] sd 0:0:0:0: [sda] Write Protect is off
[ 66.722649] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 66.722657] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 66.722727] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 66.722771] sd 0:0:0:0: [sda] Write Protect is off
[ 66.722808] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 66.722816] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 66.722865] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 66.748447] sd 0:0:0:0: [sda] Attached SCSI disk
[ 66.748580] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 66.748688] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 66.748733] sd 1:0:0:0: [sdb] Write Protect is off
[ 66.748771] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 66.748779] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 66.748849] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 66.748892] sd 1:0:0:0: [sdb] Write Protect is off
[ 66.748930] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 66.748938] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 66.748987] sdb: sdb1 sdb2
[ 66.779349] sd 1:0:0:0: [sdb] Attached SCSI disk

--------------------------------------------------------------------------------

dmesg-part of reboot with patch and without irqpoll:

[ 28.104714] Driver 'sd' needs updating - please use bus_type methods
[ 28.104787] ahci 0000:00:0a.0: version 3.0
[ 28.104961] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 28.105004] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 28.105342] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 29.105540] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 29.105588] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 29.105628] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 29.105828] scsi0 : ahci
[ 29.105924] scsi1 : ahci
[ 29.105990] scsi2 : ahci
[ 29.106057] scsi3 : ahci
[ 29.106145] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 29.106193] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 29.106240] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 29.106288] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 34.580304] ata1: port is slow to respond, please be patient (Status 0xd0)
[ 39.423111] ata1: softreset failed (device not ready)
[ 39.895423] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 39.896058] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 39.896101] ata1.00: 312581808 sectors, multi 16: LBA48
[ 39.896737] ata1.00: configured for UDMA/133
[ 40.367728] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 40.381404] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 40.381443] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 40.383449] ata2.00: configured for UDMA/133
[ 40.693685] ata3: SATA link down (SStatus 0 SControl 300)
[ 41.006339] ata4: SATA link down (SStatus 0 SControl 300)
[ 41.006428] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 41.006536] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 41.006581] sd 0:0:0:0: [sda] Write Protect is off
[ 41.006618] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 41.006626] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 41.006696] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 41.006739] sd 0:0:0:0: [sda] Write Protect is off
[ 41.006777] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 41.006784] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 41.006833] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 41.040248] sd 0:0:0:0: [sda] Attached SCSI disk
[ 41.040383] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 41.040491] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 41.040541] sd 1:0:0:0: [sdb] Write Protect is off
[ 41.040579] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 41.040588] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 41.040658] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 41.040700] sd 1:0:0:0: [sdb] Write Protect is off
[ 41.040738] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 41.040746] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 41.040795] sdb: sdb1 sdb2
[ 41.083031] sd 1:0:0:0: [sdb] Attached SCSI disk

---------------------------------------------------------------------------------

dmesg-part of cold boot with your patch and without irqpoll:

[ 42.331101] Driver 'sd' needs updating - please use bus_type methods
[ 42.331174] ahci 0000:00:0a.0: version 3.0
[ 42.331348] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 42.331391] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 42.331728] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 43.331928] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 43.331976] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 43.332015] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 43.332216] scsi0 : ahci
[ 43.332312] scsi1 : ahci
[ 43.332378] scsi2 : ahci
[ 43.332444] scsi3 : ahci
[ 43.332533] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 43.332581] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 43.332628] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 43.332675] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 43.804230] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 73.739161] ata1.00: qc timeout (cmd 0xec)
[ 73.739200] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 73.739238] ata1: failed to recover some devices, retrying in 5 secs
[ 79.203946] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 79.204575] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 79.204617] ata1.00: 312581808 sectors, multi 16: LBA48
[ 79.205269] ata1.00: configured for UDMA/133
[ 79.676252] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 79.696817] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 79.696857] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 79.698846] ata2.00: configured for UDMA/133
[ 80.008861] ata3: SATA link down (SStatus 0 SControl 300)
[ 80.321514] ata4: SATA link down (SStatus 0 SControl 300)
[ 80.321604] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 80.321712] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 80.321756] sd 0:0:0:0: [sda] Write Protect is off
[ 80.321794] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 80.321802] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 80.321872] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 80.321915] sd 0:0:0:0: [sda] Write Protect is off
[ 80.321952] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 80.321959] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 80.322008] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 80.353592] sd 0:0:0:0: [sda] Attached SCSI disk
[ 80.353724] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 80.353832] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 80.353877] sd 1:0:0:0: [sdb] Write Protect is off
[ 80.353915] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 80.353923] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 80.353993] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 80.354036] sd 1:0:0:0: [sdb] Write Protect is off
[ 80.354074] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 80.354081] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 80.354130] sdb: sdb1 sdb2
[ 80.360468] sd 1:0:0:0: [sdb] Attached SCSI disk

------------------------------------------------------------------------------------

dmesg part of cold boot with your patch and irqpoll:

[ 38.535559] Driver 'sd' needs updating - please use bus_type methods
[ 38.535632] ahci 0000:00:0a.0: version 3.0
[ 38.535806] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 38.535850] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 38.536187] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 39.536386] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 39.536435] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 39.536474] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 39.536675] scsi0 : ahci
[ 39.536771] scsi1 : ahci
[ 39.536838] scsi2 : ahci
[ 39.536904] scsi3 : ahci
[ 39.536993] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 39.537041] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 39.537088] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 39.537135] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 40.008689] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 69.943620] ata1.00: qc timeout (cmd 0xec)
[ 69.943658] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 69.943697] ata1: failed to recover some devices, retrying in 5 secs
[ 75.408405] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 75.409006] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 75.409049] ata1.00: 312581808 sectors, multi 16: LBA48
[ 75.409721] ata1.00: configured for UDMA/133
[ 75.880711] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 75.901259] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 75.901298] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 75.903294] ata2.00: configured for UDMA/133
[ 76.213320] ata3: SATA link down (SStatus 0 SControl 300)
[ 76.525973] ata4: SATA link down (SStatus 0 SControl 300)
[ 76.526062] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 76.526170] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 76.526215] sd 0:0:0:0: [sda] Write Protect is off
[ 76.526252] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 76.526261] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 76.526330] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 76.526374] sd 0:0:0:0: [sda] Write Protect is off
[ 76.526411] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 76.526418] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 76.526467] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 76.555492] sd 0:0:0:0: [sda] Attached SCSI disk
[ 76.555626] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 76.555735] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 76.555779] sd 1:0:0:0: [sdb] Write Protect is off
[ 76.555817] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 76.555826] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 76.555895] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 76.555944] sd 1:0:0:0: [sdb] Write Protect is off
[ 76.555981] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 76.555989] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 76.556038] sdb: sdb1 sdb2
[ 76.616249] sd 1:0:0:0: [sdb] Attached SCSI disk

-----------------------------------------------------------------------------------------------

If you want me to try more things, just drop a note - or the complete dmesgs.


>
> > and this on reboots:
> >
> > [ 36.619135] ata1: port is slow to respond, please be patient (Status
> > 0xd0) [ 40.982984] ata1: softreset failed (device not ready)
>
> Is this consistent? Do you always get IDENTIFY timeout on cold boots
> and softreset failure on reboots?

yes. I don't watch every (re)boot. But evertime I look into dmesg I see the
IDENTIFY timeout or the softreset failure.

Cold-boot, entering bios, change something, save changes, reboot on exit
counts as 'cold boot'.

> > If I change from 'non raid' to AHCI (because the handbook says that is
> > the way to get NCQ working) boot hangs for looong time and no harddisks
> > are found: (dmesg from systemrescuecd 1.0)
> > ahci 0000:00:0a.0: version 3.0
> > ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
> > ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23 (level, low)
> > -> IRQ 18
> > ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA
> > mode ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
> > PCI: Setting latency timer of device 0000:00:0a.0 to 64
> > scsi3 : ahci
> > scsi4 : ahci
> > scsi5 : ahci
> > scsi6 : ahci
> > ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100 irq 218
> > ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180 irq 218
> > ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200 irq 218
> > ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280 irq 218
> > ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> > APIC error on CPU0: 00(40)
> > scsi_scan_2 used greatest stack depth: 2868 bytes left
> > ata1.00: qc timeout (cmd 0xec)
> > ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
> > ata1: failed to recover some devices, retrying in 5 secs
>
> This one definitely looks like a misrouted IRQ. irqpoll should help.
> This failure is the same for 2.6.23 and 24, right?

yes. This failure has been with all kernels and bios versions I ever tried. I
only added it to show that I tried different bios settings.

With irqpoll the Interrupt reported changes from 218 to 315 (but this
might be caused by the different kernels). But irqpoll does not help - and
with the appropriate kernel-panic because no root-fs found. I can do some
more (re)boots to check that.

Thanks for looking into this.

Gl?ck Auf,
Volker

2008-03-16 08:34:38

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay.

Hi,

I tried three more kernels.

2.6.25-rc5
2.6.23.17
and
2.6.23.11

and I get the same result with all three of them which is puzzling for me,
because once 2.6.23.11 was ok and first came the timeouts then the timeouts
and then the hardware changes (PATA harddisk replaced with SATA harddisk).

this is with 2.6.25-rc5 on a coldboot:

1.360551] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 1.360551] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 1.360551] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 1.360553] scsi0 : ahci
[ 1.360567] scsi1 : ahci
[ 1.360578] scsi2 : ahci
[ 1.360591] scsi3 : ahci
[ 1.360597] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 1.360597] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 1.360597] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 1.360597] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 1.794389] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 31.794187] ata1.00: qc timeout (cmd 0xec)
[ 31.794187] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 31.794187] ata1: failed to recover some devices, retrying in 5 secs
[ 37.431961] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 37.432460] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 37.432460] ata1.00: 312581808 sectors, multi 16: LBA48
[ 37.432990] ata1.00: configured for UDMA/133
[ 38.064932] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 38.085423] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 38.085423] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 38.087332] ata2.00: configured for UDMA/133
[ 38.404590] ata3: SATA link down (SStatus 0 SControl 300)
[ 38.724459] ata4: SATA link down (SStatus 0 SControl 300)
[ 38.923769] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 38.923781] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 38.923781] sd 0:0:0:0: [sda] Write Protect is off
[ 38.923782] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 38.923782] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 38.923782] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 38.923782] sd 0:0:0:0: [sda] Write Protect is off
[ 38.923782] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 38.923782] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 38.923782] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 38.756509] sd 0:0:0:0: [sda] Attached SCSI disk
[ 38.756520] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 38.756526] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 38.756526] sd 1:0:0:0: [sdb] Write Protect is off
[ 38.756526] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 38.756526] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 38.756526] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 38.756526] sd 1:0:0:0: [sdb] Write Protect is off
[ 38.756526] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 38.756526] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 38.756526] sdb: sdb1 sdb2

complete dmesgs are attached. Please ignore the kernel names. After
doing 'make oldconfig' I forgot to remove the 'reiser4_ahci_debug' part. All
three kernels are built from unpatched sources.
All I did was copying the config of 2.6.24 and doing make oldconfig.

If there is something I could/should try/do please tell me so.

Gl?ck Auf,
Volker


Attachments:
(No filename) (3.69 kB)
dmesg_2.6.23.11_coldboot (23.67 kB)
dmesg_2.6.23.17_coldboot (22.94 kB)
dmesg_2.6.25_rc5_coldboot (25.91 kB)
dmesg_2.6.25_rc5_reboot (25.80 kB)
Download all attachments

2008-03-17 07:17:15

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Hi,

I tried some more stuff, replaced the cables, played with bios settings.

No change.

Then I updated to 2.6.24.3 - and no hangs or 'softreset' failures anymore.

[ 38.151334] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 38.151386] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 38.151425] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 38.151626] scsi0 : ahci
[ 38.151722] scsi1 : ahci
[ 38.151788] scsi2 : ahci
[ 38.151853] scsi3 : ahci
[ 38.151942] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 38.151990] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 38.152037] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 38.152085] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 38.783287] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 38.783915] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 38.783957] ata1.00: 312581808 sectors, multi 16: LBA48
[ 38.784590] ata1.00: configured for UDMA/133
[ 39.415249] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 39.435823] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 39.435862] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 39.437862] ata2.00: configured for UDMA/133
[ 39.754512] ata3: SATA link down (SStatus 0 SControl 300)
[ 40.073818] ata4: SATA link down (SStatus 0 SControl 300)
[ 40.073907] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 40.074017] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 40.074061] sd 0:0:0:0: [sda] Write Protect is off
[ 40.074099] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 40.074107] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 40.074178] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 40.074220] sd 0:0:0:0: [sda] Write Protect is off
[ 40.074258] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 40.074265] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 40.074314] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 40.098590] sd 0:0:0:0: [sda] Attached SCSI disk
[ 40.098725] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 40.098835] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 40.098879] sd 1:0:0:0: [sdb] Write Protect is off
[ 40.098917] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 40.098926] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 40.098996] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 40.099039] sd 1:0:0:0: [sdb] Write Protect is off
[ 40.099076] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 40.099084] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 40.099133] sdb: sdb1 sdb2
[ 40.109668] sd 1:0:0:0: [sdb] Attached SCSI disk

The only change to other kernels was making ehci-hcd modular.

I checked already, other kernels still have the IDENTITY error on coldboot and
the softreset failure on reboot even with the changed config.

2.6.24.2 and 2.6.24.3 both have the reiser4 patch added.

So
2.6.24.2 = bad
2.6.24.3 = good
2.6.25-rc5 = bad

Setting AHCI in bios still results in timeouts and harddisks not found.

Gl?ck Auf,
Volker


Attachments:
(No filename) (3.38 kB)
dmesg_2.6.24.3reiser4 (24.40 kB)
Download all attachments

2008-03-18 07:36:36

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Hello,

Volker Armin Hemmann wrote:
> I tried some more stuff, replaced the cables, played with bios settings.
>
> No change.
>
> Then I updated to 2.6.24.3 - and no hangs or 'softreset' failures anymore.

I don't see any libata changes which can cause such difference. Weird.
Is this result reliably reproducible?

> The only change to other kernels was making ehci-hcd modular.

Hmmm...

> I checked already, other kernels still have the IDENTITY error on coldboot and
> the softreset failure on reboot even with the changed config.

Hmmm...

> 2.6.24.2 and 2.6.24.3 both have the reiser4 patch added.
>
> So
> 2.6.24.2 = bad
> 2.6.24.3 = good
> 2.6.25-rc5 = bad
>
> Setting AHCI in bios still results in timeouts and harddisks not found.

Does pci=nomsi help?

--
tejun

2008-03-18 11:00:38

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

On Dienstag, 18. M?rz 2008, Tejun Heo wrote:
> Hello,
>
> Volker Armin Hemmann wrote:
> > I tried some more stuff, replaced the cables, played with bios settings.
> >
> > No change.
> >
> > Then I updated to 2.6.24.3 - and no hangs or 'softreset' failures
> > anymore.
>
> I don't see any libata changes which can cause such difference. Weird.
> Is this result reliably reproducible?

it was for a little bit more than 24h. I booted and rebooted several times to
make sure - and everything was fine, but after a good night and on the Xth
boot, the hang occured again - and since then it is there. Reliable on every
boot :(
(and the softreset failed message on reboots).
Of course, I booted and rebooted several times. And it stays.

Maybe it is the hardware. But I replaced the cables already and smart says the
disk is ok.

SMART Error Log Version: 1
No Errors Logged

SMART Self-test log structure revision number 1
Num Test_Description Status Remaining LifeTime(hours)
LBA
_of_first_error
# 1 Short offline Completed without error 00% 8124 -
# 2 Short offline Completed without error 00% 8067 -
# 3 Short offline Completed without error 00% 3402 -
# 4 Extended offline Completed without error 00% 3374 -
<snip>



>
> > 2.6.24.2 and 2.6.24.3 both have the reiser4 patch added.
> >
> > So
> > 2.6.24.2 = bad
> > 2.6.24.3 = good

it only was good for a couple of boots.

> > 2.6.25-rc5 = bad
> >
> > Setting AHCI in bios still results in timeouts and harddisks not found.
>
> Does pci=nomsi help?

oh yes!
It does. I changed the 'Sata operation mode' setting from 'non raid' to AHCI,
booted with that option:

[ 35.026629] Driver 'sd' needs updating - please use bus_type methods
[ 35.026702] ahci 0000:00:0a.0: version 3.0
[ 35.026877] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 35.026922] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 36.029726] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl SATA mode
[ 36.029777] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 36.029817] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 36.030019] scsi0 : ahci
[ 36.030114] scsi1 : ahci
[ 36.030180] scsi2 : ahci
[ 36.030245] scsi3 : ahci
[ 36.030333] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 23
[ 36.030381] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 23
[ 36.030428] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 23
[ 36.030476] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 23
[ 36.659406] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 36.660019] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 36.660062] ata1.00: 312581808 sectors, multi 16: LBA48
[ 36.660680] ata1.00: configured for UDMA/133
[ 37.291368] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 37.311928] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 37.311968] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 37.313968] ata2.00: configured for UDMA/133
[ 37.630631] ata3: SATA link down (SStatus 0 SControl 300)
[ 37.949937] ata4: SATA link down (SStatus 0 SControl 300)
[ 37.950026] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 37.950136] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 37.950181] sd 0:0:0:0: [sda] Write Protect is off
[ 37.950219] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 37.950227] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 37.950297] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 37.950340] sd 0:0:0:0: [sda] Write Protect is off
[ 37.950378] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 37.950385] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 37.950434] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 37.984324] sd 0:0:0:0: [sda] Attached SCSI disk
[ 37.984459] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 37.984568] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 37.984612] sd 1:0:0:0: [sdb] Write Protect is off
[ 37.984650] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 37.984659] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 37.984729] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 37.984772] sd 1:0:0:0: [sdb] Write Protect is off
[ 37.984810] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 37.984817] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 37.984866] sdb: sdb1 sdb2
[ 37.994164] sd 1:0:0:0: [sdb] Attached SCSI disk

the most obvious change are the different interrupts: 23 instead of 315
(non-raid, without nosmi) or 218 (systemrescuecd 1.0, ahci setting, without
nosmi)

full dmesg is attached.

Thanks for looking into this mess.

Gl?ck Auf,
Volker


Attachments:
(No filename) (5.03 kB)
dmesg_2.6.24.3_nosmi (24.47 kB)
Download all attachments

2008-03-18 12:56:40

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Volker Armin Hemmann wrote:
> it was for a little bit more than 24h. I booted and rebooted several times to
> make sure - and everything was fine, but after a good night and on the Xth
> boot, the hang occured again - and since then it is there. Reliable on every
> boot :(
> (and the softreset failed message on reboots).
> Of course, I booted and rebooted several times. And it stays.
>
> Maybe it is the hardware. But I replaced the cables already and smart says the
> disk is ok.

Does the attached patch make any difference?

>>> Setting AHCI in bios still results in timeouts and harddisks not found.
>> Does pci=nomsi help?
>
> oh yes!
> It does. I changed the 'Sata operation mode' setting from 'non raid' to AHCI,
> booted with that option:
>
> the most obvious change are the different interrupts: 23 instead of 315
> (non-raid, without nosmi) or 218 (systemrescuecd 1.0, ahci setting, without
> nosmi)

Yeah, that sounds about right. Hmm... Can you post the result of "lspci
-nn"?

--
tejun


Attachments:
ahci-no-hrst.patch (791.00 B)

2008-03-18 13:33:32

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

On Dienstag, 18. M?rz 2008, Tejun Heo wrote:
> failed message on reboots).
>
> > Of course, I booted and rebooted several times. And it stays.
> >
> > Maybe it is the hardware. But I replaced the cables already and smart
> > says the disk is ok.
>
> Does the attached patch make any difference?

patching file drivers/ata/ahci.c
Hunk #1 succeeded at 397 (offset -5 lines).
Hunk #2 succeeded at 1819 (offset -45 lines).

built&booted, same results as allways. With non-raid setting, IDENTIFY error
on cold boot and sofreset error on reboot:
(with patch, without nosmi):

cold boot:
[ 35.397857] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 36.398054] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 36.398102] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 36.398142] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 36.398343] scsi0 : ahci
[ 36.398439] scsi1 : ahci
[ 36.398505] scsi2 : ahci
[ 36.398570] scsi3 : ahci
[ 36.398659] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 36.398707] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 36.398754] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 36.398801] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 36.870357] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 66.805287] ata1.00: qc timeout (cmd 0xec)
[ 66.805326] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 66.805365] ata1: failed to recover some devices, retrying in 5 secs
[ 72.270071] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 72.270674] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 72.270716] ata1.00: 312581808 sectors, multi 16: LBA48
[ 72.271355] ata1.00: configured for UDMA/133
[ 72.742378] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 72.762946] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 72.762986] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 72.764976] ata2.00: configured for UDMA/133
[ 73.074987] ata3: SATA link down (SStatus 0 SControl 300)
[ 73.387641] ata4: SATA link down (SStatus 0 SControl 300)
[ 73.387729] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 73.387839] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 73.387883] sd 0:0:0:0: [sda] Write Protect is off
[ 73.387921] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 73.387929] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 73.387999] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 73.388042] sd 0:0:0:0: [sda] Write Protect is off
[ 73.388080] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 73.388087] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 73.388136] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 73.418040] sd 0:0:0:0: [sda] Attached SCSI disk
[ 73.418174] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 73.418282] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 73.418327] sd 1:0:0:0: [sdb] Write Protect is off
[ 73.418365] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 73.418373] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 73.418448] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 73.418491] sd 1:0:0:0: [sdb] Write Protect is off
[ 73.418529] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 73.418537] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 73.418586] sdb: sdb1 sdb2
[ 73.441032] sd 1:0:0:0: [sdb] Attached SCSI disk


reboot:
[ 47.228096] ahci 0000:00:0a.0: version 3.0
[ 47.228271] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 47.228315] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 47.228649] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 48.228845] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 48.228893] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 48.228932] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 48.229134] scsi0 : ahci
[ 48.229230] scsi1 : ahci
[ 48.229296] scsi2 : ahci
[ 48.229361] scsi3 : ahci
[ 48.229450] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 48.229497] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 48.229545] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 48.229592] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 53.703608] ata1: port is slow to respond, please be patient (Status 0xd0)
[ 58.546416] ata1: softreset failed (device not ready)
[ 58.706072] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 58.706695] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 58.706738] ata1.00: 312581808 sectors, multi 16: LBA48
[ 58.707375] ata1.00: configured for UDMA/133
[ 59.178379] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 59.198957] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 59.198996] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 59.200994] ata2.00: configured for UDMA/133
[ 59.510988] ata3: SATA link down (SStatus 0 SControl 300)
[ 59.823642] ata4: SATA link down (SStatus 0 SControl 300)
[ 59.823730] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 59.823841] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 59.823885] sd 0:0:0:0: [sda] Write Protect is off
[ 59.823923] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 59.823931] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 59.824001] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 59.824044] sd 0:0:0:0: [sda] Write Protect is off
[ 59.824081] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 59.824089] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 59.824138] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 59.854855] sd 0:0:0:0: [sda] Attached SCSI disk
[ 59.854992] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 59.855100] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 59.855145] sd 1:0:0:0: [sdb] Write Protect is off
[ 59.855183] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 59.855192] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 59.855261] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 59.855304] sd 1:0:0:0: [sdb] Write Protect is off
[ 59.855342] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 59.855349] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 59.855399] sdb: sdb1 sdb2
[ 59.902185] sd 1:0:0:0: [sdb] Attached SCSI disk

>
> >>> Setting AHCI in bios still results in timeouts and harddisks not found.
> >>
> >> Does pci=nomsi help?
> >
> > oh yes!
> > ?It does. I changed the 'Sata operation mode' setting from 'non raid' to
> > AHCI, booted with that option:
> >
> > the most obvious change are the different interrupts: 23 instead of 315
> > (non-raid, without nosmi) or 218 (systemrescuecd 1.0, ahci setting,
> > without nosmi)

btw, with 2.6.24.3, without nosmi and my own config it stays at 315. So
non-raid and ahci both get the same interrupt, and the same error (ahci
always gives the IDENTIFY error, on cold boots and reboots). But non-raid
finds the harddisks at the end, ahci does not.

>
> Yeah, that sounds about right. ?Hmm... Can you post the result of "lspci
> -nn"?

lspci -nn
00:00.0 RAM memory [0500]: nVidia Corporation MCP65 Memory Controller
[10de:0444] (rev a1)
00:01.0 ISA bridge [0601]: nVidia Corporation MCP65 LPC Bridge [10de:0441]
(rev a2)
00:01.1 SMBus [0c05]: nVidia Corporation MCP65 SMBus [10de:0446] (rev a1)
00:01.2 RAM memory [0500]: nVidia Corporation MCP65 Memory Controller
[10de:0445] (rev a1)
00:02.0 USB Controller [0c03]: nVidia Corporation MCP65 USB Controller
[10de:0454] (rev a1)
00:02.1 USB Controller [0c03]: nVidia Corporation MCP65 USB Controller
[10de:0455] (rev a1)
00:08.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI bridge [10de:0449]
(rev a1)
00:09.0 IDE interface [0101]: nVidia Corporation MCP65 IDE [10de:0448] (rev
a1)
00:0a.0 SATA controller [0106]: nVidia Corporation MCP65 AHCI Controller
[10de:044d] (rev a1)
00:0b.0 PCI bridge [0604]: nVidia Corporation Unknown device [10de:045b] (rev
a1)
00:0c.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:045a] (rev a1)
00:0d.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:0458] (rev a1)
00:0e.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:0459] (rev a1)
00:18.0 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
HyperTransport Technology Configuration [1022:1100]
00:18.1 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Address Map [1022:1101]
00:18.2 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
DRAM Controller [1022:1102]
00:18.3 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Miscellaneous Control [1022:1103]
01:00.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd.
RTL8111/8168B PCI Express Gigabit Ethernet controller [10ec:8168] (rev 01)
02:08.0 SCSI storage controller [0100]: Adaptec AHA-2944UW / AIC-7884U
[9004:8478] (rev 01)
02:09.0 Multimedia audio controller [0401]: Creative Labs SB0400 Audigy2 Value
[1102:0008]
04:00.0 VGA compatible controller [0300]: nVidia Corporation Unknown device
[10de:0402] (rev a1)

Gl?ck Auf,
Volker

2008-03-19 19:46:50

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Hi,
On Mittwoch, 19. M?rz 2008, Tejun Heo wrote:
> Tejun Heo wrote:

> Just in case, here's the updated patch.

I tried your patch (on freshly unpacked sources).

non-raid setting in bios,

coldboot:

[ 37.123980] ahci 0000:00:0a.0: version 3.0
[ 37.124155] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 37.124199] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 37.124533] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 38.124731] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 38.124780] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 38.124819] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 38.125020] scsi0 : ahci
[ 38.125116] scsi1 : ahci
[ 38.125182] scsi2 : ahci
[ 38.125248] scsi3 : ahci
[ 38.125337] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 38.125384] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 38.125432] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 38.125479] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 38.597035] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 38.597732] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 38.597775] ata1.00: 312581808 sectors, multi 16: LBA48
[ 38.598405] ata1.00: configured for UDMA/133
[ 39.069342] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 39.084225] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 39.084264] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 39.086268] ata2.00: configured for UDMA/133
[ 39.405277] ata3: SATA link down (SStatus 0 SControl 300)
[ 39.724583] ata4: SATA link down (SStatus 0 SControl 300)
[ 39.724672] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 39.724782] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 39.724826] sd 0:0:0:0: [sda] Write Protect is off
[ 39.724864] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 39.724872] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 39.724942] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 39.724985] sd 0:0:0:0: [sda] Write Protect is off
[ 39.725023] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 39.725030] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 39.725079] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 39.763787] sd 0:0:0:0: [sda] Attached SCSI disk
[ 39.763923] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 39.764032] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 39.764077] sd 1:0:0:0: [sdb] Write Protect is off
[ 39.764115] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 39.764124] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 39.764194] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 39.764237] sd 1:0:0:0: [sdb] Write Protect is off
[ 39.764275] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 39.764283] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 39.764332] sdb: sdb1 sdb2
[ 39.793043] sd 1:0:0:0: [sdb] Attached SCSI disk

reboot:
[ 39.522696] ahci 0000:00:0a.0: version 3.0
[ 39.522871] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 39.522914] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 39.523247] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
[ 40.523447] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 40.523496] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
[ 40.523535] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 40.523737] scsi0 : ahci
[ 40.523832] scsi1 : ahci
[ 40.523898] scsi2 : ahci
[ 40.523964] scsi3 : ahci
[ 40.524053] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 40.524100] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 40.524148] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 40.524195] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 40.995751] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 40.996374] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 40.996416] ata1.00: 312581808 sectors, multi 16: LBA48
[ 40.997049] ata1.00: configured for UDMA/133
[ 41.468058] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 41.471873] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 41.471913] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 41.473917] ata2.00: configured for UDMA/133
[ 41.790689] ata3: SATA link down (SStatus 0 SControl 300)
[ 42.109995] ata4: SATA link down (SStatus 0 SControl 300)
[ 42.110084] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 42.110194] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 42.110238] sd 0:0:0:0: [sda] Write Protect is off
[ 42.110276] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 42.110284] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.110354] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 42.110397] sd 0:0:0:0: [sda] Write Protect is off
[ 42.110435] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 42.110442] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.110492] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 42.140153] sd 0:0:0:0: [sda] Attached SCSI disk
[ 42.140289] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 42.140398] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 42.140443] sd 1:0:0:0: [sdb] Write Protect is off
[ 42.140481] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 42.140489] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.140559] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 42.140602] sd 1:0:0:0: [sdb] Write Protect is off
[ 42.140639] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 42.140647] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 42.140696] sdb: sdb1 sdb2
[ 42.167607] sd 1:0:0:0: [sdb] Attached SCSI disk

Full dmesgs attached.

Gl?ck Auf
Volker


Attachments:
(No filename) (6.44 kB)
dmesg_2.6.24.3reiser4ahci_no_srst_coldboot (24.10 kB)
dmesg_2.6.24.3reiser4ahci_no_srst_reboot (24.10 kB)
Download all attachments

2008-03-19 19:47:38

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

On Mittwoch, 19. M?rz 2008, Tejun Heo wrote:
> x UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
>
> > irq 315
> > [ ? 38.125479] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port
> > 0xf9dfc280 irq 315
> > [ ? 38.597035] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> > [ ? 38.597732] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max
> > UDMA/133 [ ? 38.597775] ata1.00: 312581808 sectors, multi 16: LBA48
> > [ ? 38.598405] ata1.00: configured for UDMA/133
> > [ ? 39.069342] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> > [ ? 39.084225] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
> > [ ? 39.084264] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth
> > 0/32) [ ? 39.086268] ata2.00: configured for UDMA/133
>
> So, just to confirm. ?With the updated patch, you don't see any problem,
> right?

Correct. With the updated patch I don't see problems in 'non-raid' mode. AHCI
mode still has problems without nosmi. But that is an entirely different
problem, right?

Gl?ck Auf,
Volker

2008-03-19 19:50:46

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

On Mittwoch, 19. M?rz 2008, Tejun Heo wrote:
> Volker Armin Hemmann wrote:
> > On Mittwoch, 19. M?rz 2008, Tejun Heo wrote:
> >> x UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
> >>
> >>> irq 315
> >>> [ 38.125479] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port
> >>> 0xf9dfc280 irq 315
> >>> [ 38.597035] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> >>> [ 38.597732] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max
> >>> UDMA/133 [ 38.597775] ata1.00: 312581808 sectors, multi 16: LBA48
> >>> [ 38.598405] ata1.00: configured for UDMA/133
> >>> [ 39.069342] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> >>> [ 39.084225] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
> >>> [ 39.084264] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth
> >>> 0/32) [ 39.086268] ata2.00: configured for UDMA/133
> >>
> >> So, just to confirm. With the updated patch, you don't see any problem,
> >> right?
> >
> > Correct. With the updated patch I don't see problems in 'non-raid' mode.
> > AHCI mode still has problems without nosmi. But that is an entirely
> > different problem, right?
>
> Yeap, can you please post the result of "lspci -nn"?

with AHCI+nosmi, 2.6.24.3:
lspci -nn
00:00.0 RAM memory [0500]: nVidia Corporation MCP65 Memory Controller
[10de:0444] (rev a1)
00:01.0 ISA bridge [0601]: nVidia Corporation MCP65 LPC Bridge [10de:0441]
(rev a2)
00:01.1 SMBus [0c05]: nVidia Corporation MCP65 SMBus [10de:0446] (rev a1)
00:01.2 RAM memory [0500]: nVidia Corporation MCP65 Memory Controller
[10de:0445] (rev a1)
00:02.0 USB Controller [0c03]: nVidia Corporation MCP65 USB Controller
[10de:0454] (rev a1)
00:02.1 USB Controller [0c03]: nVidia Corporation MCP65 USB Controller
[10de:0455] (rev a1)
00:08.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI bridge [10de:0449]
(rev a1)
00:09.0 IDE interface [0101]: nVidia Corporation MCP65 IDE [10de:0448] (rev
a1)
00:0a.0 SATA controller [0106]: nVidia Corporation MCP65 AHCI Controller
[10de:044d] (rev a1)
00:0b.0 PCI bridge [0604]: nVidia Corporation Unknown device [10de:045b] (rev
a1)
00:0c.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:045a] (rev a1)
00:0d.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:0458] (rev a1)
00:0e.0 PCI bridge [0604]: nVidia Corporation MCP65 PCI Express bridge
[10de:0459] (rev a1)
00:18.0 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
HyperTransport Technology Configuration [1022:1100]
00:18.1 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Address Map [1022:1101]
00:18.2 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
DRAM Controller [1022:1102]
00:18.3 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron]
Miscellaneous Control [1022:1103]
01:00.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd.
RTL8111/8168B PCI Express Gigabit Ethernet controller [10ec:8168] (rev 01)
02:08.0 SCSI storage controller [0100]: Adaptec AHA-2944UW / AIC-7884U
[9004:8478] (rev 01)
02:09.0 Multimedia audio controller [0401]: Creative Labs SB0400 Audigy2 Value
[1102:0008]
04:00.0 VGA compatible controller [0300]: nVidia Corporation Unknown device
[10de:0402] (rev a1)

I booted into systemrescuecd (which is the easiest way to get the error and be
able to safe the stuff) - lspci is of course identical, but /proc/interrupts
is not.

2.6.24.3:
cat /proc/interrupts
CPU0 CPU1
0: 57 1 IO-APIC-edge timer
1: 1 544 IO-APIC-edge i8042
8: 0 1 IO-APIC-edge rtc
9: 0 1 IO-APIC-fasteoi acpi
12: 0 3 IO-APIC-edge i8042
17: 22 21598 IO-APIC-fasteoi nvidia
18: 0 0 IO-APIC-fasteoi EMU10K1
19: 5 1132 IO-APIC-fasteoi eth0
22: 30 21627 IO-APIC-fasteoi ehci_hcd:usb1
23: 29 23078 IO-APIC-fasteoi ahci
NMI: 0 0 Non-maskable interrupts
LOC: 41299 60204 Local timer interrupts
RES: 14872 22506 Rescheduling interrupts
CAL: 1783 173 function call interrupts
TLB: 2668 2406 TLB shootdowns
TRM: 0 0 Thermal event interrupts
THR: 0 0 Threshold APIC interrupts
SPU: 0 0 Spurious interrupts
ERR: 0

systemrescuecd's rescue64 kernel:
CPU0 CPU1
0: 148 1 IO-APIC-edge timer
1: 0 946 IO-APIC-edge i8042
8: 0 25 IO-APIC-edge rtc
9: 0 1 IO-APIC-fasteoi acpi
12: 0 4 IO-APIC-edge i8042
14: 0 0 IO-APIC-edge libata
15: 0 0 IO-APIC-edge libata
18: 0 16 IO-APIC-fasteoi aic7xxx
21: 0 0 IO-APIC-fasteoi ohci_hcd:usb2
22: 2 8158 IO-APIC-fasteoi ehci_hcd:usb1
1274: 0 0 PCI-MSI-edge ahci
1275: 0 453 PCI-MSI-edge eth0
NMI: 0 0 Non-maskable interrupts
LOC: 16082 18972 Local timer interrupts
RES: 7042 6008 Rescheduling interrupts
CAL: 48 45 function call interrupts
TLB: 1836 1843 TLB shootdowns
TRM: 0 0 Thermal event interrupts
THR: 0 0 Threshold APIC interrupts
SPU: 0 0 Spurious interrupts
ERR: 6

dmesg of this is attached.

Gl?ck Auf,
Volker


Attachments:
(No filename) (5.52 kB)
dmesg_rescue64 (25.88 kB)
Download all attachments

2008-03-19 22:24:17

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Volker Armin Hemmann wrote:
> On Dienstag, 18. M?rz 2008, Tejun Heo wrote:
>> failed message on reboots).
>>
>>> Of course, I booted and rebooted several times. And it stays.
>>>
>>> Maybe it is the hardware. But I replaced the cables already and smart
>>> says the disk is ok.
>> Does the attached patch make any difference?
>
> patching file drivers/ata/ahci.c
> Hunk #1 succeeded at 397 (offset -5 lines).
> Hunk #2 succeeded at 1819 (offset -45 lines).

It's okay. It's because I was lazy and generated the patch against
devel tree.

> built&booted, same results as allways. With non-raid setting, IDENTIFY error
> on cold boot and sofreset error on reboot:
> (with patch, without nosmi):

Oh... I see. I made a mistake. In ahci.c, what I intended was making
ahci_softreset NULL not ahci_hardreset. Can you please change that and
test again?

--
tejun

2008-03-19 22:36:36

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Tejun Heo wrote:
> Volker Armin Hemmann wrote:
>> On Dienstag, 18. M?rz 2008, Tejun Heo wrote:
>>> failed message on reboots).
>>>
>>>> Of course, I booted and rebooted several times. And it stays.
>>>>
>>>> Maybe it is the hardware. But I replaced the cables already and smart
>>>> says the disk is ok.
>>> Does the attached patch make any difference?
>> patching file drivers/ata/ahci.c
>> Hunk #1 succeeded at 397 (offset -5 lines).
>> Hunk #2 succeeded at 1819 (offset -45 lines).
>
> It's okay. It's because I was lazy and generated the patch against
> devel tree.
>
>> built&booted, same results as allways. With non-raid setting, IDENTIFY error
>> on cold boot and sofreset error on reboot:
>> (with patch, without nosmi):
>
> Oh... I see. I made a mistake. In ahci.c, what I intended was making
> ahci_softreset NULL not ahci_hardreset. Can you please change that and
> test again?
>

Just in case, here's the updated patch.

--
tejun


Attachments:
ahci-no-srst.patch (805.00 B)

2008-03-19 22:40:15

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Volker Armin Hemmann wrote:
> On Mittwoch, 19. M?rz 2008, Tejun Heo wrote:
>> x UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
>>
>>> irq 315
>>> [ 38.125479] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port
>>> 0xf9dfc280 irq 315
>>> [ 38.597035] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>>> [ 38.597732] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max
>>> UDMA/133 [ 38.597775] ata1.00: 312581808 sectors, multi 16: LBA48
>>> [ 38.598405] ata1.00: configured for UDMA/133
>>> [ 39.069342] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>>> [ 39.084225] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
>>> [ 39.084264] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth
>>> 0/32) [ 39.086268] ata2.00: configured for UDMA/133
>> So, just to confirm. With the updated patch, you don't see any problem,
>> right?
>
> Correct. With the updated patch I don't see problems in 'non-raid' mode. AHCI
> mode still has problems without nosmi. But that is an entirely different
> problem, right?

Yeap, can you please post the result of "lspci -nn"?

--
tejun

2008-03-19 23:16:29

by Tejun Heo

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Volker Armin Hemmann wrote:
>
> [ 37.123980] ahci 0000:00:0a.0: version 3.0
> [ 37.124155] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
> [ 37.124199] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
> (level, low) -> IRQ 23
> [ 37.124533] ahci 0000:00:0a.0: controller can't do PMP, turning off CAP_PMP
> [ 38.124731] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
> impl IDE mode
> [ 38.124780] ahci 0000:00:0a.0: flags: 64bit sntf led clo pio
> [ 38.124819] PCI: Setting latency timer of device 0000:00:0a.0 to 64
> [ 38.125020] scsi0 : ahci
> [ 38.125116] scsi1 : ahci
> [ 38.125182] scsi2 : ahci
> [ 38.125248] scsi3 : ahci
> [ 38.125337] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
> irq 315
> [ 38.125384] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
> irq 315
> [ 38.125432] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
> irq 315
> [ 38.125479] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
> irq 315
> [ 38.597035] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [ 38.597732] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
> [ 38.597775] ata1.00: 312581808 sectors, multi 16: LBA48
> [ 38.598405] ata1.00: configured for UDMA/133
> [ 39.069342] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [ 39.084225] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
> [ 39.084264] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
> [ 39.086268] ata2.00: configured for UDMA/133

So, just to confirm. With the updated patch, you don't see any problem,
right?

--
tejun

2008-03-27 16:40:54

by Volker Armin Hemmann

[permalink] [raw]
Subject: Re: 2.6.24.X: SATA/AHCI related boot delay. - not with 2.6.24.3

Hi,

I tried 2.6.25-rc7 and there are some changes.

Bad news first.
With AHCI in bios I get this:
[ 0.363838] ------------[ cut here ]------------
[ 0.363838] WARNING: at drivers/ata/ahci.c:645 ahci_init_one+0x190/0xa3a()
[ 0.363838] Modules linked in:
[ 0.363838] Pid: 1, comm: swapper Not tainted 2.6.25-rc7 #3
[ 0.363838]
[ 0.363838] Call Trace:
[ 0.363838] [<ffffffff8022c545>] warn_on_slowpath+0x51/0x63
[ 0.363838] [<ffffffff80220061>] __ioremap+0x8/0x197
[ 0.363838] [<ffffffff8038e218>] pci_conf1_read+0xb2/0xbd
[ 0.363841] [<ffffffff802f3076>] pcim_iomap_release+0x0/0x2c
[ 0.363880] [<ffffffff802f3076>] pcim_iomap_release+0x0/0x2c
[ 0.363920] [<ffffffff80346ca1>] devres_find+0x4b/0x65
[ 0.363959] [<ffffffff8036a405>] ahci_init_one+0x190/0xa3a
[ 0.363998] [<ffffffff802af91f>] sysfs_addrm_finish+0x1d/0x209
[ 0.364038] [<ffffffff80283f98>] ifind+0x34/0x8d
[ 0.364076] [<ffffffff802af636>] sysfs_find_dirent+0x1b/0x2f
[ 0.364115] [<ffffffff802eb4c5>] ida_get_new_above+0xf0/0x180
[ 0.364154] [<ffffffff802af91f>] sysfs_addrm_finish+0x1d/0x209
[ 0.364194] [<ffffffff802b0458>] sysfs_create_link+0xb6/0x102
[ 0.364234] [<ffffffff802f83c4>] pci_device_probe+0x4c/0x72
[ 0.364273] [<ffffffff80344874>] driver_probe_device+0xb5/0x132
[ 0.364312] [<ffffffff80344a07>] __driver_attach+0x6f/0xaf
[ 0.364350] [<ffffffff80344998>] __driver_attach+0x0/0xaf
[ 0.364389] [<ffffffff80344998>] __driver_attach+0x0/0xaf
[ 0.364429] [<ffffffff80343c82>] bus_for_each_dev+0x44/0x6f
[ 0.364468] [<ffffffff803444a0>] bus_add_driver+0xae/0x1f6
[ 0.364507] [<ffffffff80344c36>] driver_register+0x59/0xce
[ 0.364546] [<ffffffff802f8605>] __pci_register_driver+0x4a/0x7d
[ 0.364587] [<ffffffff804dd6b8>] kernel_init+0x14f/0x2b9
[ 0.364626] [<ffffffff8020be98>] child_rip+0xa/0x12
[ 0.364670] [<ffffffff804dd569>] kernel_init+0x0/0x2b9
[ 0.364709] [<ffffffff8020be8e>] child_rip+0x0/0x12
[ 0.364746]
[ 0.364783] ---[ end trace 1d94b6d2f6a54ae4 ]---
[ 1.370499] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
imp
l SATA mode
[ 1.370499] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 1.370499] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 1.370501] scsi0 : ahci
[ 1.370509] scsi1 : ahci
[ 1.370517] scsi2 : ahci
[ 1.370526] scsi3 : ahci
[ 1.370532] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq
23
[ 1.370532] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq
23
[ 1.370532] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq
23
[ 1.370532] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq
23
[ 1.801113] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1.801617] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 1.801617] ata1.00: 312581808 sectors, multi 16: LBA48
[ 1.802150] ata1.00: configured for UDMA/133
[ 2.434072] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.447930] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 2.447930] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 2.449834] ata2.00: configured for UDMA/133
[ 2.767054] ata3: SATA link down (SStatus 0 SControl 300)
[ 3.086918] ata4: SATA link down (SStatus 0 SControl 300)
[ 3.290523] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ
: 0 ANSI: 5
[ 3.290535] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 3.290535] sd 0:0:0:0: [sda] Write Protect is off
[ 3.290535] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 3.290535] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
does
n't support DPO or FUA
[ 3.290535] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 3.290535] sd 0:0:0:0: [sda] Write Protect is off
[ 3.290535] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 3.290535] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
does
n't support DPO or FUA
[ 3.290535] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 3.122431] sd 0:0:0:0: [sda] Attached SCSI disk
[ 3.122593] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ
: 0 ANSI: 5
[ 3.122721] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 3.122768] sd 1:0:0:0: [sdb] Write Protect is off
[ 3.122806] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 3.122820] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
does
n't support DPO or FUA
[ 3.122900] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 3.122946] sd 1:0:0:0: [sdb] Write Protect is off
[ 3.122984] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 3.122998] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
does
n't support DPO or FUA
[ 3.123047] sdb: sdb1 sdb2
[ 3.148928] sd 1:0:0:0: [sdb] Attached SCSI disk

with nomsi. Without nomsi drives are still not found. Evil or not, it looks
scary ;)

dmesg&config is attached.

Now the good ones. With non-raid set in bios box boots cleanly without hang:

[ 0.360492] Driver 'sd' needs updating - please use bus_type methods
[ 0.360498] ahci 0000:00:0a.0: version 3.0
[ 0.360498] ACPI: PCI Interrupt Link [LSA0] enabled at IRQ 23
[ 0.360498] ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LSA0] -> GSI 23
(level, low) -> IRQ 23
[ 1.363826] ahci 0000:00:0a.0: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf
impl IDE mode
[ 1.363826] ahci 0000:00:0a.0: flags: 64bit sntf led clo pmp pio
[ 1.363826] PCI: Setting latency timer of device 0000:00:0a.0 to 64
[ 1.363828] scsi0 : ahci
[ 1.363836] scsi1 : ahci
[ 1.363844] scsi2 : ahci
[ 1.363851] scsi3 : ahci
[ 1.363857] ata1: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc100
irq 315
[ 1.363857] ata2: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc180
irq 315
[ 1.363857] ata3: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc200
irq 315
[ 1.363857] ata4: SATA max UDMA/133 abar m8192@0xf9dfc000 port 0xf9dfc280
irq 315
[ 1.794371] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1.794871] ata1.00: ATA-7: WDC WD1600JS-00MHB1, 10.02E01, max UDMA/133
[ 1.794871] ata1.00: 312581808 sectors, multi 16: LBA48
[ 1.795403] ata1.00: configured for UDMA/133
[ 2.427327] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 2.447811] ata2.00: ATA-8: SAMSUNG HD501LJ, CR100-12, max UDMA7
[ 2.447811] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 2.449717] ata2.00: configured for UDMA/133
[ 2.766980] ata3: SATA link down (SStatus 0 SControl 300)
[ 3.086847] ata4: SATA link down (SStatus 0 SControl 300)
[ 3.290513] scsi 0:0:0:0: Direct-Access ATA WDC WD1600JS-00M 10.0
PQ: 0 ANSI: 5
[ 3.290525] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 3.290525] sd 0:0:0:0: [sda] Write Protect is off
[ 3.290525] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 3.290525] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 3.290525] sd 0:0:0:0: [sda] 312581808 512-byte hardware sectors (160042
MB)
[ 3.290525] sd 0:0:0:0: [sda] Write Protect is off
[ 3.290525] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 3.290525] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 3.290525] sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[ 3.123110] sd 0:0:0:0: [sda] Attached SCSI disk
[ 3.123121] scsi 1:0:0:0: Direct-Access ATA SAMSUNG HD501LJ CR10
PQ: 0 ANSI: 5
[ 3.123131] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 3.123131] sd 1:0:0:0: [sdb] Write Protect is off
[ 3.123131] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 3.123131] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 3.123131] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors (500108
MB)
[ 3.123131] sd 1:0:0:0: [sdb] Write Protect is off
[ 3.123131] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 3.123131] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 3.123131] sdb: sdb1 sdb2
[ 3.127056] sd 1:0:0:0: [sdb] Attached SCSI disk

again, dmesg is attached.

Gl?ck Auf,
Volker


Attachments:
(No filename) (8.17 kB)
dmesg_2.6.25_rc7 (30.17 kB)
dmesg_2.6.25_rc7_non_raid (28.18 kB)
.config (39.46 kB)
Download all attachments