Skip site navigation (1)Skip section navigation (2)
Date:      Sun, 12 Aug 2018 12:30:03 +0000
From:      bugzilla-noreply@freebsd.org
To:        wireless@FreeBSD.org
Subject:   [Bug 211520] iwn controller panic in 11.0-BETA3
Message-ID:  <bug-211520-21060-v2U3e2FnuK@https.bugs.freebsd.org/bugzilla/>
In-Reply-To: <bug-211520-21060@https.bugs.freebsd.org/bugzilla/>
References:  <bug-211520-21060@https.bugs.freebsd.org/bugzilla/>

next in thread | previous in thread | raw e-mail | index | archive | help
https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D211520

david@catwhisker.org changed:

           What    |Removed                     |Added
----------------------------------------------------------------------------
                 CC|                            |david@catwhisker.org

--- Comment #4 from david@catwhisker.org ---
I am seeing something similar (to the original report) in:
FreeBSD g1-215.catwhisker.org 12.0-ALPHA1 FreeBSD 12.0-ALPHA1 #268=20
r337568M/337568:1200076: Fri Aug 10 05:27:01 PDT 2018=20=20=20=20
root@g1-215.catwhisker.org:/common/S3/obj/usr/src/amd64.amd64/sys/CANARY  a=
md64


Here's an excerpt from /var/log/messages showing the most recent one as of =
the
moment:

<13>1 2018-08-12T11:57:19.406030+00:00 localhost dhclient 58253 - - Exiting
/etc/dhclient-exit-hooks (REBOOT) with exit_status 0
<29>1 2018-08-12T11:57:21.959167+00:00 localhost wpa_supplicant 57362 - -
wlan0: CTRL-EVENT-DISCONNECTED bssid=3D04:18:d6:22:22:1f reason=3D0
<2>1 2018-08-12T11:57:21.959314+00:00 localhost kernel - - - iwn0: iwn_intr:
fatal firmware error
<2>1 2018-08-12T11:57:21.959335+00:00 localhost kernel - - - firmware error
log:
<2>1 2018-08-12T11:57:21.959352+00:00 localhost kernel - - -   error type=
=20=20=20=20=20
=3D "UNKNOWN" (0x0000001D)
<2>1 2018-08-12T11:57:21.959368+00:00 localhost kernel - - -   program coun=
ter
=3D 0x000006B4
<2>1 2018-08-12T11:57:21.959384+00:00 localhost kernel - - -   source line=
=20=20=20=20
=3D 0x00007B96
<2>1 2018-08-12T11:57:21.959399+00:00 localhost kernel - - -   error data=
=20=20=20=20=20
=3D 0x0000000203630000
<2>1 2018-08-12T11:57:21.959414+00:00 localhost kernel - - -   branch link=
=20=20=20=20
=3D 0x0000067A000007B8
<2>1 2018-08-12T11:57:21.959430+00:00 localhost kernel - - -   interrupt li=
nk=20
=3D 0x0000153200006874
<2>1 2018-08-12T11:57:21.959446+00:00 localhost kernel - - -   time=20=20=
=20=20=20=20=20=20=20=20=20
=3D 3517494799
<2>1 2018-08-12T11:57:21.959461+00:00 localhost kernel - - - driver status:
<2>1 2018-08-12T11:57:21.959477+00:00 localhost kernel - - -   tx ring  0:
qid=3D0  cur=3D140 queued=3D2=20=20
<2>1 2018-08-12T11:57:21.959492+00:00 localhost kernel - - -   tx ring  1:
qid=3D1  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959508+00:00 localhost kernel - - -   tx ring  2:
qid=3D2  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959524+00:00 localhost kernel - - -   tx ring  3:
qid=3D3  cur=3D4   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959539+00:00 localhost kernel - - -   tx ring  4:
qid=3D4  cur=3D55  queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959555+00:00 localhost kernel - - -   tx ring  5:
qid=3D5  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959572+00:00 localhost kernel - - -   tx ring  6:
qid=3D6  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959589+00:00 localhost kernel - - -   tx ring  7:
qid=3D7  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959605+00:00 localhost kernel - - -   tx ring  8:
qid=3D8  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959620+00:00 localhost kernel - - -   tx ring  9:
qid=3D9  cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959640+00:00 localhost kernel - - -   tx ring 10:
qid=3D10 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959656+00:00 localhost kernel - - -   tx ring 11:
qid=3D11 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959672+00:00 localhost kernel - - -   tx ring 12:
qid=3D12 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959688+00:00 localhost kernel - - -   tx ring 13:
qid=3D13 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959704+00:00 localhost kernel - - -   tx ring 14:
qid=3D14 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959722+00:00 localhost kernel - - -   tx ring 15:
qid=3D15 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959737+00:00 localhost kernel - - -   tx ring 16:
qid=3D16 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959752+00:00 localhost kernel - - -   tx ring 17:
qid=3D17 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959768+00:00 localhost kernel - - -   tx ring 18:
qid=3D18 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959784+00:00 localhost kernel - - -   tx ring 19:
qid=3D19 cur=3D0   queued=3D0=20=20
<2>1 2018-08-12T11:57:21.959802+00:00 localhost kernel - - -   rx ring: cur=
=3D34
<2>1 2018-08-12T11:57:21.959819+00:00 localhost kernel - - - iwn0:
iwn_panicked: controller panicked, iv_state =3D 5; restarting
<6>1 2018-08-12T11:57:21.959836+00:00 localhost kernel - - - wlan0: link st=
ate
changed to DOWN
<27>1 2018-08-12T11:57:21.959260+00:00 localhost wpa_supplicant 57362 - -
ioctl[SIOCS80211, op=3D20, val=3D0, arg_len=3D7]: Can't assign requested ad=
dress
<2>1 2018-08-12T11:57:21.960898+00:00 localhost kernel - - - iwn0:
iwn_read_firmware: ucode rev=3D0x09dd0401
<29>1 2018-08-12T11:57:22.950315+00:00 localhost wpa_supplicant 57362 - -
wlan0: Trying to associate with 04:18:d6:22:22:1f (SSID=3D'lmdhw-net' freq=
=3D2462
MHz)
<29>1 2018-08-12T11:57:22.959678+00:00 localhost wpa_supplicant 57362 - -
wlan0: Associated with 04:18:d6:22:22:1f
<6>1 2018-08-12T11:57:22.959810+00:00 localhost kernel - - - wlan0: link st=
ate
changed to UP
<27>1 2018-08-12T11:57:22.960136+00:00 localhost dhclient 57392 - -
send_packet: No buffer space available
<29>1 2018-08-12T11:57:23.968790+00:00 localhost wpa_supplicant 57362 - -
wlan0: WPA: Key negotiation completed with 04:18:d6:22:22:1f [PTK=3DCCMP
GTK=3DCCMP]
<29>1 2018-08-12T11:57:23.968875+00:00 localhost wpa_supplicant 57362 - -
wlan0: CTRL-EVENT-CONNECTED - Connection to 04:18:d6:22:22:1f completed [id=
=3D27
id_str=3D]
<13>1 2018-08-12T11:57:29.969299+00:00 localhost dhclient 58293 - -
/etc/dhclient-enter-hooks invoked with reason REBOOT


I have the impression that trying to pass more traffic through the NIC
increases the frequency with which this occurs; for me, it's usually about =
6 -
10 per hour.

pciconf says:
iwn0@pci0:3:0:0:        class=3D0x028000 card=3D0x11218086 chip=3D0x422b808=
6 rev=3D0x35
hdr=3D0x00
    vendor     =3D 'Intel Corporation'
    device     =3D 'Centrino Ultimate-N 6300'
    class      =3D network

And ifconfig -v:
wlan0: flags=3D8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 15=
00
        ether 3c:a9:f4:a2:ca:f0
        inet 172.17.1.215 netmask 0xffff0000 broadcast 172.17.255.255=20
        groups: wlan=20
        ssid lmdhw-net channel 11 (2462 MHz 11g) bssid 04:18:d6:22:22:1f
        regdomain FCC country US anywhere -ecm authmode WPA2/802.11i -wps
        -tsn privacy ON deftxkey UNDEF
        AES-CCM 2:128-bit powersavemode OFF powersavesleep 100 txpower 30
        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
        11acg   ucast NONE    mgmt  1 Mb/s mcast  1 Mb/s maxretry 6
        11ac    ucast NONE    mgmt  6 Mb/s mcast  6 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=20=20=20
        roam:11ng    rssi    7dBm  MCS  1=20=20=20=20
        roam:half    rssi    7dBm rate  6 Mb/s
        roam:quarter rssi    7dBm rate  3 Mb/s
        roam:11acg   rssi    7dBm rate 64 Mb/s
        roam:11ac    rssi    7dBm rate 64 Mb/s
        -pureg protmode CTS -ht htcompat ampdu ampdulimit 64k ampdudensity 8
        -amsdutx amsdurx shortgi htprotmode RTSCTS -puren -smps -rifs -stbc
        -ldpc -vht -vht40 -vht80 -vht80p80 -vht160 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
        media: IEEE 802.11 Wireless Ethernet OFDM/54Mbps mode 11g
        status: associated
        nd6 options=3D29<PERFORMNUD,IFDISABLED,AUTO_LINKLOCAL>

Further: the panics are very rare for me when I'm running stable/11 -- e.g.:
FreeBSD g1-215.catwhisker.org 11.2-STABLE FreeBSD 11.2-STABLE #707=20
r337682M/337682:1102501: Sun Aug 12 03:47:31 PDT 2018=20=20=20=20
root@g1-215.catwhisker.org:/common/S1/obj/usr/src/sys/CANARY  amd64

At this point, I believe it's fair to characterize the head (or ALPHA1_
behavior as a "regression" (vs. the stable/11 behavior), at least in my cas=
e.

--=20
You are receiving this mail because:
You are the assignee for the bug.=



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?bug-211520-21060-v2U3e2FnuK>