Bienvenido a la Comunidad UBNT

Problema con reseteo continuo de APs

Hola a todos que tal?

Tengo un problema que me lleva de cabeza hace mas de una semana, tengo un site de un controller que las antenas estan continuamente reseteandose, no se que mirar ya, esto pasa desde que actualize el controller a la version 5.9.29, anteriormente no me pasaba, he revisado en las notas si habia algun bug o algo, pero no veo nada referente a esto, alguien podria darme alguna idea de que puede ser?
Copia un log de una de las antenas luego de resetearse.

Muchas gracias de antemano y saludos.

Wed Jan  9 14:55:02 2019 kern.info kernel: [393860.655757] eth0: link down
Wed Jan  9 14:55:02 2019 kern.info kernel: [393860.655816] br0: port 1(eth0) entered disabled state
Wed Jan  9 14:55:02 2019 kern.info kernel: [393860.657271] br0.391: port 3(eth0.391) entered disabled state
Wed Jan  9 14:55:23 2019 kern.info kernel: [393881.656883] eth0: link up (100Mbps/Full duplex)
Wed Jan  9 14:55:23 2019 kern.info kernel: [393881.656944] br0: port 1(eth0) entered blocking state
Wed Jan  9 14:55:23 2019 kern.info kernel: [393881.656958] br0: port 1(eth0) entered forwarding state
Wed Jan  9 14:55:23 2019 kern.info kernel: [393881.658381] br0.391: port 3(eth0.391) entered blocking state
Wed Jan  9 14:55:23 2019 kern.info kernel: [393881.658405] br0.391: port 3(eth0.391) entered forwarding state
Wed Jan  9 14:55:57 2019 kern.info kernel: [393915.655332] eth0: link down
Wed Jan  9 14:55:57 2019 kern.info kernel: [393915.655391] br0: port 1(eth0) entered disabled state
Wed Jan  9 14:55:57 2019 kern.info kernel: [393915.656737] br0.391: port 3(eth0.391) entered disabled state
Wed Jan  9 14:56:18 2019 kern.info kernel: [393936.656455] eth0: link up (100Mbps/Full duplex)
Wed Jan  9 14:56:18 2019 kern.info kernel: [393936.656516] br0: port 1(eth0) entered blocking state
Wed Jan  9 14:56:18 2019 kern.info kernel: [393936.656530] br0: port 1(eth0) entered forwarding state
Wed Jan  9 14:56:18 2019 kern.info kernel: [393936.657961] br0.391: port 3(eth0.391) entered blocking state
Wed Jan  9 14:56:18 2019 kern.info kernel: [393936.657985] br0.391: port 3(eth0.391) entered forwarding state
Wed Jan  9 14:56:23 2019 user.err : ace_reporter.reporter_fail(): Server Reject (http://192.168.89.214:8080/inform)
Wed Jan  9 14:56:23 2019 user.err : ace_reporter.reporter_fail(): inform failed #1 (last inform: 105 seconds ago), rc=5
Wed Jan  9 14:56:45 2019 daemon.info hostapd: ath0: STA d0:a6:37:b1:28:d5 IEEE 802.11: sta_stats
Wed Jan  9 14:56:45 2019 daemon.info hostapd: ath0: STA d0:a6:37:b1:28:d5 IEEE 802.11: disassociated
Wed Jan  9 14:56:45 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath0: d0:a6:37:b1:28:d5 / 1

Wed Jan  9 14:59:20 2019 daemon.info hostapd: ath1: STA 80:2a:a8:55:1e:50 DRIVER: Sead AUTH addr=8c:f5:a3:9b:0b:6d status_code=0
Wed Jan  9 14:59:20 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_JOIN ath1: 8c:f5:a3:9b:0b:6d / 4

Wed Jan  9 14:59:20 2019 kern.warn kernel: [394118.730700] wmi_unified_event_rx : no registered event handler : event id 0x901b
Wed Jan  9 14:59:20 2019 daemon.info hostapd: ath1: STA 8c:f5:a3:9b:0b:6d IEEE 802.11: associated
Wed Jan  9 14:59:21 2019 daemon.info hostapd: ath1: STA 8c:f5:a3:9b:0b:6d WPA: pairwise key handshake completed (RSN)
Wed Jan  9 14:59:21 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_IP ath1: 8c:f5:a3:9b:0b:6d / 10.191.25.147

Wed Jan  9 14:59:21 2019 kern.warn kernel: [394119.353165] [wifi1] FWLOG: [940680] RATE: ChainMask 3, phymode 1044489, ni_flags 0x02233006, vht_mcs_set 0xfffa, ht_mcs_set 0xffff, legacy_rate_set 0xe5ace
Wed Jan  9 14:59:21 2019 kern.warn kernel: [394119.353199] [wifi1] FWLOG: [940750] WAL_DBGID_SECURITY_ALLOW_DATA ( 0x438618 )
Wed Jan  9 14:59:22 2019 kern.warn kernel: [394120.353232] [wifi1] FWLOG: [941848] RATE: ChainMask 3, phymode 1044490, ni_flags 0x02233006, vht_mcs_set 0xfffa, ht_mcs_set 0xffff, legacy_rate_set 0x60401
Wed Jan  9 14:59:24 2019 daemon.info hostapd: ath1: STA 8c:f5:a3:9b:0b:6d IEEE 802.11: disassociated
Wed Jan  9 14:59:24 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath1: 8c:f5:a3:9b:0b:6d / 4

Wed Jan  9 14:59:30 2019 user.info : wevent.ubnt_handle_custom_alert_sta_assoc(): EVT_AP_STA_ASSOC_TRACKER_DBG: event_id: 1 event_type: soft failure vap: ath1 sta_mac: 8c:f5:a3:9b:0b:6d auth_ts: 394118.699444 auth_delta: 0 assoc_delta: 10000 wpa_auth_delta: 80000 radius_auth_delta: -1 radius_auth_status: N/A ip_delta: 80000 disassoc_count: 0 ip_assign_type: roamed auth_failures: 0 assoc_failures: 0 wpa_auth_failures: 0 ip_failures: 0 acl_status: allowed arp_status: N/A dns_status: N/A

Wed Jan  9 14:59:44 2019 kern.info kernel: [394142.653566] eth0: link down
Wed Jan  9 14:59:44 2019 kern.info kernel: [394142.653625] br0: port 1(eth0) entered disabled state
Wed Jan  9 14:59:44 2019 kern.info kernel: [394142.655076] br0.391: port 3(eth0.391) entered disabled state
Wed Jan  9 15:00:03 2019 kern.info kernel: [394161.654741] eth0: link up (100Mbps/Full duplex)
Wed Jan  9 15:00:03 2019 kern.info kernel: [394161.654802] br0: port 1(eth0) entered blocking state
Wed Jan  9 15:00:03 2019 kern.info kernel: [394161.654816] br0: port 1(eth0) entered forwarding state
Wed Jan  9 15:00:03 2019 kern.info kernel: [394161.656312] br0.391: port 3(eth0.391) entered blocking state
Wed Jan  9 15:00:03 2019 kern.info kernel: [394161.656336] br0.391: port 3(eth0.391) entered forwarding state
Wed Jan  9 15:02:47 2019 daemon.info hostapd: ath0: STA 80:2a:a8:54:1e:50 DRIVER: Sead AUTH addr=98:ca:33:bc:8d:a6 status_code=0
Wed Jan  9 15:02:47 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_JOIN ath0: 98:ca:33:bc:8d:a6 / 1

Wed Jan  9 15:02:47 2019 daemon.info hostapd: ath0: STA 98:ca:33:bc:8d:a6 IEEE 802.11: associated
Wed Jan  9 15:02:47 2019 daemon.info hostapd: ath1: STA 98:ca:33:bc:8d:a6 IEEE 802.11: disassociated
Wed Jan  9 15:02:47 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath1: 98:ca:33:bc:8d:a6 / 2

Wed Jan  9 15:02:47 2019 daemon.info hostapd: ath0: STA 98:ca:33:bc:8d:a6 WPA: pairwise key handshake completed (RSN)
Wed Jan  9 15:02:47 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_IP ath0: 98:ca:33:bc:8d:a6 / 10.191.23.104

Wed Jan  9 15:02:48 2019 daemon.notice stamgr: kick-sta-on 98:ca:33:bc:8d:a6 ath1 (reason:On other VAP)
Wed Jan  9 15:02:48 2019 kern.warn kernel: [394326.652347]  ieee80211_ioctl_kickmac[14876]
Wed Jan  9 15:02:52 2019 daemon.info hostapd: ath0: STA 98:ca:33:bc:8d:a6 IEEE 802.11: disassociated
Wed Jan  9 15:02:52 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath0: 98:ca:33:bc:8d:a6 / 1

Wed Jan  9 15:02:57 2019 user.info : wevent.ubnt_handle_custom_alert_sta_assoc(): EVT_AP_STA_ASSOC_TRACKER_DBG: event_id: 1 event_type: soft failure vap: ath0 sta_mac: 98:ca:33:bc:8d:a6 auth_ts: 394325.657414 auth_delta: 0 assoc_delta: 20000 wpa_auth_delta: 40000 radius_auth_delta: -1 radius_auth_status: N/A ip_delta: 40000 disassoc_count: 0 ip_assign_type: roamed auth_failures: 0 assoc_failures: 0 wpa_auth_failures: 0 ip_failures: 0 acl_status: allowed arp_status: N/A dns_status: N/A

Wed Jan  9 15:02:58 2019 daemon.info hostapd: ath1: STA 08:c5:e1:ba:96:8a IEEE 802.11: disassociated
Wed Jan  9 15:02:59 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath1: 08:c5:e1:ba:96:8a / 1

Wed Jan  9 15:09:51 2019 daemon.info hostapd: ath0: STA 80:2a:a8:54:1e:50 DRIVER: Sead AUTH addr=e4:e1:30:03:63:bf status_code=0
Wed Jan  9 15:09:54 2019 daemon.info hostapd: ath0: STA 80:2a:a8:54:1e:50 DRIVER: Sead AUTH addr=e4:e1:30:03:63:bf status_code=0
Wed Jan  9 15:09:55 2019 daemon.info hostapd: ath0: STA 80:2a:a8:54:1e:50 DRIVER: Sead AUTH addr=e4:e1:30:03:63:bf status_code=0
Wed Jan  9 15:09:55 2019 daemon.info hostapd: ath0: STA 80:2a:a8:54:1e:50 DRIVER: Sead AUTH addr=e4:e1:30:03:63:bf status_code=0
Wed Jan  9 15:10:00 2019 daemon.info hostapd: ath0: STA e4:e1:30:03:63:bf IEEE 802.11: disassociated
Wed Jan  9 15:10:01 2019 user.info : wevent.ubnt_handle_custom_alert_sta_assoc(): EVT_AP_STA_ASSOC_TRACKER_DBG: event_id: 1 event_type: failure vap: ath0 sta_mac: e4:e1:30:03:63:bf auth_ts: 394749.424676 auth_delta: -1 assoc_delta: -1 wpa_auth_delta: -1 radius_auth_delta: -1 radius_auth_status: N/A ip_delta: -1 disassoc_count: 0 ip_assign_type: N/A auth_failures: 9 assoc_failures: 0 wpa_auth_failures: 0 ip_failures: 0 acl_status: allowed arp_status: N/A dns_status: N/A

Wed Jan  9 15:18:49 2019 daemon.info hostapd: ath1: STA 80:2a:a8:55:1e:50 DRIVER: Sead AUTH addr=78:88:6d:73:95:47 status_code=0
Wed Jan  9 15:18:49 2019 daemon.info hostapd: ath1: STA 80:2a:a8:55:1e:50 DRIVER: Sead AUTH addr=78:88:6d:73:95:47 status_code=0
Wed Jan  9 15:18:49 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_JOIN ath1: 78:88:6d:73:95:47 / 1

Wed Jan  9 15:18:49 2019 daemon.info hostapd: ath1: STA 78:88:6d:73:95:47 IEEE 802.11: associated
Wed Jan  9 15:18:49 2019 kern.warn kernel: [395287.387003] wmi_unified_event_rx : no registered event handler : event id 0x901b
Wed Jan  9 15:18:49 2019 daemon.info hostapd: ath1: STA 78:88:6d:73:95:47 WPA: pairwise key handshake completed (RSN)
Wed Jan  9 15:18:49 2019 kern.warn kernel: [395287.436877] [wifi1] FWLOG: [2137382] RATE: ChainMask 3, phymode 1044489, ni_flags 0x02233006, vht_mcs_set 0xfffa, ht_mcs_set 0xffff, legacy_rate_set 0x209d31
Wed Jan  9 15:18:49 2019 kern.warn kernel: [395287.436912] [wifi1] FWLOG: [2137393] WAL_DBGID_SECURITY_ALLOW_DATA ( 0x438c70 )
Wed Jan  9 15:18:50 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_IP ath1: 78:88:6d:73:95:47 / 10.191.25.103

Wed Jan  9 15:18:51 2019 kern.warn kernel: [395289.437035] [wifi1] FWLOG: [2138522] RATE: ChainMask 3, phymode 1044490, ni_flags 0x02233006, vht_mcs_set 0xfffa, ht_mcs_set 0xffff, legacy_rate_set 0x60401
Wed Jan  9 15:18:59 2019 user.info : wevent.ubnt_handle_custom_alert_sta_assoc(): EVT_AP_STA_ASSOC_TRACKER_DBG: event_id: 1 event_type: success vap: ath1 sta_mac: 78:88:6d:73:95:47 auth_ts: 395287.48796 auth_delta: 0 assoc_delta: 0 wpa_auth_delta: 10000 radius_auth_delta: -1 radius_auth_status: N/A ip_delta: 1120000 disassoc_count: 0 ip_assign_type: dhcp auth_failures: 3 assoc_failures: 0 wpa_auth_failures: 0 ip_failures: 0 acl_status: allowed arp_status: yes dns_status: yes

Wed Jan  9 15:18:59 2019 daemon.info hostapd: ath1: STA 78:88:6d:73:95:47 RADIUS: starting accounting session 170A827588A359A2
Wed Jan  9 15:19:04 2019 daemon.info hostapd: ath1: STA 78:88:6d:73:95:47 IEEE 802.11: disassociated
Wed Jan  9 15:19:04 2019 user.info : wevent.ubnt_custom_event(): EVENT_STA_LEAVE ath1: 78:88:6d:73:95:47 / 1

Wed Jan  9 15:31:15 2019 daemon.info hostapd: ath1: STA a8:88:08:a0:65:76 WPA: group key handshake completed (RSN)


Etiquetado:

Comentarios

  • UBNT-RicardoUBNT-Ricardo 2531 Puntos
    Hola amigo, parece un problema en el cable, en el PoE o en el LAN de tu radio. 
  • IgnasiIgnasi 6 Puntos
    Muchas gracias por contestar tan rapido, no es eso, ya que es una instalacion que tiene 176 aps y pasa continuamente, a aps aleatorias, no siempre son las mismas, ademas como he dicho solo pasa desde que actualice el controlador y frimware.
  • Con qué alimentas los equipos??
  • IgnasiIgnasi 6 Puntos
    Con switches POE cisco smb sg350
  • IgnasiIgnasi 6 Puntos
    Pero insisto, tengo varias instalaciones asi y ninca habia tenido problemas y esta instalacion ha empezado a tener los problemas una vez actualizado los frimwares.
  • Regresa un par de equipos al firmware anterior para probar , pero no hemos tenidp reportes 
  • IgnasiIgnasi 6 Puntos
    @UBNT-Ricardo algun manual o algo para hacer el downgrade correctamente?

  • Realmente no es difícil en manual update selecciona la versión que deseas.
  • IgnasiIgnasi 6 Puntos
    Pongo el link del frimware en actualizacion personalizada, no?
  • Ignasi dijo:
    Pongo el link del frimware en actualizacion personalizada, no?
    Así es 
  • IgnasiIgnasi 6 Puntos
    Buenos dias Ricardo, solo decir que he hecho el downgrade y todo correcto, las 3 instalaciones que tengo con la misma topologia estan dando lo mismo problemas, el resto no, por lo que ya aseguro que es problema de la actualizacion
  • Gracias por el dato, dejame analizar si hay más reportes, en mi caso no tuve problemas con esa versión. 
  • Hola.
    Quería comunicar que este problema sigue pasando, además ahora en la ultima actualización se deja de soportar los firmware mas bajos como el 3.49 que funcionaba.
Accede o Regístrate para comentar.