Possible conflicts with Interrupts?

ssaatt · November 28, 2011, 7:30pm

Well after the thread where I was asking for advice due to having freeze problems with opensuse 12.1, it seems it’s time for another one.
I decided to give opensuse 11.4 a try and see how it performs on my system. I noticed some strange messages:

Nov 28 00:38:25 sda-linux kernel:  3920.023599] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Nov 28 00:38:27 sda-linux kernel:  3922.849435] wlan0: authenticate with 74:ea:3a:e4:3c:ea (try 1)
Nov 28 00:38:27 sda-linux kernel:  3922.851225] wlan0: authenticated
Nov 28 00:38:27 sda-linux kernel:  3922.851249] wlan0: associate with 74:ea:3a:e4:3c:ea (try 1)
Nov 28 00:38:27 sda-linux kernel:  3922.855325] wlan0: RX AssocResp from 74:ea:3a:e4:3c:ea (capab=0x431 status=0 aid=1)
Nov 28 00:38:27 sda-linux kernel:  3922.855329] wlan0: associated
Nov 28 00:38:27 sda-linux kernel:  3922.932720] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Nov 28 00:38:28 sda-linux dhclient: Internet Systems Consortium DHCP Client 4.2.1
Nov 28 00:38:28 sda-linux dhclient: Copyright 2004-2011 Internet Systems Consortium.
Nov 28 00:38:28 sda-linux dhclient: All rights reserved.
Nov 28 00:38:28 sda-linux dhclient: For info, please visit https://www.isc.org/software/dhcp/
Nov 28 00:38:28 sda-linux dhclient: 
Nov 28 00:38:28 sda-linux dhclient: Listening on LPF/wlan0/d8:5d:4c:84:1f:3f
Nov 28 00:38:28 sda-linux dhclient: Sending on   LPF/wlan0/d8:5d:4c:84:1f:3f
Nov 28 00:38:28 sda-linux dhclient: Sending on   Socket/fallback
Nov 28 00:38:29 sda-linux avahi-daemon[1259]: Registering new address record for fe80::da5d:4cff:fe84:1f3f on wlan0.*.
Nov 28 00:38:30 sda-linux dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Nov 28 00:38:30 sda-linux dhclient: DHCPACK from 192.168.1.1
Nov 28 00:38:30 sda-linux dhclient: bound to 192.168.1.101 -- renewal in 2147483648 seconds.
Nov 28 00:38:30 sda-linux avahi-daemon[1259]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.101.
Nov 28 00:38:30 sda-linux avahi-daemon[1259]: New relevant interface wlan0.IPv4 for mDNS.
Nov 28 00:38:30 sda-linux avahi-daemon[1259]: Registering new address record for 192.168.1.101 on wlan0.IPv4.
Nov 28 00:38:31 sda-linux if-up.d/21-dhcpcd-hook-samba: No dhcpcd info nor dhclient leases file found for wlan0.
Nov 28 00:38:38 sda-linux kernel:  3932.981272] wlan0: no IPv6 routers present
Nov 28 00:40:21 sda-linux su: (to root) saty on /dev/pts/1
Nov 28 00:43:35 sda-linux su: FAILED SU (to root) saty on /dev/pts/1
Nov 28 00:43:45 sda-linux su: (to root) saty on /dev/pts/1
Nov 28 00:45:25 sda-linux kernel:  4338.878738] wlan0: deauthenticating from 74:ea:3a:e4:3c:ea by local choice (reason=3)
Nov 28 00:45:25 sda-linux kernel:  4338.967109] cfg80211: Calling CRDA to update world regulatory domain
Nov 28 00:45:25 sda-linux kernel:  4338.970931] cfg80211: World regulatory domain updated:
Nov 28 00:45:25 sda-linux kernel:  4338.970934]     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Nov 28 00:45:25 sda-linux kernel:  4338.970938]     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 00:45:25 sda-linux kernel:  4338.970940]     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 28 00:45:25 sda-linux kernel:  4338.970943]     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 28 00:45:25 sda-linux kernel:  4338.970945]     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 00:45:25 sda-linux kernel:  4338.970947]     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 00:45:25 sda-linux avahi-daemon[1259]: Withdrawing address record for 192.168.1.101 on wlan0.
Nov 28 00:45:25 sda-linux avahi-daemon[1259]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.101.
Nov 28 00:45:25 sda-linux avahi-daemon[1259]: Interface wlan0.IPv4 no longer relevant for mDNS.
Nov 28 00:45:25 sda-linux avahi-daemon[1259]: Withdrawing address record for fe80::da5d:4cff:fe84:1f3f on wlan0.
Nov 28 00:45:33 sda-linux kernel:  4347.382978] r8169 0000:0a:00.0: eth0: link down
Nov 28 00:45:33 sda-linux kernel:  4347.383296] ADDRCONF(NETDEV_UP): eth0: link is not ready
Nov 28 00:45:33 sda-linux kernel:  4347.383732] r8169 0000:0c:00.0: eth1: link down
Nov 28 00:45:33 sda-linux kernel:  4347.384075] ADDRCONF(NETDEV_UP): eth1: link is not ready
Nov 28 00:45:41 sda-linux kernel:  4355.231160] usb 1-1.1: USB disconnect, address 3
Nov 28 00:45:41 sda-linux avahi-daemon[1259]: Withdrawing workstation service for wlan0.
Nov 28 00:45:41 sda-linux kernel:  4355.262793] usb 1-1.1: ath9k_htc: USB reboot failed
Nov 28 00:45:41 sda-linux kernel:  4355.262797] usb 1-1.1: ath9k_htc: USB layer deinitialized
Nov 28 00:45:41 sda-linux ifdown:     wlan0     
Nov 28 00:45:41 sda-linux ifdown: Interface not available and no configuration found.
Nov 28 00:46:15 sda-linux pulseaudio[2168]: ratelimit.c: 252 events suppressed
Nov 28 01:11:33 sda-linux pulseaudio[2168]: ratelimit.c: 248 events suppressed
Nov 28 02:33:01 sda-linux rsyslogd: -- MARK --
Nov 28 02:45:20 sda-linux kernel: [11513.976755] irq 16: nobody cared (try booting with the "irqpoll" option)
Nov 28 02:45:20 sda-linux kernel: [11513.976758] Pid: 0, comm: swapper Tainted: P            2.6.37.6-0.9-desktop #1
Nov 28 02:45:20 sda-linux kernel: [11513.976759] Call Trace:
Nov 28 02:45:20 sda-linux kernel: [11513.976765]  <ffffffff810059b9>] dump_trace+0x79/0x340
Nov 28 02:45:20 sda-linux kernel: [11513.976769]  <ffffffff815231b2>] dump_stack+0x69/0x6f
Nov 28 02:45:20 sda-linux kernel: [11513.976772]  <ffffffff810cc3be>] __report_bad_irq+0x1e/0x90
Nov 28 02:45:20 sda-linux kernel: [11513.976774]  <ffffffff810cc5d9>] note_interrupt+0x1a9/0x200
Nov 28 02:45:20 sda-linux kernel: [11513.976776]  <ffffffff810cd575>] handle_fasteoi_irq+0x105/0x140
Nov 28 02:45:20 sda-linux kernel: [11513.976779]  <ffffffff810058b5>] handle_irq+0x15/0x20
Nov 28 02:45:20 sda-linux kernel: [11513.976780]  <ffffffff810054fe>] do_IRQ+0x5e/0xe0
Nov 28 02:45:20 sda-linux kernel: [11513.976783]  <ffffffff81526a53>] ret_from_intr+0x0/0xa
Nov 28 02:45:20 sda-linux kernel: [11513.976786]  <ffffffff812bb00e>] intel_idle+0xbe/0x110
Nov 28 02:45:20 sda-linux kernel: [11513.976790]  <ffffffff813f1638>] cpuidle_idle_call+0xb8/0x370
Nov 28 02:45:20 sda-linux kernel: [11513.976793]  <ffffffff8100125c>] cpu_idle+0x4c/0xa0
Nov 28 02:45:20 sda-linux kernel: [11513.976796]  <ffffffff81b3ebf8>] start_kernel+0x39a/0x3a5
Nov 28 02:45:20 sda-linux kernel: [11513.976798]  <ffffffff81b3e414>] x86_64_start_kernel+0xf9/0xff
Nov 28 02:45:20 sda-linux kernel: [11513.976800] handlers:
Nov 28 02:45:20 sda-linux kernel: [11513.976800] <ffffffff81386de0>] (usb_hcd_irq+0x0/0x70)
Nov 28 02:45:20 sda-linux kernel: [11513.976802] <ffffffff8136f850>] (ata_bmdma_interrupt+0x0/0x1f0)
Nov 28 02:45:20 sda-linux kernel: [11513.976805] <ffffffffa0155fa0>] (irq_handler+0x0/0x360 [firewire_ohci])
Nov 28 02:45:20 sda-linux kernel: [11513.976811] <ffffffffa017ba80>] (oxygen_interrupt+0x0/0x270 [snd_oxygen_lib])
Nov 28 02:45:20 sda-linux kernel: [11513.976814] <ffffffffa0911300>] (nv_kern_isr+0x0/0x80 [nvidia])
Nov 28 02:45:20 sda-linux kernel: [11513.976908] Disabling IRQ #16
Nov 28 03:33:01 sda-linux rsyslogd: -- MARK --
Nov 28 04:33:01 sda-linux rsyslogd: -- MARK --
Nov 28 05:33:01 sda-linux rsyslogd: -- MARK --

The log is probably a bit too long but there seems to be something wrong with IRQ 16.
Here is /etc/interrupts:

           CPU0       CPU1       CPU2       CPU3       CPU4       CPU5       CPU6       CPU7       
  0:        132          0          0          0          0          0          0          0   IO-APIC-edge      timer
  1:          2          0          0          0          0          0          0          0   IO-APIC-edge      i8042
  4:          2          0          0          0          0          0          0          0   IO-APIC-edge    
  6:          2          0          0          0          0          0          0          0   IO-APIC-edge      floppy
  8:         59          0          0          0          0          0          0          0   IO-APIC-edge      rtc0
  9:          0          0          0          0          0          0          0          0   IO-APIC-fasteoi   acpi
 12:          4          0          0          0          0          0          0          0   IO-APIC-edge      i8042
 16:     975963          0          0          0          0          0          0          0   IO-APIC-fasteoi   ehci_hcd:usb1, pata_via, firewire_ohci, oxygen, nvidia
 17:        202          0          0          0          0          0          0          0   IO-APIC-fasteoi   hda_intel
 23:        441     164210          0          0          0          0          0          0   IO-APIC-fasteoi   ehci_hcd:usb2
 40:          0          0          0          0          0          0          0          0   PCI-MSI-edge      PCIe PME
 41:          0          0          0          0          0          0          0          0   PCI-MSI-edge      PCIe PME
 42:          0          0          0          0          0          0          0          0   PCI-MSI-edge      PCIe PME
 43:          0          0          0          0          0          0          0          0   PCI-MSI-edge      PCIe PME
 52:      41112          0          0          0          0          0          0          0   PCI-MSI-edge      ahci
 53:          0          0          0          0          0          0          0          0   PCI-MSI-edge      xhci_hcd
 54:          0          0          0          0          0          0          0          0   PCI-MSI-edge      xhci_hcd
 55:          0          0          0          0          0          0          0          0   PCI-MSI-edge      xhci_hcd
 56:          0          0          0          0          0          0          0          0   PCI-MSI-edge      eth0
 57:          0          0          0          0          0          0          0          0   PCI-MSI-edge      eth1
NMI:        210        184         77         34         69        103         35         19   Non-maskable interrupts
LOC:     315089     205844     127137      85789     148422     169196     110511      75474   Local timer interrupts
SPU:          0          0          0          0          0          0          0          0   Spurious interrupts
PMI:        210        184         77         34         69        103         35         19   Performance monitoring interrupts
IWI:          0          0          0          0          0          0          0          0   IRQ work interrupts
RES:       1959      10208        697        349       2760       2109        477        373   Rescheduling interrupts
CAL:       5278       9662      10152      10489      11700       7419      12083      11520   Function call interrupts
TLB:       6696       1064        841        544       1509       6714       1021        497   TLB shootdowns
TRM:          0          0          0          0          0          0          0          0   Thermal event interrupts
THR:          0          0          0          0          0          0          0          0   Threshold APIC interrupts
MCE:          0          0          0          0          0          0          0          0   Machine check exceptions
MCP:         12         12         12         12         12         12         12         12   Machine check polls
ERR:          7
MIS:          0

The first time I noticed something is going on was because of some strange knetworkmanager “crashes” and internet disappearances. I use wireless USB adapter which also rely on IRQ 16 I believe.
Here is some other log showing some other problems(pulseaudio):

Nov 28 18:41:45 sda-linux kernel:    61.571983] warning: `VirtualBox' uses 32-bit capabilities (legacy support in use)
Nov 28 18:41:57 sda-linux su: (to root) saty on /dev/pts/1
Nov 28 18:42:00 sda-linux pulseaudio[2427]: ratelimit.c: 399 events suppressed
Nov 28 18:43:10 sda-linux pulseaudio[2427]: ratelimit.c: 592 events suppressed
Nov 28 18:46:54 sda-linux pulseaudio[2427]: ratelimit.c: 588 events suppressed
Nov 28 18:47:24 sda-linux pulseaudio[2427]: ratelimit.c: 428 events suppressed
Nov 28 18:47:47 sda-linux pulseaudio[2427]: ratelimit.c: 420 events suppressed
Nov 28 18:48:02 sda-linux pulseaudio[2427]: ratelimit.c: 584 events suppressed
Nov 28 18:48:14 sda-linux pulseaudio[2427]: ratelimit.c: 399 events suppressed
Nov 28 18:49:44 sda-linux pulseaudio[2427]: ratelimit.c: 427 events suppressed
Nov 28 18:49:51 sda-linux pulseaudio[2427]: ratelimit.c: 580 events suppressed
Nov 28 18:52:51 sda-linux su: (to root) saty on /dev/pts/1
Nov 28 18:53:58 sda-linux su: (to root) saty on /dev/pts/1
Nov 28 18:54:33 sda-linux kernel:   826.870593] ieee80211 phy0: wlan0: No probe response from AP 74:ea:3a:e4:3c:ea after 500ms, disconnecting.
Nov 28 18:54:33 sda-linux kernel:   827.012719] cfg80211: Calling CRDA to update world regulatory domain
Nov 28 18:54:33 sda-linux kernel:   827.016342] cfg80211: World regulatory domain updated:
Nov 28 18:54:33 sda-linux kernel:   827.016346]     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Nov 28 18:54:33 sda-linux kernel:   827.016349]     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 18:54:33 sda-linux kernel:   827.016352]     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 28 18:54:33 sda-linux kernel:   827.016354]     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 28 18:54:33 sda-linux kernel:   827.016356]     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 18:54:33 sda-linux kernel:   827.016359]     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 28 18:54:35 sda-linux kernel:   828.828449] wlan0: authenticate with 74:ea:3a:e4:3c:ea (try 1)
Nov 28 18:54:35 sda-linux kernel:   828.830219] wlan0: authenticated
Nov 28 18:54:35 sda-linux kernel:   828.951589] wlan0: associate with 74:ea:3a:e4:3c:ea (try 1)
Nov 28 18:54:35 sda-linux kernel:   828.955359] wlan0: RX AssocResp from 74:ea:3a:e4:3c:ea (capab=0x431 status=0 aid=1)
Nov 28 18:54:35 sda-linux kernel:   828.955362] wlan0: associated
Nov 28 18:55:16 sda-linux kernel:   869.944871] ieee80211 phy0: wlan0: No probe response from AP 74:ea:3a:e4:3c:ea after 500ms, disconnecting.
Nov 28 18:55:16 sda-linux kernel:   870.191708] cfg80211: Calling CRDA to update world regulatory domain

From the /proc/interrupts file I believe my audio card is also listed under IRQ 16 as “oxygen”.
For now I booted with irqpoll option to see what’s different.
I’d appreciate any feedback on the issue.

linuxvinh · November 30, 2011, 8:31am

This looks very similar to the problem I had, also related to IRQ 16…

http://forums.opensuse.org/english/get-technical-help-here/hardware/468132-device-8086-340a-error-status.html

I was able to resolve the problem by moving the graphics card to another slot. It’s not the most satisfactory solution, but my system is stable again.

ssaatt · November 30, 2011, 6:52pm

Thanks.
I actually think I managed to sort this issue out for me just by bringing some devices down.

First I disabled firewire controller from the bios. Then got rid of nvidia hdmi(strangely enough that’s the hda-intel entry in the interrupts file).
Just added “echo 1 > /sys/devices/pci0000:00/0000:00:02.0/0000:02:00.1/remove” in boot.local. Found the correct path(number thingy) by looking at “lspci” output for nvidia hdmi device.

For now this works fine for me. Just hope that this really fixed the problem.