Date: Tue, 6 Jan 2015 06:50:35 -0800 From: David Wolfskill <david@catwhisker.org> To: current@freebsd.org Subject: iwn0: iwn_panicked: controller panicked, iv_state = 5; resetting... Message-ID: <20150106145035.GY14822@albert.catwhisker.org>
next in thread | raw e-mail | index | archive | help
--DejVYFcqCV4p9T4J
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
Content-Transfer-Encoding: quoted-printable
One of the things I've noticed for a bit (but failed ot mention, as it's
been something I find difficult to describe adequately) is that when I
run head on my laptop -- generally, just to perform a source-based
upgrade-in-place (so it tends to be rather busy), the machine acts as if
it's failing to pay attention to some of the interrupts sometimes.
E.g., moving the mouse is extremely laggy & jerky (with a fair amount of
overshooting once the position settles). And the wireless NIC (iwn)
will often lose ... well, everything it can. And I'm seeing messages
such as this in /var/log/messages:
=2E..
Jan 6 06:24:12 g1-253 kernel: iwn0: iwn_read_firmware: ucode rev=3D0x08530=
501
Jan 6 06:24:14 g1-253 kernel: iwn0: iwn_tx_data: m=3D0xd2a33c00: seqno (25=
151) (63) !=3D ring index (0) !
Jan 6 06:24:14 g1-253 kernel: iwn0: iwn_intr: fatal firmware error
Jan 6 06:24:14 g1-253 kernel: firmware error log:
Jan 6 06:24:14 g1-253 kernel: error type =3D "SYSASSERT" (0x00000005)
Jan 6 06:24:14 g1-253 kernel: program counter =3D 0x0000C210
Jan 6 06:24:14 g1-253 kernel: source line =3D 0x00000E4E
Jan 6 06:24:14 g1-253 kernel: error data =3D 0x0000000000000E4E
Jan 6 06:24:14 g1-253 kernel: branch link =3D 0x0000C1280000C128
Jan 6 06:24:14 g1-253 kernel: interrupt link =3D 0x0000091600000000
Jan 6 06:24:14 g1-253 kernel: time =3D 1199025467
Jan 6 06:24:14 g1-253 kernel: driver status:
Jan 6 06:24:14 g1-253 kernel: tx ring 0: qid=3D0 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 1: qid=3D1 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 2: qid=3D2 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 3: qid=3D3 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 4: qid=3D4 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 5: qid=3D5 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 6: qid=3D6 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 7: qid=3D7 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 8: qid=3D8 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 9: qid=3D9 cur=3D28 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 10: qid=3D10 cur=3D1 queued=3D1 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 11: qid=3D11 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 12: qid=3D12 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 13: qid=3D13 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 14: qid=3D14 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 15: qid=3D15 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 16: qid=3D16 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 17: qid=3D17 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 18: qid=3D18 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: tx ring 19: qid=3D19 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:14 g1-253 kernel: rx ring: cur=3D32
Jan 6 06:24:14 g1-253 kernel: iwn0: iwn_panicked: controller panicked, iv_=
state =3D 5; resetting...
Jan 6 06:24:14 g1-253 kernel: iwn0: iwn_read_firmware: ucode rev=3D0x08530=
501
Jan 6 06:24:16 g1-253 kernel: iwn0: iwn_tx_data: m=3D0xd2a41500: seqno (25=
152) (64) !=3D ring index (0) !
Jan 6 06:24:16 g1-253 kernel: iwn0: iwn_intr: fatal firmware error
Jan 6 06:24:16 g1-253 kernel: firmware error log:
Jan 6 06:24:16 g1-253 kernel: error type =3D "SYSASSERT" (0x00000005)
Jan 6 06:24:16 g1-253 kernel: program counter =3D 0x0000C210
Jan 6 06:24:16 g1-253 kernel: source line =3D 0x00000E4E
Jan 6 06:24:16 g1-253 kernel: error data =3D 0x0000000000000E4E
Jan 6 06:24:16 g1-253 kernel: branch link =3D 0x0000C1280000C128
Jan 6 06:24:16 g1-253 kernel: interrupt link =3D 0x0000091600000000
Jan 6 06:24:16 g1-253 kernel: time =3D 1201317618
Jan 6 06:24:16 g1-253 kernel: driver status:
Jan 6 06:24:16 g1-253 kernel: tx ring 0: qid=3D0 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 1: qid=3D1 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 2: qid=3D2 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 3: qid=3D3 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 4: qid=3D4 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 5: qid=3D5 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 6: qid=3D6 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 7: qid=3D7 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 8: qid=3D8 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 9: qid=3D9 cur=3D27 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 10: qid=3D10 cur=3D1 queued=3D1 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 11: qid=3D11 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 12: qid=3D12 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 13: qid=3D13 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 14: qid=3D14 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 15: qid=3D15 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 16: qid=3D16 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 17: qid=3D17 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 18: qid=3D18 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: tx ring 19: qid=3D19 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:16 g1-253 kernel: rx ring: cur=3D30
Jan 6 06:24:16 g1-253 kernel: iwn0: iwn_panicked: controller panicked, iv_=
state =3D 5; resetting...
Jan 6 06:24:16 g1-253 kernel: iwn0: iwn_read_firmware: ucode rev=3D0x08530=
501
Jan 6 06:24:17 g1-253 kernel: iwn0: iwn_tx_data: m=3D0xd2a36900: seqno (25=
153) (65) !=3D ring index (0) !
Jan 6 06:24:17 g1-253 kernel: iwn0: iwn_intr: fatal firmware error
Jan 6 06:24:17 g1-253 kernel: firmware error log:
Jan 6 06:24:17 g1-253 kernel: error type =3D "SYSASSERT" (0x00000005)
Jan 6 06:24:17 g1-253 kernel: program counter =3D 0x0000C210
Jan 6 06:24:17 g1-253 kernel: source line =3D 0x00000E4E
Jan 6 06:24:17 g1-253 kernel: error data =3D 0x0000000000000E4E
Jan 6 06:24:17 g1-253 kernel: branch link =3D 0x0000C1280000C128
Jan 6 06:24:17 g1-253 kernel: interrupt link =3D 0x0000091600000000
Jan 6 06:24:17 g1-253 kernel: time =3D 1202473968
Jan 6 06:24:17 g1-253 kernel: driver status:
Jan 6 06:24:17 g1-253 kernel: tx ring 0: qid=3D0 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 1: qid=3D1 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 2: qid=3D2 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 3: qid=3D3 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 4: qid=3D4 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 5: qid=3D5 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 6: qid=3D6 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 7: qid=3D7 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 8: qid=3D8 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 9: qid=3D9 cur=3D26 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 10: qid=3D10 cur=3D1 queued=3D1 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 11: qid=3D11 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 12: qid=3D12 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 13: qid=3D13 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 14: qid=3D14 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 15: qid=3D15 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 16: qid=3D16 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 17: qid=3D17 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 18: qid=3D18 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: tx ring 19: qid=3D19 cur=3D0 queued=3D0 =
=20
Jan 6 06:24:17 g1-253 kernel: rx ring: cur=3D59
Jan 6 06:24:17 g1-253 kernel: iwn0: iwn_panicked: controller panicked, iv_=
state =3D 5; resetting...
Jan 6 06:24:17 g1-253 kernel: iwn0: iwn_read_firmware: ucode rev=3D0x08530=
501
=2E....
For the above, this is while running:
FreeBSD g1-253.catwhisker.org 11.0-CURRENT FreeBSD 11.0-CURRENT #1475 r276=
694M/276694:1100052: Mon Jan 5 20:04:17 PST 2015 root@g1-253.catwhiske=
r.org:/common/S4/obj/usr/src/sys/CANARY i386
while building the kernel as part of an update to head @r276749.
Stable/10:
FreeBSD g1-253.catwhisker.org 10.1-STABLE FreeBSD 10.1-STABLE #1434 r27674=
4M/276749:1001505: Tue Jan 6 04:52:54 PST 2015 root@g1-253.catwhisker.=
org:/common/S1/obj/usr/src/sys/CANARY i386
has few (if any) problems of that nature in this environment
(presently at home).
The iwn(4) device shows up as:
iwn0@pci0:12:0:0: class=3D0x028000 card=3D0x11218086 chip=3D0x4235808=
6 rev=3D0x00 hdr=3D0x00
vendor =3D 'Intel Corporation'
device =3D 'Ultimate N WiFi Link 5300'
class =3D network
and in dmesg.boot, I see:
=2E..
iwn0: <Intel Ultimate N WiFi Link 5300> mem 0xf1ffe000-0xf1ffffff irq 17 at=
device 0.0 on pci12
=2E..
wlan0: Ethernet address: 00:21:6a:26:34:c0
iwn0: iwn_read_firmware: ucode rev=3D0x08530501
ifconfig says:
iwn0: flags=3D8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 2290
ether 00:21:6a:26:34:c0
nd6 options=3D21<PERFORMNUD,AUTO_LINKLOCAL>
media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng
status: associated
and:
g1-253(11.0-C)[5] ifconfig -v wlan0
wlan0: flags=3D8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 15=
00
ether 00:21:6a:26:34:c0
inet 172.17.1.253 netmask 0xffff0000 broadcast 172.17.255.255=20
nd6 options=3D29<PERFORMNUD,IFDISABLED,AUTO_LINKLOCAL>
media: IEEE 802.11 Wireless Ethernet MCS mode 11ng
status: associated
ssid lmdhw-net channel 11 (2462 MHz 11g ht/20) bssid 04:18:d6:22:22=
:1f
regdomain 0 country US anywhere -ecm authmode WPA2/802.11i -wps -tsn
privacy ON deftxkey UNDEF
AES-CCM 2:128-bit powersavemode OFF powersavesleep 100 txpower 15
txpowmax 50.0 -dotd rtsthreshold 2346 fragthreshold 2346 bmiss 10
11a ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
11b ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
11g ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
turboA ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
turboG ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
sturbo ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
11na ucast NONE mgmt 12 MCS mcast 12 MCS maxretry 6
11ng ucast NONE mgmt 2 MCS mcast 2 MCS maxretry 6
half ucast NONE mgmt 3 Mb/s mcast 3 Mb/s maxretry 6
quarter ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
scanvalid 60 bgscan bgscanintvl 300 bgscanidle 250
roam:11a rssi 7dBm rate 12 Mb/s
roam:11b rssi 7dBm rate 1 Mb/s
roam:11g rssi 7dBm rate 5 Mb/s
roam:turboA rssi 7dBm rate 12 Mb/s
roam:turboG rssi 7dBm rate 12 Mb/s
roam:sturbo rssi 7dBm rate 12 Mb/s
roam:11na rssi 7dBm MCS 1 =20
roam:11ng rssi 7dBm MCS 1 =20
roam:half rssi 7dBm rate 6 Mb/s
roam:quarter rssi 7dBm rate 3 Mb/s
-pureg protmode CTS ht htcompat ampdu ampdulimit 64k ampdudensity 8
-amsdutx amsdurx shortgi htprotmode RTSCTS -puren -smps -rifs wme
-burst -dwds roaming MANUAL bintval 100
AC_BE cwmin 4 cwmax 10 aifs 3 txopLimit 0 -acm ack
cwmin 4 cwmax 10 aifs 3 txopLimit 0 -acm
AC_BK cwmin 4 cwmax 10 aifs 7 txopLimit 0 -acm ack
cwmin 4 cwmax 10 aifs 7 txopLimit 0 -acm
AC_VI cwmin 3 cwmax 4 aifs 2 txopLimit 94 -acm ack
cwmin 3 cwmax 4 aifs 2 txopLimit 94 -acm
AC_VO cwmin 2 cwmax 3 aifs 2 txopLimit 47 -acm ack
cwmin 2 cwmax 3 aifs 2 txopLimit 47 -acm
groups: wlan=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
g1-253(11.0-C)[6]=20
[Remember that I mentioned something about "interrupts" up there?
The above silliness with the extraneous linefeeds is another symptom
of that, I think: I had only hit "Enter" the minimum number of times..]
So: what can be done about this? I'm happy to test....
Peace,
david
--=20
David H. Wolfskill david@catwhisker.org
Actions have consequences ... as do inactions.
See http://www.catwhisker.org/~david/publickey.gpg for my public key.
--DejVYFcqCV4p9T4J
Content-Type: application/pgp-signature
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2
iQJ8BAEBCgBmBQJUq/Y7XxSAAAAAAC4AKGlzc3Vlci1mcHJAbm90YXRpb25zLm9w
ZW5wZ3AuZmlmdGhob3JzZW1hbi5uZXQ4RThEMDY4QTIxMjc1MDZFRDIzODYzRTc4
QTY3RjlDOERFRjQxOTNCAAoJEIpn+cje9Bk70eAP/2U9J9eyb0MEhIuxio1iUUVo
cDB2A04QTzyshAKjMtGgdoJ+2WG3x0HafqpaFgRqCL/VSqal+z/fhlYC3LoLXeFo
K5vfgIARuAzats1zwHueCOok1dFoyebZFZ7oJ1L/XxdDFEDYrvARh4hUDFkUiZle
dtgCnqcfXN8p3hMhZAP62T14v/+8JTrBR07WzJg9MF2cH1QntVlb+sQY0NCY5rKT
CdqiiulGuqXAJWE6tyR2XmF2lTbVBpCHGVGJHR/OofUhcJScUAKvsuWqfP5aE9PG
iWLV2R72nFYDtnd9VkMVydY33wqxCAE2dF1dirwVyvVnT42U85rgm+6Nlf6j8HTl
toMHUqf2Omco0/aFkEYLFgahmkjXO17bXoIEMu5VOgjIxJJYO+IKCUKt2cSQ0fQN
ReM6YAd9j6Nl2JNF/CqHSrJ8MGMIX7RvZtQYtpt00M+iUGFyZppSi9IrCJZesWCK
wGfBS5jS3+QAv4/1Mlwbs3FV7he8WoFf6Qs0QLCjROldhEkwDb4e/tapJb0qBzEc
K4Ky63slsZaXZjUXoWeTT0FndjB95f0l+AHYAp0Y/hsXR6iQoX4sNfHkWrEhifBA
aqrlO6tqm2TdGIjhmriu9zZD6noNMTHrdx0MONXE1p/HNTChadLcrgjT1eSvFGKd
e8fThyxn1/FRYy9sXhFL
=emIL
-----END PGP SIGNATURE-----
--DejVYFcqCV4p9T4J--
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20150106145035.GY14822>