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

  • UI-RicardoUI-Ricardo 2755 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.
  • UI-RicardoUI-Ricardo 2755 Puntos
    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.
  • UI-RicardoUI-Ricardo 2755 Puntos
    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?

  • UI-RicardoUI-Ricardo 2755 Puntos
    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?
  • UI-RicardoUI-Ricardo 2755 Puntos
    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
  • UI-RicardoUI-Ricardo 2755 Puntos
    Gracias por el dato, dejame analizar si hay más reportes, en mi caso no tuve problemas con esa versión. 
  • IgnasiIgnasi 6 Puntos
    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.
  • Hola estoy teniendo los mismos problemas tengo varios locaciones son un total de 73 AP de distintos modelos hay 14 que nunca se reinician unos cuantos que se han reiniciado un par de veces a la semana (parece que cuando hay más tráfico o conexiones) y otros cada una cuantas horas algunos 

    La versión instalada es 4.0.69.10871 y el controlador 5.12.35

    Entube probando subí en algunos ap de los que más fallaba a la 4.0.80.10875 y luego baje también algunos ap de los que más fallaba a la versión 4.0.42.10433 pero con el mismo problema otro problema que note es que en algunos equipos que no se reinicias tan seguidos y que son dual banda por algún motivo se deshabilita por uno o 2 minutos y luego se habilita la banda de 5.8Ghz los saque de los canales DFC y continúan con el problema 

    También los desasocie y volvo a adoptar y continúan con el problema

    El 6 de enero tuvimos un evento con más de 10000 personas y con promedio de 1300  dispositivos conectados simultáneos en una de las locaciones y fue caótico se reiniciaban continuamente por suerte no hubieron quejas ya que no se reiniciaban todos simultáneamente y los otros ap absorbían la carga en otros eventos anteriores con la mis cantidad no tuvimos problemas pero vercion anterior de controlado y fw

    Lo único que se me ocurre que puede ser algún tema del controlador o una mala configuración que se allá heredado de una vecino anterior y no sea compatible con esta?


  • Buen día

    También tengo un comportamiento simimlar, desde las últimas 2 actualizaciones, los AP se reinician, de manera aleatoria y sin un patrón en los horarios, ya probé haciendo un downgrade en uno de los AP, pero el comportamiento es el mismo. Los AP son: AP-LR, AP-AC-Lite, AP-AC-LR, AP-AC-PRO, SW 24 POE-250W, USG4; Versión de FW: 4.0.69.10871; CK Versión: 5.12.35-12979-1, FW: UCK.mtk7623.v1.1.6.c289a3c.191031.0856.

     

    Nota: Todos los AP vienen alimentados por el SW 24 POE-250W

    Alguna nueva sugerencia para probar?


  • Por acá el mismo problema, tengo al versión 4.0.69.10871 en los AP y en Controlador: 5.12.35.0
    Al parecer el Controlador es el problema, alguna forma de downgradear a una version estable como se comenta mas arriba (version 3.49)? 
  • Gracias por el dato, dejame analizar si hay más reportes, en mi caso no tuve problemas con esa versión. 
    Hola Ricardo buen día.

    Disculpa la molestia, tendrás ya algún dato acerca del tema, en mi caso sigo con problemas de reseteo, algunos AP les hice un downgrade, otros los tengo actualizados, pero el problema persiste y sin patrón aparente.

    Saludos
  • rabalgarrabalgar 0 Puntos
    editado 23 de febrero
    Hola.

    Tengo el mismo problema. Los puntos de acceso, de manera aleatoria se desconectan del controlador. Si los apago y los enciendo vuelven a conectarse, pero al cabo de un tiempo, de manera aleatoria, vuelven a desconectarse.

    Despues de muchas pruebas he encontrado la solución parcial al problema. He quitado la lista blanca de MACS que tenía en una de las redes. En otra de las redes la lista negra sigue funcionando.

    He hecho muchas pruebas con múltiples configuraciones, incluso he realizado una instalación limpia del controlador y, si activo la lista blanca, al cabo de un rato, cuando los clientes van conectandose y haciendo roaming entre los AP, los puntos de acceso se desconectan de manera aleatoria.

    A Jollemir lo conozco personalmente y tiene el mismo problema:
    https://forum-es.ubnt.com/discussion/1505062/desconexiones-aleatorias-de-los-aps-del-controller#latest  

Accede o Regístrate para comentar.