Mobility Express Issues

ross_rulz · ‎09-04-2018

Hi,

I have 5 Cisco 1852 mobility express access points and have all been working fine. But in the last week 4 of the access points keep flapping between standalone and connected mode. I've tried rebooting the access points which join the controller but then disconnect. The software version on all the access points is 8.5.120.0. Find below the logs from one I rebooted today:

Depot-AP01#show log
System logging:
Sep 5 00:48:23 syslogd started: BusyBox v1.23.2
Sep 5 00:48:23 kernel: printkd started: BusyBox v1.23.2 (2018-01-30 02:33:23 PST)
Sep 5 00:48:23 kernel: klogd started: BusyBox v1.23.2 (2018-01-30 02:33:23 PST)
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:37.2985] buginf() enabled.
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:37.3085] Made it into bootsh: Jan 30 2018 02:54:22
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:37.3085] bootsh build T-aa5421185f6af7f1b26f46379e8dc11f6136c54c-gaa542118-vipendya
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:39.0279] ^Minit started: BusyBox v1.23.2 (2018-01-30 02:33:23 PST)
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:40.7674] GCM-128 POST passed
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:40.7674] GCM-256 POST passed
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.0873] Active version: 8.5.120.0
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.0973] Backup version: 8.3.112.0
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.3272]
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.3272] led pattern module start
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.4072] AP1852I
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.5272] nss_driver - Turbo Support 1
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.5272] Supported Frequencies - 110Mhz 550Mhz 733Mhz
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.5572] NSS firmware booted
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.5871] NSS firmware booted
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.6771]
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:41.6771] phy_value=0: org="0x1000" phy_reg="0x1800"
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:42.0270] Current value of FACTORY_RESET=0
Sep 5 00:48:23 kernel: [*01/01/1970 00:00:42.0470] Last reload time: Sep 5 00:48:23 2018
Sep 5 00:48:23 kernel: [*09/05/2018 00:48:23.0000] Setting system time Wed Sep 5 00:48:23 UTC 2018
Sep 5 00:48:23 kernel: [*09/05/2018 00:48:23.0599] device wired0 entered promiscuous mode
Sep 5 00:48:23 kernel: [*09/05/2018 00:48:23.1299] device wired1 entered promiscuous mode
Sep 5 00:48:23 kernel: [*09/05/2018 00:48:23.5798] stile module dp init successfully
Sep 5 00:48:23 kernel: [*09/05/2018 00:48:23.5798] NBAR Label:BLD_MCP_DEV_LATEST_20150424_123021 NBAR VERSION:module build date: Jan 30 2018 at 02:50:55
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:23.9897] ADDRCONF(NETDEV_UP): wired0: link is not ready
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.0297] bonding: Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.2696] miibus get ef438c00!
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.2996]
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3096] boot 268 build T-aa5421185f6af7f1b26f46379e8dc11f6136c54c-gaa542118-vipendya board corsica mac DC:CE:C1:20:13:88
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: mbedtls .text=0xbf000000 data=0xbf007e18 bss=0xbf008070
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: aptrace .text=0xbf00c000 data=0xbf018834 bss=0xbf018cc0
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: led_module .text=0xbf020000 data=0xbf0204e0 bss=0xbf020b48
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: qca_nss_gmac .text=0xbf024000 data=0xbf02abc0 bss=0xbf02b084
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: qca_nss_drv .text=0xbf034000 data=0xbf04fb60 bss=0xbf050910
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: qca_nss_capwapmgr .text=0xbf071000 data=0xbf075acc bss=0xbf075c20
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: nssmgrctl .text=0xbf07c000 data=0xbf07d70c bss=0xbf07da30
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: proclikefs .text=0xbf083000 data= bss=0xbf083b40
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: stile_lm_ft .text= data= bss=
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: stile_lm_dp .text= data= bss=
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: ipv6 .text=0xbfa07000 data=0xbfa37f00 bss=0xbfa397ac
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: bonding .text=0xbfa53000 data=0xbfa64628 bss=0xbfa64a54
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: lowmemorykiller .text=0xbfa71000 data=0xbfa712c0 bss=0xbfa7144c
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: qca_nss_profile_drv .text=0xbfa75000 data=0xbfa76320 bss=0xbfa76494
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: scsi_wait_scan .text= data= bss=
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3196] Module: ssdk_reg .text=0xbfa7f000 data= bss=0xbfa7f838
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.3696] phram: phram-oops device: 0x2000 at 0x42520000
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.6695] phram: phram-oops device: 0x10000 at 0x42500000
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.7594] ramoops: platform device not found, using module parameters
Sep 5 00:48:24 kernel: [*09/05/2018 00:48:24.7994] Last reload reason : 1: reload command
Sep 5 00:48:25 FIPS[2445]: *** fastcgi: FIPS Mode = disabled ***
Sep 5 00:48:25 FIPS[2447]: *** fastcgi: FIPS Mode = disabled ***
Sep 5 00:48:26 FIPS[2450]: *** brain: FIPS Mode = disabled ***
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.3589] ADDRCONF(NETDEV_CHANGE): wired0: link becomes ready
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.3689] wired0: 1000 Mbps Full Duplex
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.3689]
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 rmmod: module is not loaded
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4189] __mm_init_module
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4389] ath_dfs: Version 2.0.0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4389] Copyright (c) 2005-2006 Atheros Communications, Inc. All Rights Reserved
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] ath_ol_pci: (Atheros/multi-bss)
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] ol_ath_pci_probe: PCI device id 0040 :0040
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] *********** Beeliner *************
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] __ol_ath_attach() Allocated scn ee080480
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] ol_ath_pci_configure : num_desired MSI set to 0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] Using PCI Legacy Interrupt
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4689] chip_id 0x9 chip_revision 0x1
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] CLOCK PLL skipped
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] HIF_PCIDeviceProbed: setting the target pll frac ffffffff intval ffffffff
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] HIF_PCIDeviceProbed: no frac provided, skipping pre-configuring PLL
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] HIF_PCIDeviceProbed: targ_clk is not provided, skipping pre-configuring PLL
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] __ol_ath_attach: dev name wifi0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] ol_ath_attach interface_id 0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] ol_target_init() BMI inited.
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] ol_target_init() BMI Get Target Info.
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789] Chip id: 0x9, chip version: 0x1000000
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4789]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4989] qc98xx_verify_checksum: flash checksum passed: 0xef28
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4989] ol_transfer_bin_file 3974: Download Flash data len 12064
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.4989] Board extended Data download address: 0x0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5189]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5189] Board data initialized
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5189] ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5189]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5189] Selecting OTP binary for CHIP Version 1
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5289] ol_transfer_bin_file 3793: downloading file 0, Download data len 11100
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689] First OTP send param 8000
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689] ol_ath_download_firmware :First OTP download and Execute is good address:0xa000 return param 4660
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689] ol_ath_download_firmware:##Board Id 8 , CHIP Id 1
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689] ol_ath_download_firmware: BOARDDATA DOWNLOAD TO address 0xc0000
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5689] ol_transfer_bin_file: Board Data File download to address=0xc0000 file name=AR900B/hw.2/boardData_AR900B_CUS260_negative_pwr_offset_2G_v2_008.bin
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5789] ol_transfer_bin_file 3793: downloading file 3, Download data len 12064
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5789] Board extended Data download address: 0x0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5989] ol_ath_download_firmware: Using 0x1234 for the remainder of init
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5989]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.5989] Selecting OTP binary for CHIP Version 1
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6189] ol_transfer_bin_file 3793: downloading file 0, Download data len 11100
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489] [Flash] : Ignore Module param
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489] Second otp download Param 10000
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489] ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489]
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6489] Mission mode: Firmware CHIP Version 1
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6589] ol_swap_seg_alloc: Successfully allocated memory for SWAP size=262144
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] Swap: bytes_left to copy: fw:16; dma_page:76179
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] Swap: wrong length read:0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185965 Target address 417fe0
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] scn=ee080480 target_write_addr=417fe0 seg_info=ee604610
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] bin_filename=AR900B/hw.2/athwlan.bin swap_filename=/lib/firmware/AR900B/hw.2/athwlan.codeswap.bin
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6788] ol_transfer_bin_file: Downloading firmware file: AR900B/hw.2/athwlan.bin
Sep 5 00:48:26 kernel: [*09/05/2018 00:48:26.6888] ol_transfer_bin_file 3793: downloading file 1, Download data len 362100
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] ol_target_init() Download FW done.
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] ol_ath_attach() WMI attached. wmi_handle ed100000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] htc_wmi_init() HT Create . c49af000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] htc_wmi_init() HIF Claim.
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] htc_wmi_init 7842 host_enable 0 nss_nwifi_offload 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko)
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7685] OL_ACBKMinfree : 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] OL_ACBEMinfree : 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] OL_ACVIMinfree : 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] OL_ACVOMinfree : 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] CE_recv_buf_enqueue 877 Populate last entry 512 for CE 5
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] CE_recv_buf_enqueue 886 CE 5 wi 511 dest_ptr 0x6e0f7040 nbytes 0 recv_ctxt 0xecb48200
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] HTC Rx: insufficient length, got:4 expected =8
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] Startup Mode-0 set
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] <=== cfg max peer id 1056 ====>
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] HTC Service:0x0300 ep:1 TX flow control disabled
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] CE_pkt_dl_len_set CE 4 Pkt download length 64
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] ol_txrx_pdev_attach: 2500 tx desc's allocated ; range starts from ec920000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] HTC Service:0x0100 ep:2 TX flow control disabled
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] wmi_service_ready_event_rx: WMI UNIFIED SERVICE READY event
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] Firmware_Build_Number:70
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] num_rf_chain:0x00000004 ht_cap_info:0x0000185b vht_cap_info:0x339a79b2 vht_supp_mcs:0x0000ffaa
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] RES CFG Support wmi_service_bitmap 9778
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] Sending Ext resource cfg: HOST PLATFORM as 0 and fw_feature_bitmap as 50 to TGT
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] ol_ath_service_ready_event: tt_support: 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] ol_ath_service_ready_event: periodic_chan_stats: 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] ol_ath_service_ready_event: sw_cal_support_check_flag: 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] Peer Caching Enabled ; num_peers = 234, num_active_peers = 53 num_tids = 106, num_vdevs = 18
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 54
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 54
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 54
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 0 req 1 num_units 0 num_unit_info 2 unit size 1272 actual units 235
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] idx 6 req 5 num_units 0 num_unit_info 2 unit size 1920 actual units 235
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 0 len 13824 requested ,ptr 0x6ddac000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 1 len 55296 requested ,ptr 0x6de10000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 2 len 221184 requested ,ptr 0x6de80000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 3 len 298920 requested ,ptr 0x6df00000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 4 len 107520 requested ,ptr 0x6dec0000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 5 len 6144 requested ,ptr 0x6dee0000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.7785] chunk 6 len 451200 requested ,ptr 0x6df80000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] wmi_ready_event_rx: WMI UNIFIED READY event
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] ol_ath_connect_htc() WMI is ready
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] ol_ath_attach() connect HTC.
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] bypasswmi : 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] target uses HTT version 2.2; host uses 2.2
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] ol_ath_phyerr_attach: called
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] OL Resmgr Init-ed
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] acfg_attach: 3522: Netlink socket created:c4aafa00
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585] Green-AP : Green-AP : Attached
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8585]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] __ol_ath_attach: dev name wifi1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] ol_ath_attach interface_id 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] ol_target_init() BMI inited.
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] ol_target_init() BMI Get Target Info.
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] Chip id: 0x9, chip version: 0x1000000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] CE WAR Disabled
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] NUM_DEV=1 FWMODE=0x2 FWSUBMODE=0x0 FWBR_BUF 0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] ol_target_init() configure Target .
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8785] Target Version is 1000000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8985] qc98xx_verify_checksum: flash checksum passed: 0xd012
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8985] ol_transfer_bin_file 3974: Download Flash data len 12064
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.8985] Board extended Data download address: 0x0
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9185]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9185] Board data initialized
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9185] ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9185]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9185] Selecting OTP binary for CHIP Version 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9285] ol_transfer_bin_file 3793: downloading file 0, Download data len 11100
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684]
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684] First OTP send param 8000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684] ol_ath_download_firmware :First OTP download and Execute is good address:0x9c00 return param 4660
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684] ol_ath_download_firmware:##Board Id 7 , CHIP Id 1
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684] ol_ath_download_firmware: BOARDDATA DOWNLOAD TO address 0xc0000
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9684] ol_transfer_bin_file: Board Data File download to address=0xc0000 file name=AR900B/hw.2/boardData_AR900B_CUS239_negative_pwr_offset_5G_v2_007.bin
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9784] ol_transfer_bin_file 3793: downloading file 3, Download data len 12064
Sep 5 00:48:27 kernel: [*09/05/2018 00:48:27.9784] Board extended Data download address: 0x0
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:27.9984] ol_ath_download_firmware: Using 0x1234 for the remainder of init
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:27.9984]
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:27.9984] Selecting OTP binary for CHIP Version 1
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0084] ol_transfer_bin_file 3793: downloading file 0, Download data len 11100
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484]
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484] [Flash] : Ignore Module param
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484]
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484] Second otp download Param 10000
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484] ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484]
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0484] Mission mode: Firmware CHIP Version 1
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0584] ol_swap_seg_alloc: Successfully allocated memory for SWAP size=262144
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0684] Swap: bytes_left to copy: fw:16; dma_page:76179
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0684] Swap: wrong length read:0
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0684] ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185965 Target address 417fe0
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0784] scn=ec3c0480 target_write_addr=417fe0 seg_info=ee604e10
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0784] ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0784] bin_filename=AR900B/hw.2/athwlan.bin swap_filename=/lib/firmware/AR900B/hw.2/athwlan.codeswap.bin
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0784] ol_transfer_bin_file: Downloading firmware file: AR900B/hw.2/athwlan.bin
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.0884] ol_transfer_bin_file 3793: downloading file 1, Download data len 362100
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.7782] FWLOG: [47798] WAL_DBGID_TX_AC_BUFFER_SET ( 0x3, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.7782] FWLOG: [47798] WAL_DBGID_TX_AC_BUFFER_SET ( 0x12, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.7782] FWLOG: [47798] WAL_DBGID_TX_AC_BUFFER_SET ( 0x45, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:28 kernel: [*09/05/2018 00:48:28.7782] FWLOG: [47798] WAL_DBGID_TX_AC_BUFFER_SET ( 0x67, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] ol_target_init() Download FW done.
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] ol_ath_attach() WMI attached. wmi_handle ed930000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] htc_wmi_init() HT Create . ed081000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] htc_wmi_init() HIF Claim.
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] htc_wmi_init 7842 host_enable 0 nss_nwifi_offload 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko)
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] OL_ACBKMinfree : 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] OL_ACBEMinfree : 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] OL_ACVIMinfree : 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] OL_ACVOMinfree : 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1681] CE_recv_buf_enqueue 877 Populate last entry 512 for CE 5
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] wmi_service_ready_event_rx: WMI UNIFIED SERVICE READY event
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] Firmware_Build_Number:70
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] num_rf_chain:0x00000004 ht_cap_info:0x0000185b vht_cap_info:0x339b79b2 vht_supp_mcs:0x0000ffaa
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781]
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] RES CFG Support wmi_service_bitmap 9778
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781]
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] Sending Ext resource cfg: HOST PLATFORM as 0 and fw_feature_bitmap as 50 to TGT
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] ol_ath_service_ready_event: tt_support: 1
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] ol_ath_service_ready_event: periodic_chan_stats: 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] ol_ath_service_ready_event: sw_cal_support_check_flag: 1
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] Peer Caching Enabled ; num_peers = 234, num_active_peers = 53 num_tids = 106, num_vdevs = 18
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 54
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 54
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 54
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 0 req 1 num_units 0 num_unit_info 2 unit size 1272 actual units 235
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] idx 6 req 5 num_units 0 num_unit_info 2 unit size 1920 actual units 235
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 0 len 13824 requested ,ptr 0x6e57c000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 1 len 55296 requested ,ptr 0x6da40000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 2 len 221184 requested ,ptr 0x6da80000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 3 len 298920 requested ,ptr 0x6db00000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 4 len 107520 requested ,ptr 0x6da60000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 5 len 6144 requested ,ptr 0x6e578000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.1781] chunk 6 len 451200 requested ,ptr 0x6db80000
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] wmi_ready_event_rx: WMI UNIFIED READY event
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_ath_connect_htc() WMI is ready
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 1
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_ath_attach() connect HTC.
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] bypasswmi : 0
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] target uses HTT version 2.2; host uses 2.2
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_ath_phyerr_attach: called
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] OL Resmgr Init-ed
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] acfg_attach: Offload using existing sock c4aafa00
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] Green-AP : Green-AP : Attached
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480]
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] Green-AP : Attached
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] rate power table override is only supported for AR98XX
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_if_dfs_setup: called
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_if_dfs_attach: called; ptr=ed951984, radar_info=ee531b90
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] ol_ath_attach() UMAC attach .
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480]
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2480] BURSTING enabled by default
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2580] ACS not enabled
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2580] __ol_ath_attach: needed_headroom reservation 60
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2580] ol_ath_thermal_mitigation_attach: --
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2780] ath_hal: 0.9.17.1 (AR5416, AR9380, WRITE_EEPROM, TX_DATA_SWAP, RX_DATA_SWAP, 11D)
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2780] ath_rate_atheros: Copyright (c) 2001-2005 Atheros Communications, Inc, All Rights Reserved
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2880] ath_dev: Copyright (c) 2001-2007 Atheros Communications, Inc, All Rights Reserved
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2980] pktlog_init: Initializing Pktlog for AR900B, pktlog_hdr_size = 16
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.2980] pktlog_init: Initializing Pktlog for AR900B, pktlog_hdr_size = 16
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.4280] ADDRCONF(NETDEV_UP): wired1: link is not ready
Sep 5 00:48:29 dnsmasq[2660]: started, version 2.78 cachesize 150
Sep 5 00:48:29 dnsmasq[2660]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
Sep 5 00:48:29 dnsmasq[2660]: read /etc/hosts - 3 addresses
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.4680] brain: unloading click config
Sep 5 00:48:29 kernel: [*09/05/2018 00:48:29.4680] brain: spring cleaning pages
Sep 5 00:48:30 kernel: [*09/05/2018 00:48:30.1678] FWLOG: [49180] WAL_DBGID_TX_AC_BUFFER_SET ( 0x3, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:30 kernel: [*09/05/2018 00:48:30.1678] FWLOG: [49180] WAL_DBGID_TX_AC_BUFFER_SET ( 0x12, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:30 kernel: [*09/05/2018 00:48:30.1678] FWLOG: [49180] WAL_DBGID_TX_AC_BUFFER_SET ( 0x45, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:30 kernel: [*09/05/2018 00:48:30.1678] FWLOG: [49180] WAL_DBGID_TX_AC_BUFFER_SET ( 0x67, 0x1e, 0x94c, 0x94c, 0x0 )
Sep 5 00:48:30 kernel: [*09/05/2018 00:48:30.6276] brain: loading click config
Sep 5 00:48:31 kernel: [*09/05/2018 00:48:31.3274] pid 2450's current affinity mask: 3
Sep 5 00:48:31 kernel: [*09/05/2018 00:48:31.3274] pid 2450's new affinity mask: 1
Sep 5 00:48:32 kernel: [*09/05/2018 00:48:32.8969] click-config/io-real.click:131: While configuring 'todev_wired0 :: ToDevice':
Sep 5 00:48:32 kernel: [*09/05/2018 00:48:32.8969] warning: device 'wired0' is down
Sep 5 00:48:32 kernel: [*09/05/2018 00:48:32.9369] click-config/io-real.click:131: While configuring 'fromdev_wired0 :: FromDevice':
Sep 5 00:48:32 kernel: [*09/05/2018 00:48:32.9369] warning: device 'wired0' is down
Sep 5 00:48:32 upgrade: Image verification failed.
Sep 5 00:48:32 upgrade: Cleaning up tmp directory.
Sep 5 00:48:33 kernel: [*09/05/2018 00:48:33.5167]
Sep 5 00:48:33 kernel: [*09/05/2018 00:48:33.5167] Click sched monitor: schedulers = 1
Sep 5 00:48:36 upgrade: Image verification failed.
Sep 5 00:48:36 upgrade: Cleaning up tmp directory.
Sep 5 00:48:36 kernel: [*09/05/2018 00:48:36.5458] click-config/io-real.click:131: While initializing 'tohost_wired0 :: ToHost':
Sep 5 00:48:36 kernel: [*09/05/2018 00:48:36.5458] warning: device 'wired0' is down
Sep 5 00:48:36 kernel: [*09/05/2018 00:48:36.6157] ADDRCONF(NETDEV_UP): wired0: link is not ready
Sep 5 00:48:36 kernel: [*09/05/2018 00:48:36.6157] ADDRCONF(NETDEV_UP): wired1: link is not ready
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.0656] wmi_dbg_cfg_send: mod[0]00000000 dbgcfg40000000 cfgvalid[0] 00000000 cfgvalid[1] 00000000
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.0656] wmi_dbg_cfg_send: mod[0]00000000 dbgcfg40000000 cfgvalid[0] 00000000 cfgvalid[1] 00000000
Sep 5 00:48:37 brain: do_state:6325: assigning brain state: scan1; reason: initial_scan
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.3955] ADDRCONF(NETDEV_CHANGE): wired0: link becomes ready
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.4055] wired0: 1000 Mbps Full Duplex
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.4055]
Sep 5 00:48:37 FIPS[3815]: *** lighttpd: FIPS Mode = disabled ***
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.7754] wired0: Link down
Sep 5 00:48:37 kernel: [*09/05/2018 00:48:37.7754]
Sep 5 00:48:38 kernel: [*09/05/2018 00:48:38.1653] chatter: tohost_srcr6 :: ToHost: device 'srcr6' went down
Sep 5 00:48:38 kernel: [*09/05/2018 00:48:38.1853] chatter: tohost_srcr6 :: ToHost: device 'srcr6' came up
Sep 5 00:48:38 kernel: [*09/05/2018 00:48:38.1952] ip6_port srcr6, ip6local fe80::dece:c1ff:fe20:1388, ip6 ::, plen 0, gw6 ::, gw6_mac 00:00:00:00:00:00, mtu 1500, vid 0, mode6 3(slaac)
Sep 5 00:48:38 dnsmasq[2660]: exiting on receipt of SIGTERM
Sep 5 00:48:38 dnsmasq[3864]: started, version 2.78 cachesize 150
Sep 5 00:48:38 dnsmasq[3864]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
Sep 5 00:48:38 dnsmasq[3864]: warning: using interface srcr2 instead
Sep 5 00:48:38 dnsmasq[3864]: warning: using interface srcr2 instead
Sep 5 00:48:38 dnsmasq[3864]: warning: using interface srcr2 instead
Sep 5 00:48:38 dnsmasq[3864]: read /etc/hosts - 3 addresses
Sep 5 00:48:38 brain: transitioning from init to scan1
Sep 5 00:48:38 brain: CDP PoE negotiation START
Sep 5 00:48:39 kernel: [*09/05/2018 00:48:39.4049] wired0: 1000 Mbps Full Duplex
Sep 5 00:48:39 kernel: [*09/05/2018 00:48:39.4049]
Sep 5 00:48:40 brain: CDP PoE negotiation OK, Allocated power 24800 mWatt Requested power 24800 mWatt
Sep 5 00:48:40 brain: Power mode: Full-Power, power_detection: DC_adapter(FALSE), 802.3AF_POE(TRUE)
Sep 5 00:48:40 kernel: [*09/05/2018 00:48:40.5945]
Sep 5 00:48:40 kernel: [*09/05/2018 00:48:40.5945] phy_value=0: org="0x1800" phy_reg="0x1000"
Sep 5 00:48:47 kernel: [*09/05/2018 00:48:47.4624] Loading sha2...
Sep 5 00:48:47 kernel: [*09/05/2018 00:48:47.4624] Dumpping sha1...
Sep 5 00:48:49 kernel: [*09/05/2018 00:48:49.1519] Loading MIC cert and key
Sep 5 00:48:49 kernel: [*09/05/2018 00:48:49.1519] Dumpping sha2...
Sep 5 00:48:50 kernel: [*09/05/2018 00:48:50.8413] Loading MIC cert and key
Sep 5 00:48:51 kernel: [*09/05/2018 00:48:51.2112] Generating key, this may take a while...
Sep 5 00:48:53 kernel: [*09/05/2018 00:48:53.5105] Public key portion is:
Sep 5 00:48:53 kernel: [*09/05/2018 00:48:53.5105] ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCYczbvjLrfmxmGYOA34gvzN283TynQ7oNyr2BbsJjPTJ82BWhSDvJH0bPJLsHfHTAnQvUgtbcUG1Hcx4qB4PKOi7mCZnC0h99hprcKdqaYfQbFsNbnGFFxVmUrv4cA/RFno6g5OKrrmvawlH0hdx6rQcC1QWgvqdxA
Sep 5 00:48:53 kernel: [*09/05/2018 00:48:53.5105] Fingerprint: md5 1f:c3:5a:b2:8b:20:2f:bb:a3:22:44:e4:c3:0f:81:ed
Sep 5 00:48:53 kernel: [*09/05/2018 00:48:53.5705] pid 3958's current affinity mask: 3
Sep 5 00:48:53 kernel: [*09/05/2018 00:48:53.5705] pid 3958's new affinity mask: 1
Sep 5 00:48:54 syslog: Password for 'admin' changed
Sep 5 00:48:54 kernel: [*09/05/2018 00:48:54.0403] Password for 'admin' changed
Sep 5 00:48:54 syslog: +gzip -dc /etc/wcp.usermodule.template.gz | sed -e 's/__TX_POWER_2G_FILE__/\/radio_fw\/R2.csv/g' -e 's/__TX_POWER_5G_FILE__/\/radio_fw\/R5.csv/g' -e 's/__EVM_POWER_2G_FILE__/\/radio_fw\/EVM_2.csv/g' -e 's/__EVM
Sep 5 00:48:54 syslog: _POWER_5G_FILE__/\/radio_fw\/EVM_5.csv/g' -e 's/__DOMAIN_2G__/0/g' -e 's/__DOMAIN_5G__/48/g' -e 's/__PWR_TABLE_49G__/0/g' -e 's/__PRODUCT_ID__/AIR-AP1852I-Z-K9/g' -e 's/__AP_TYPE__/48/g' -e 's/__WIRELESS_MAC__/
Sep 5 00:48:54 syslog: DC:CE:C1:20:B4:80/g' -e 's/__WIRED_MAC__/DC:CE:C1:20:13:88/g' -e 's/__WIRED1_MAC__/DC:CE:C1:20:13:88/g' | DMALLOC_OPTIONS=debug=0x400003,inter=1000,log=/tmp/wcp.dmalloc.log,limit=40000000 wcpd &
Sep 5 00:48:54 kernel: [*09/05/2018 00:48:54.1603] gzip -dc /etc/wcp.usermodule.template.gz | sed -e 's/__TX_POWER_2G_FILE__/\/radio_fw\/R2.csv/g' -e 's/__TX_POWER_5G_FILE__/\/radio_fw\/R5.csv/g' -e 's/__EVM_POWER_2G_FILE__/\/radio_fw\/EVM_2.csv/g' -
Sep 5 00:48:54 FIPS[4011]: *** wcpd: FIPS Mode = disabled ***
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.5499] DOT11_DRV[0]: Init Radio0
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.5699] DOT11_DRV[0]: Stop Radio0
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.5799] DOT11_DRV[0]: set_channel Channel set to 6
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.5899] DOT11_DRV[1]: Init Radio1
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.6199] DOT11_DRV[1]: Stop Radio1
Sep 5 00:48:55 kernel: [*09/05/2018 00:48:55.6199] DOT11_DRV[1]: set_channel Channel set to 36
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.2597] DOT11_CFG[0] Radio Mode is changed from Local to FlexConnect
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.2597] DOT11_DRV[0]: Start Radio0
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.2696] DOT11_CFG[1] Radio Mode is changed from Local to FlexConnect
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.2696] DOT11_DRV[1]: Start Radio1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.2896] DOT11_DRV[0]: Stop Radio0
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6595] DOT11_DRV[0]: set_channel Channel set to 1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6595] DOT11_DRV[0]: Start Radio0
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6595] DOT11_DRV[0]: Channel set to 1, width 20
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6695] DOT11_DRV[0]: Stop Radio0
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6695] DOT11_DRV[0]: Start Radio0
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6695] DOT11_DRV[0]: Channel set to 1, width 20
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6795] DOT11_DRV[1]: Stop Radio1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6995] DOT11_DRV[1]: set_channel Channel set to 52
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6995] DOT11_DRV[1]: Start Radio1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.6995] DOT11_DRV[1]: Stop Radio1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.7195] DOT11_DRV[1]: Start Radio1
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.7195] DOT11_DRV[1]: Channel set to 52, width 80
Sep 5 00:48:56 kernel: [*09/05/2018 00:48:56.7195] DOT11_DRV[1]: Stop Radio1
Sep 5 00:48:57 kernel: [*09/05/2018 00:48:57.0094] ol_if_dfs_enable: called
Sep 5 00:48:57 kernel: [*09/05/2018 00:48:57.0194] DOT11_DRV[1]: Start Radio1
Sep 5 00:48:57 kernel: [*09/05/2018 00:48:57.0194] ol_if_dfs_enable: called
Sep 5 00:48:57 kernel: [*09/05/2018 00:48:57.0194] DOT11_DRV[1]: Channel set to 52, width 80
Sep 5 00:49:01 kernel: [*09/05/2018 00:49:01.0482] Waiting for uplink IPv4 configuration
Sep 5 00:49:06 kernel: [*09/05/2018 00:49:06.0566] Waiting for uplink IPv4 configuration
Sep 5 00:49:06 brain: dhcpc succeeded on wired0
Sep 5 00:49:06 brain: do_state:6507: assigning brain state: gateway_check 0; reason: dhcp_on_wired0
Sep 5 00:49:06 dnsmasq[4060]: started, version 2.78 cachesize 150
Sep 5 00:49:06 dnsmasq[4060]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
Sep 5 00:49:06 brain: transitioning from scan1 to gateway_check 0
Sep 5 00:49:06 dnsmasq[4060]: using nameserver 172.20.13.241#53
Sep 5 00:49:06 dnsmasq[4060]: using nameserver 172.20.13.240#53
Sep 5 00:49:06 dnsmasq[4060]: read /etc/hosts - 3 addresses
Sep 5 00:49:06 dnsmasq[4060]: exiting on receipt of SIGTERM
Sep 5 00:49:07 dnsmasq[4066]: started, version 2.78 cachesize 150
Sep 5 00:49:07 dnsmasq[4066]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
Sep 5 00:49:07 brain: do_state:6415: assigning brain state: gateway_good 0; reason: internet and dns connectivity successful
Sep 5 00:49:07 brain: transitioning from gateway_check 0 to gateway_good 0
Sep 5 00:49:07 brain: boot time: 45.320 seconds, 4067 processes spawned
Sep 5 00:49:07 dnsmasq[4066]: using nameserver 172.20.13.241#53
Sep 5 00:49:07 dnsmasq[4066]: using nameserver 172.20.13.240#53
Sep 5 00:49:07 dnsmasq[4066]: read /etc/hosts - 3 addresses
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:06.8463] ethernet_port wired0, ip 172.16.113.21, netmask 255.255.255.0, gw 172.16.113.254, mtu 1500, bcast 172.16.113.255, dns1 172.20.13.240, dns2 172.20.13.241, domain cop.orgDOT11_CFG[0] Radio Mode is chang
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.1647] DOT11_CFG[1] Radio Mode is changed from FlexConnect to FlexConnect
Sep 5 00:49:12 syslog: +/usr/bin/cisco_core.sh
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.2646] AP IPv4 Address updated from 0.0.0.0 to 172.16.113.21
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] dtls_init: Use MIC device cert
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] dtls_init: Use MIC device cert private key
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046]
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] CAPWAP State: Init
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046]
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] PNP is not required, Starting CAPWAP discovery
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046]
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046]
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] CAPWAP State: Discovery
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] Got WLC address 0.172.20.14 from DHCP.
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3046] IP DNS query for CISCO-CAPWAP-CONTROLLER.cop.org
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3146] Discovery Request sent to 172.16.113.1, discovery type STATIC_CONFIG(1)
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3146] Discovery Request sent to 0.172.20.14, discovery type DHCP(2)
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3246] Discovery Request sent to 255.255.255.255, discovery type UNKNOWN(0)
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3246]
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3246] CAPWAP State: Discovery
Sep 5 00:49:12 kernel: [*09/05/2018 00:49:12.3246] Discovery Response from 172.16.113.1
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.0000] Discovery Response from 172.16.113.1
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.0000]
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.0000] CAPWAP State: DTLS Setup
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.2399]
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.2399] CAPWAP State: Join
Sep 5 00:50:55 kernel: [*09/05/2018 00:50:55.2399] Sending Join request to 172.16.113.1 through port 5256
Sep 5 00:51:18 FIPS[4192]: *** shell: FIPS Mode = disabled ***
Sep 5 00:51:30 kernel: [*09/05/2018 00:51:30.4589] srcr6: no IPv6 routers present
Sep 5 00:51:52 kernel: [*09/05/2018 00:51:52.0222]
Sep 5 00:51:52 kernel: [*09/05/2018 00:51:52.0222] CAPWAP State: DTLS Teardown
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.8907]
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.8907] CAPWAP State: Discovery
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.8907] Got WLC address 0.172.20.14 from DHCP.
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.8907] IP DNS query for CISCO-CAPWAP-CONTROLLER.cop.org
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.8907] Discovery Request sent to 172.16.113.1, discovery type STATIC_CONFIG(1)
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.9007] Discovery Request sent to 0.172.20.14, discovery type DHCP(2)
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.9007] Discovery Request sent to 255.255.255.255, discovery type UNKNOWN(0)
Sep 5 00:51:56 kernel: [*09/05/2018 00:51:56.9007] Discovery Response from 172.16.113.1
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0000] Discovery Response from 172.16.113.1
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0000]
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0000] CAPWAP State: DTLS Setup
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0699]
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0699] CAPWAP State: Join
Sep 5 00:52:06 kernel: [*09/05/2018 00:52:06.0699] Sending Join request to 172.16.113.1 through port 5256
Sep 5 00:53:03 kernel: [*09/05/2018 00:53:03.0222]
Sep 5 00:53:03 kernel: [*09/05/2018 00:53:03.0222] CAPWAP State: DTLS Teardown
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8807]
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8807] CAPWAP State: Discovery
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8807] Got WLC address 0.172.20.14 from DHCP.
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8807] IP DNS query for CISCO-CAPWAP-CONTROLLER.cop.org
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8907] Discovery Request sent to 172.16.113.1, discovery type STATIC_CONFIG(1)
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8907] Discovery Request sent to 0.172.20.14, discovery type DHCP(2)
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8907] Discovery Request sent to 255.255.255.255, discovery type UNKNOWN(0)
Sep 5 00:53:07 kernel: [*09/05/2018 00:53:07.8907] Discovery Response from 172.16.113.1
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0000] Discovery Response from 172.16.113.1
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0000]
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0000] CAPWAP State: DTLS Setup
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0599]
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0599] CAPWAP State: Join
Sep 5 00:53:17 kernel: [*09/05/2018 00:53:17.0599] Sending Join request to 172.16.113.1 through port 5256
Sep 5 00:53:23 kernel: [*09/05/2018 00:53:23.2281] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Join(5).
Sep 5 00:53:23 kernel: [*09/05/2018 00:53:23.2281] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Join(5).
Sep 5 00:53:28 kernel: [*09/05/2018 00:53:28.1865] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Join(5).
Sep 5 00:53:28 kernel: [*09/05/2018 00:53:28.2265] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Join(5).
Sep 5 00:54:14 kernel: [*09/05/2018 00:54:14.0222]
Sep 5 00:54:14 kernel: [*09/05/2018 00:54:14.0222] CAPWAP State: DTLS Teardown
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8807]
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8807] CAPWAP State: Discovery
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8807] Got WLC address 0.172.20.14 from DHCP.
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8807] IP DNS query for CISCO-CAPWAP-CONTROLLER.cop.org
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8907] Discovery Request sent to 172.16.113.1, discovery type STATIC_CONFIG(1)
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8907] Discovery Request sent to 0.172.20.14, discovery type DHCP(2)
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8907] Discovery Request sent to 255.255.255.255, discovery type UNKNOWN(0)
Sep 5 00:54:18 kernel: [*09/05/2018 00:54:18.8907] Discovery Response from 172.16.113.1
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0000] Discovery Response from 172.16.113.1
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0000]
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0000] CAPWAP State: DTLS Setup
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0699]
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0699] CAPWAP State: Join
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0699] Sending Join request to 172.16.113.1 through port 5256
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.0799] Join Response from 172.16.113.1
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.1699] nss_capwapmgr_enable_tunnel[1661]:c62d8000: tunnel 0 is already enabled
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.1899]
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.1899] CAPWAP State: Image Data
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.2499] do NO_UPGRADE, part1 is active part
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.2499]
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.2499] CAPWAP State: Configure
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.2499] DOT11_CFG[0] Radio Mode is changed from FlexConnect to FlexConnect
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.2499] DOT11_CFG[1] Radio Mode is changed from FlexConnect to FlexConnect
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.6698] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Configure(8).
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.6698] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Configure(8).
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.6698] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Configure(8).
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.6698] Discarding msg CAPWAP_WTP_EVENT_REQUEST(type 9) in CAPWAP state: Configure(8).
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.8197]
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.8197] CAPWAP State: Run
Sep 5 00:54:29 kernel: [*09/05/2018 00:54:29.9697] AP has joined controller
Sep 5 00:54:30 kernel: [*09/05/2018 00:54:29.9897] Flexconnect Switching to Connected Mode!
Sep 5 00:56:32 kernel: [*09/05/2018 00:56:32.0000] FOUND CONFIGURED WLC (Primary) REDISCOVER TO CONNECT WITH THAT.
Sep 5 00:56:32 kernel: [*09/05/2018 00:56:32.0000] Flexconnect Switching to Standalone Mode!
Sep 5 00:56:32 kernel: [*09/05/2018 00:56:32.0000]
Sep 5 00:56:32 kernel: [*09/05/2018 00:56:32.0000] CAPWAP State: DTLS Teardown
Sep 5 00:56:36 kernel: [*09/05/2018 00:56:36.8385] Discovery Response from 172.16.113.1
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0000] Discovery Response from 172.16.113.1
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0000]
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0000] CAPWAP State: DTLS Setup
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0599]
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0599] CAPWAP State: Join
Sep 5 00:56:37 kernel: [*09/05/2018 00:56:37.0699] Sending Join request to 172.16.113.1 through port 5256

Depot-AP01#
Depot-AP01#show flexconnect status

Flexconnect Status:
AP in Standalone Mode

If someone can please help me I'd appreciate it.

Thanks.

.Jaidev Hattiangadi. · ‎09-05-2018

Are there any other WLC's that the APs can reach? if yes, they could be trying to move to the other WLC