ALi SATA problem

ALi SATA problem

[Pavel Mateja]

Hello,
I got some strange problem with my ULi M5288 SATA. I have one disk connected 
to 1st port. Dmesg says:

ahci 0000:00:16.1: AHCI 0001.0000 32 slots 4 ports 3 Gbps 0xf impl IDE mode
ahci 0000:00:16.1: flags: ncq ilck pm led clopmp pio slum part
scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xffffc20000028500 ctl 0x0000000000000000 bmdma 
0x0000000000000000 irq 21
ata2: SATA max UDMA/133 cmd 0xffffc20000028580 ctl 0x0000000000000000 bmdma 
0x0000000000000000 irq 21
ata3: SATA max UDMA/133 cmd 0xffffc20000028600 ctl 0x0000000000000000 bmdma 
0x0000000000000000 irq 21
ata4: SATA max UDMA/133 cmd 0xffffc20000028680 ctl 0x0000000000000000 bmdma 
0x0000000000000000 irq 21
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: ATA-6: WDC WD360GD-00FLC0, 33.08F33,max UDMA/133
ata1.00: 72303840 sectors, multi 16: LBA48
ata1.00: configured for UDMA/133
ata2: SATA link down (SStatus 0 SControl 300)
ata3: SATA link down (SStatus 0 SControl 300)
ata4: SATA link down (SStatus 0 SControl 300)

This is true, but shortly after boot my dmesg log gets flooded by

ata4: soft resetting port
ata4: SATA link down (SStatus 1 SControl 300)
ata4: EH complete
ata2: soft resetting port
ata2: SATA link down (SStatus 1 SControl 300)
ata2: EH complete
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
ata2: (irq_stat 0x00000040, connection status changed)
ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
ata4: (irq_stat 0x00000040, connection status changed)
ata2: soft resetting port
ata2: SATA link down (SStatus 1 SControl 300)
ata2: EH complete
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
ata2: (irq_stat 0x00000040, connection status changed)

and scsi_eh_1 and/or scsi_eh_3 processes came to D state for a while.

I can provide any additional info upon request. Kernel is 2.6.22.1 with 
suspend2 patch but vanilla is doing same thing.
Had anybody seen such thing?
-- 
Pavel Mateja

Re: ALi SATA problem

[Tejun Heo]

Pavel Mateja wrote:
> ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> ata2: (irq_stat 0x00000040, connection status changed)
> ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> ata4: (irq_stat 0x00000040, connection status changed)
> ata2: soft resetting port

Please check power supply and re-cable things.

-- 
tejun

Re: ALi SATA problem

[Pavel Mateja]

Dne Wednesday 18 of July 2007 11:35:00 jste napsal(a):
> Pavel Mateja wrote:
> > ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> > ata2: (irq_stat 0x00000040, connection status changed)
> > ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> > ata4: (irq_stat 0x00000040, connection status changed)
> > ata2: soft resetting port
>
> Please check power supply and re-cable things.

Funny thing is there are no cables to be checked. Nothing is connected to 
those ports. Just ata1 is occupied by disk.
-- 
Pavel Mateja

Re: ALi SATA problem

[Tejun Heo]

Pavel Mateja wrote:
> Dne Wednesday 18 of July 2007 11:35:00 jste napsal(a):
>> Pavel Mateja wrote:
>>> ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
>>> ata2: (irq_stat 0x00000040, connection status changed)
>>> ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
>>> ata4: (irq_stat 0x00000040, connection status changed)
>>> ata2: soft resetting port
>> Please check power supply and re-cable things.
> 
> Funny thing is there are no cables to be checked. Nothing is connected to 
> those ports. Just ata1 is occupied by disk.

Sigh... The controller is telling the driver that "there's something
going on this port (hotplug event), please take look".  Can you try
hotplug some device at those ports and report the resulting kernel log?

-- 
tejun

Re: ALi SATA problem

[Pavel Mateja]

Dne Wednesday 18 of July 2007 11:45:46 jste napsal(a):
> Pavel Mateja wrote:
> > Dne Wednesday 18 of July 2007 11:35:00 jste napsal(a):
> >> Pavel Mateja wrote:
> >>> ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> >>> ata2: (irq_stat 0x00000040, connection status changed)
> >>> ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
> >>> ata4: (irq_stat 0x00000040, connection status changed)
> >>> ata2: soft resetting port
> >>
> >> Please check power supply and re-cable things.
> >
> > Funny thing is there are no cables to be checked. Nothing is connected to
> > those ports. Just ata1 is occupied by disk.
>
> Sigh... The controller is telling the driver that "there's something
> going on this port (hotplug event), please take look".  Can you try
> hotplug some device at those ports and report the resulting kernel log?

Hotplug was working in cca 1 of 8 attemps.

ata4: soft resetting port
ata4: SATA link down (SStatus 1 SControl 300)
ata4: EH complete
ata4: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
ata4: (irq_stat 0x00000040, connection status changed)
ata2: exception Emask 0x10 SAct 0x0 SErr 0x4060000 action 0x2 frozen
ata2: (irq_stat 0x00000040, connection status changed)
ata4: soft resetting port
ata2: soft resetting port
ata2: SATA link down (SStatus 1 SControl 300)
ata2: EH complete
ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata4.00: ATA-6: ST3120026AS, 8.05, max UDMA/133
ata4.00: 234375000 sectors, multi 0: LBA48
ata4.00: configured for UDMA/133
ata4: EH complete
Device driver target3:0:0 lacks bus and class support for being resumed.
scsi 3:0:0:0: Direct-Access     ATA      ST3120026AS      8.05 PQ: 0 ANSI: 5
sd 3:0:0:0: [sdb] 234375000 512-byte hardware sectors (120000 MB)
sd 3:0:0:0: [sdb] Write Protect is off
sd 3:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support 
DPO or FUA
sd 3:0:0:0: [sdb] 234375000 512-byte hardware sectors (120000 MB)
sd 3:0:0:0: [sdb] Write Protect is off
sd 3:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support 
DPO or FUA
 sdb: sdb1
sd 3:0:0:0: [sdb] Attached SCSI disk
-- 
Pavel Mateja

Re: ALi SATA problem

[Tejun Heo]

Pavel Mateja wrote:
>> Sigh... The controller is telling the driver that "there's something
>> going on this port (hotplug event), please take look".  Can you try
>> hotplug some device at those ports and report the resulting kernel log?
> 
> Hotplug was working in cca 1 of 8 attemps.

Can you attach full kernel log and annotate it with when you hotplugged
a device?

Thanks.

-- 
tejun