[BUG] 4.15-rc4 'do_IRQ: 0.33 No irq handler for vector'

[vcaputo@pengaru.com]

[-- Attachment #1: Type: text/plain, Size: 5081 bytes --]

Hello everyone,

While investigating `journalctl -k` for anything associated with broken
audio, this was found:

  Dec 18 16:09:28 iridesce kernel: do_IRQ: 0.33 No irq handler for vector
  Dec 18 16:10:21 iridesce kernel: ata1.00: exception Emask 0x0 SAct 0xfe0 SErr 0x0 action 0x6 frozen
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:28:30:92:79/00:00:1c:00:00/40 tag 5 ncq dma 4096 out
                                            res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:30:10:20:68/00:00:00:00:00/40 tag 6 ncq dma 4096 out
                                            res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:38:30:5d:ec/00:00:1c:00:00/40 tag 7 ncq dma 4096 out
                                            res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/48:40:c0:75:64/00:00:00:00:00/40 tag 8 ncq dma 36864 out
                                            res 40/00:fe:00:00:00/00:00:00:00:00/40 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:48:20:20:ec/00:00:1c:00:00/40 tag 9 ncq dma 4096 out
                                            res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:50:80:20:78/00:00:1c:00:00/40 tag 10 ncq dma 4096 out
                                            res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1.00: failed command: WRITE FPDMA QUEUED
  Dec 18 16:10:21 iridesce kernel: ata1.00: cmd 61/08:58:90:3e:78/00:00:1c:00:00/40 tag 11 ncq dma 4096 out
                                            res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
  Dec 18 16:10:21 iridesce kernel: ata1.00: status: { DRDY }
  Dec 18 16:10:21 iridesce kernel: ata1: hard resetting link
  Dec 18 16:10:21 iridesce kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
  Dec 18 16:10:21 iridesce kernel: ata1.00: supports DRM functions and may not be fully accessible
  Dec 18 16:10:21 iridesce kernel: ata1.00: NCQ Send/Recv Log not supported
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
  Dec 18 16:10:21 iridesce kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
  Dec 18 16:10:21 iridesce kernel: ata1.00: supports DRM functions and may not be fully accessible
  Dec 18 16:10:21 iridesce kernel: ata1.00: NCQ Send/Recv Log not supported
  Dec 18 16:10:21 iridesce kernel: ata1.00: configured for UDMA/133
  Dec 18 16:10:21 iridesce kernel: ata1.00: device reported invalid CHS sector 0
  Dec 18 16:10:21 iridesce kernel: ata1.00: device reported invalid CHS sector 0
  Dec 18 16:10:21 iridesce kernel: ata1.00: device reported invalid CHS sector 0
  Dec 18 16:10:21 iridesce kernel: ata1.00: device reported invalid CHS sector 0
  Dec 18 16:10:21 iridesce kernel: ata1.00: device reported invalid CHS sector 0
  Dec 18 16:10:21 iridesce kernel: ata1: EH complete

I have approximately ~300 boots logged back to kernel 4.13.0-rc7, and
there's not a single occurrence of this kind of thing in any of them.

Now 4.15-rc4 is my first 4.15 kernel, so I don't know if this was
introduced in one of the RCs or if it's 4.15-*.

I also don't know if this is associated with the audio problem I
observed.  Nor do I know if this is reproducible.  It hasn't recurred
yet, and I'm now in my second boot of 4.15-rc4.  The problem is, I'm not
likely to spend much time in 4.15-rc4 with audio malfunctioning, as this
is my primary laptop and music tends to always be playing.

Machine is thinkpad x61s, 840 evo ssd, kernel config attached.

Regards,
Vito Caputo

[-- Attachment #2: v4.15-rc4.config.gz --]
[-- Type: application/gzip, Size: 25598 bytes --]