[Help]: Upgrade from 4.4.8 to 5.14 results in launching the first time setup wizard

[Laephis]

Controller Version

4.4.8

Describe Your Issue or Question

I attempted to upgrade from 4.4.8 to the latest controller version and when opening the webpage I keep getting the wizard to create a new account instead of being able to log in with my existing username/password. I went through that process thinking that the upgrade from 4 to 5 required a new account to be setup, but it didn't look like it imported anything. The volume settings for the new container are identical and I removed the "work" volume since it was stated it's not used anymore. Am I missing a step to correctly migrate from 4 to 5?

In the meantime I've restored my backup and loaded 4.4 again successfully.

Thanks!

Expected Behavior

Expecting to be able to log into the new container with old credentials after upgrading.

Steps to Reproduce

docker stop -t 60 omada-controller
docker rm omada-controller

How You're Launching the Container

docker run -d \
  --name omada-controller \
  --stop-timeout 60 \
  --restart unless-stopped \
  --ulimit nofile=4096:8192 \
  -p 8088:8088 \
  -p 8043:8043 \
  -p 8843:8843 \
  -p 27001:27001/udp \
  -p 29810:29810/udp \
  -p 29811-29816:29811-29816 \
  -e MANAGE_HTTP_PORT=8088 \
  -e MANAGE_HTTPS_PORT=8043 \
  -e PGID="508" \
  -e PORTAL_HTTP_PORT=8088 \
  -e PORTAL_HTTPS_PORT=8843 \
  -e PORT_ADOPT_V1=29812 \
  -e PORT_APP_DISCOVERY=27001 \
  -e PORT_DISCOVERY=29810 \
  -e PORT_MANAGER_V1=29811 \
  -e PORT_MANAGER_V2=29814 \
  -e PORT_TRANSFER_V2=29815 \
  -e PORT_RTTY=29816 \
  -e PORT_UPGRADE_V1=29813 \
  -e PUID="508" \
  -e SHOW_SERVER_LOGS=true \
  -e SHOW_MONGODB_LOGS=false \
  -e SSL_CERT_NAME="tls.crt" \
  -e SSL_KEY_NAME="tls.key" \
  -e TZ=America/Chicago \
  -v /srv/dev-disk-by-uuid-7607484e-fb4b-477b-8e5f-efe62e2b82e7/Storage/appdata/Omada/omada-data:/opt/tplink/EAPController/data \
  -v /srv/dev-disk-by-uuid-7607484e-fb4b-477b-8e5f-efe62e2b82e7/Storage/appdata/Omada/omada-logs:/opt/tplink/EAPController/logs \
  mbentley/omada-controller:latest

Container Logs

2024-10-28 04:24:31 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-38 changed
2024-10-28 04:27:31 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-38 changed
2024-10-28 06:25:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-35 changed
2024-10-28 06:26:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-35 changed
2024-10-28 07:13:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-35 changed
2024-10-28 07:15:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-35 changed
2024-10-28 07:24:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-38 changed
2024-10-28 07:27:32 [topology-task-0] [INFO]-[SourceFile:222] - topology of switch 60-A4-B7-CF-EA-38 changed
2024-10-28 07:45:28 [comm-pool-0] [WARN]-[SourceFile:142] - get InterruptedException while saving log.
2024-10-28 07:45:28 [Thread-1] [INFO]-[SourceFile:219] - Going to stop mongod which pid is 60
2024-10-28 07:45:28 [nioEventLoopGroup-8-2] [INFO]-[SourceFile:50] - Omada Controller isn't prepared to handle event
2024-10-28 07:45:29 [nioEventLoopGroup-8-4] [INFO]-[SourceFile:50] - Omada Controller isn't prepared to handle event
2024-10-28 07:45:30 [nioEventLoopGroup-8-6] [INFO]-[SourceFile:50] - Omada Controller isn't prepared to handle event
2024-10-28 07:45:32 [nioEventLoopGroup-8-1] [INFO]-[SourceFile:50] - Omada Controller isn't prepared to handle event
2024-10-28 07:45:33 [nioEventLoopGroup-8-7] [INFO]-[SourceFile:50] - Omada Controller isn't prepared to handle event
2024-10-28 07:45:35 [Thread-1] [INFO]-[SourceFile:181] - success to shutdown mongodb database
2024-10-28 07:45:35 [Thread-1] [INFO]-[SourceFile:186] - Omada Controller exited
10-28-2024 07:48:38.551 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: start the omada controller
10-28-2024 07:48:38.555 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: set property finished
10-28-2024 07:48:38.592 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: configure log finished
10-28-2024 07:48:38.595 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap prepare
10-28-2024 07:48:38.623 INFO [main] [] c.t.s.o.c.o.c.b(): success to load configuration omada.properties
10-28-2024 07:48:38.625 INFO [main] [] c.t.s.o.c.o.OmadacType(): omadacType: Local Controller
10-28-2024 07:48:38.625 INFO [main] [] c.t.s.o.s.e.c(): Configuration omadacType is linux
10-28-2024 07:48:38.666 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): going to start local mongod.
10-28-2024 07:48:41.675 INFO [main] [] c.t.s.o.s.s.b(): mongodb process id is 227
10-28-2024 07:48:41.678 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Mongo DB server started
10-28-2024 07:48:41.678 INFO [main] [] c.t.s.o.s.s.b(): Mongo DB server started
10-28-2024 07:48:42.042 WARN [main] [] c.t.s.o.s.OmadaBootstrap(): Valid keystore is missing. Generating one ...
10-28-2024 07:48:42.626 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): Certificate generated!
10-28-2024 07:48:42.855 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap record finished
10-28-2024 07:48:42.855 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap startup
10-28-2024 07:48:42.917 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: start run omada tasks
10-28-2024 07:48:42.918 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask start
10-28-2024 07:48:43.689 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.14.32.2 using Java 17.0.12 on 693a567968bb with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.14.32.2.jar started by omada in /opt/tplink/EAPController/lib)
10-28-2024 07:48:43.692 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"
10-28-2024 07:48:58.043 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:00.897 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:05.583 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:09.196 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:10.198 INFO [main] [] c.t.s.e.s.c.c(): start schedule remove expire device... period = 10
10-28-2024 07:49:10.210 INFO [main] [] c.t.s.e.s.c.c(): update rateLimiterCache, permitsPerSecond = 5.0
10-28-2024 07:49:11.002 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): upgradeSendReq workGroup core thread num is 1, max thread num is 1
10-28-2024 07:49:15.671 INFO [main] [] c.t.s.o.m.p.c.o.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 07:49:16.385 INFO [main] [] c.t.s.o.m.d.d.m.m.c.DeviceMsgConfig(): setMsgThreadPool thread coreSize is 8, maxSize is 8,queue size is 4500
10-28-2024 07:49:17.067 INFO [main] [] c.t.s.o.m.c.t.e.TemplateSyncExecutorConfiguration(): template sync workGroup core thread num is 30, max thread num is 30
10-28-2024 07:49:17.119 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manager workGroup core thread  num is 16, max thread num is 16
10-28-2024 07:49:19.574 INFO [main] [] c.t.s.o.m.c.d.m.s.w.s.c.WirelessGroupConfig(): ssidWorkerGroup thread size is 4, queue size is 1500
10-28-2024 07:49:20.070 INFO [main] [] c.t.s.o.m.c.d.m.s.d.d(): init token valid duration : 86400
10-28-2024 07:49:20.302 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manage Inform workGroup core thread  num is 16, max thread num is 16
10-28-2024 07:49:22.621 INFO [main] [] c.t.s.o.m.c.d.m.s.a.AbstractActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 07:49:23.127 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): adopt workGroup core thread num is 8, max thread num is8
10-28-2024 07:49:25.061 INFO [main] [] c.t.s.o.c.p.m.d.h.j(): Initializing clientHistoryRestrict: Optional[12000000]
10-28-2024 07:49:30.396 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.411 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.412 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.412 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.413 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.413 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.414 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.414 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.414 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.415 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.415 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.416 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.416 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:30.420 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 07:49:32.114 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting Digest-Attributes with WISPr-Redirection-URL
10-28-2024 07:49:32.114 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting WISPr-Redirection-URL with WISPr-Location-ID
10-28-2024 07:49:32.281 INFO [main] [] c.t.c.c.a.c.a.e.c.ActionConfig(): Success to init eap action dispatcher, num = 20, invokes = {PEAP_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action2(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_FINISH=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action9(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action6(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_MSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action7(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_MD5_CHALLENGE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action6(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_MD5_ACTION_PRECESS_CHALLENGE_VALUE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket@9d6a55d, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action3(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_MD5_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket@9d6a55d, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_MSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action8(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_EAP_MD5_CHALLENGE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action5(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action7(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action2(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action5(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_ACK=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@74c6c6bb, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action4(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action3(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@9c25d13, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action4(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq))}
10-28-2024 07:49:32.296 INFO [main] [] c.t.c.c.a.c.a.c.EapAuthConfig(): Success to init eap auth dispatcher, num = 4, invokes = {PEAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapPeapAuthHandler@5bea1561, EAP_MD5=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapMd5AuthHandler@5cd47b53, EAP_TLS=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapTlsAuthHandler@1bfbac86, EAP_TTLS=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapTTlsAuthHandler@63afb44a}
10-28-2024 07:49:32.303 INFO [main] [] c.t.c.c.a.c.a.c.AuthConfig(): Success to init radius auth dispatcher, num = 8, invokes = {PORTAL_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PapPortalAuthHandler@27a88f46, USER_ACCESS_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapAuthHandler@1b183ae7, USER_ACCESS_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessChapAuthHandler@77958193, MAC_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacEapAuthHandler@744d64f0, PPSK_BOUND_MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PpskBoundMacPapAuthHandler@31e5a8c2, PORTAL_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.ChapPortalAuthHandler@5c2b1ef9, USER_ACCESS_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessPapAuthHandler@2e8ee060, MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacPapAuthHandler@448eb3f1}
10-28-2024 07:49:32.371 INFO [main] [] c.t.c.c.a.c.a.a.AccountingConfig(): Success to init radius accounting handler, num = 0, invokes = {}
10-28-2024 07:49:32.596 INFO [main] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): log mq consume task is start...
10-28-2024 07:49:32.600 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Started OmadaLinuxMain in 49.528 seconds (JVM running for 55.125)
10-28-2024 07:49:34.236 INFO [main] [] c.t.s.o.m.d.p.b.a(): manager maintenance Handling event: org.springframework.boot.context.event.ApplicationStartedEvent[source=org.springframework.boot.SpringApplication@16aa3a0b]
10-28-2024 07:49:34.509 WARN [https-jsse-nio-8043-exec-4] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:34.533 WARN [https-jsse-nio-8043-exec-4] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 07:49:34.800 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask finished
10-28-2024 07:49:34.800 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before OmadacInitTask
10-28-2024 07:49:35.308 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init bean
10-28-2024 07:49:35.374 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack workGroup core thread  num is 8, max thread num is 8
10-28-2024 07:49:35.860 INFO [main] [] c.t.s.o.p.p.r.a.c(): init nioEventLoopGroup
10-28-2024 07:49:36.042 INFO [main] [] c.t.s.o.c.c.p.c.o.s.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 07:49:36.231 INFO [main] [] c.t.s.o.m.d.m.v.l(): log mq produce task is starting...
10-28-2024 07:49:36.260 INFO [main] [] c.t.s.o.m.l.p.e.LicenseEventCenterProperties(): licenseManagerTopic: omada.cloud.license.prd.topics
10-28-2024 07:49:36.492 INFO [main] [] c.t.s.o.c.p.e.g(): init DpiTrafficNotifyWatcher
10-28-2024 07:49:36.913 INFO [main] [] c.t.s.o.d.g.c.f.b.c(): file download mq consume task is start...
10-28-2024 07:49:36.969 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): discovery workGroup core thread num is 2, max thread num is 10
10-28-2024 07:49:37.657 INFO [main] [] c.t.s.o.l.p.c.s.a.a(): Scheduled APP push task at fixed rate: PT5M
10-28-2024 07:49:40.850 INFO [main] [] c.t.s.o.c.d.a.m(): Initializing clientRestrict: Optional[12000000]
10-28-2024 07:49:40.994 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack time task workGroup core thread  num is 8, max thread num is 8
10-28-2024 07:49:42.004 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Starting collection maintenance.
10-28-2024 07:49:42.013 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init bean
10-28-2024 07:49:42.113 INFO [main] [] c.t.s.o.i.a.b.d(): name=default_name tenant is not exist.
10-28-2024 07:49:42.117 INFO [main] [] c.t.s.o.i.a.b.d(): name=default_name tenant is not exist.
10-28-2024 07:49:42.694 INFO [main] [] c.t.s.o.i.d.m.o.b.a.a(): Initializing client settings of OmadacId: 02108b9d13eca5c5f9113870ad618a61
10-28-2024 07:49:42.791 INFO [main] [] c.t.s.o.s.d.a(): succeed in initializing default omadac OmadacVO(id=02108b9d13eca5c5f9113870ad618a61, name=Omada Controller_618A61, pattern=0)
10-28-2024 07:49:42.826 INFO [main] [] c.t.s.o.s.p.m.d.b(): No startUpInfo exists.
10-28-2024 07:49:42.864 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): Start init data.
10-28-2024 07:49:42.864 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Start init data.
10-28-2024 07:49:42.918 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): initializing controllerId. OmadacVO(id=02108b9d13eca5c5f9113870ad618a61, name=Omada Controller_618A61, pattern=null)
10-28-2024 07:49:42.959 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init for hwc
10-28-2024 07:49:42.959 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init for hwc
10-28-2024 07:49:42.969 INFO [main] [] c.t.s.o.p.r.b.d(): DisconnectRequestServer start
10-28-2024 07:49:43.097 ERROR [main] [] c.t.s.o.s.t.OmadacInitTask(): init ModifyScheduleOnSystemTimeChanged error.
java.lang.NullPointerException: null
	at java.util.Objects.requireNonNull(Objects.java:209) ~[?:?]
	at com.tplink.smb.omada.manager.h.a.a.c.<init>(SourceFile:28) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.port.mongo.configuration.site.i.a(SourceFile:65) ~[manager-port-mongo-5.14.32.2.jar:5.14.32.2]
	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:197) ~[?:?]
	at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1625) ~[?:?]
	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:509) ~[?:?]
	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:499) ~[?:?]
	at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:921) ~[?:?]
	at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[?:?]
	at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:682) ~[?:?]
	at com.tplink.smb.omada.manager.port.mongo.configuration.site.h.a(SourceFile:73) ~[manager-port-mongo-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.configuration.domain.model.site.dst.i.b(SourceFile:123) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.configuration.domain.model.site.dst.i.a(SourceFile:103) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.initModifyScheduleOnSystemTimeChanged(SourceFile:380) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.afterOmadacInit(SourceFile:203) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.d.a.a(SourceFile:79) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.execute(SourceFile:94) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.c.a(SourceFile:20) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaBootstrap.f(SourceFile:406) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaLinuxMain.a(SourceFile:99) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaLinuxMain.main(SourceFile:42) ~[local-starter-5.14.32.2.jar:5.14.32.2]
10-28-2024 07:49:43.209 INFO [main] [] c.t.s.o.s.d.a(): record: after OmadacInitTask
10-28-2024 07:49:43.209 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask start
10-28-2024 07:49:43.215 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask finished
10-28-2024 07:49:43.215 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask start
10-28-2024 07:49:43.217 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask finished
10-28-2024 07:49:43.292 INFO [main] [] c.t.s.o.d.g.a(): Init Local Device-gateway Managed Device: [ap, pro ap, switch, pro switch, pro gateway, gateway, ems, olt]
10-28-2024 07:49:43.338 INFO [main] [] c.t.s.o.d.g.a(): startServers, DISCOVERY host:null.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): discovery Port is 29810.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): manage    port v1 is 29811.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): manage    port v2 is 29814.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): adopt     port v1 is 29812.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): upgrade   port is 29813.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): transfer  port is 29815.
10-28-2024 07:49:43.341 INFO [main] [] c.t.s.o.d.g.a(): rtty port is 29816.
10-28-2024 07:49:43.409 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyUdpServer bind /0.0.0.0:29810, export localhost/127.0.0.1:29810
10-28-2024 07:49:43.411 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyUdpServer bind /0.0.0.0:19810, export localhost/127.0.0.1:19810
10-28-2024 07:49:43.428 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29812, export localhost/127.0.0.1:29812
10-28-2024 07:49:43.430 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29811, export localhost/127.0.0.1:29811
10-28-2024 07:49:43.432 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29813, export localhost/127.0.0.1:29813
10-28-2024 07:49:43.435 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29814, export localhost/127.0.0.1:29814
10-28-2024 07:49:43.436 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29815, export localhost/127.0.0.1:29815
10-28-2024 07:49:43.441 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start RttyNettyTcpServer bind /0.0.0.0:29816, export localhost/127.0.0.1:29816
10-28-2024 07:49:43.441 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Device connector server started.
10-28-2024 07:49:43.442 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Omada Controller started
10-28-2024 07:49:43.442 INFO [main] [] c.t.s.o.s.t.SuccessExitTask(): Omada Controller started
10-28-2024 07:49:43.442 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: finished run omada tasks
10-28-2024 07:49:44.404 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-A9-77-76 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:44.547 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 60-A4-B7-CF-EA-38 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:44.649 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 60-A4-B7-CF-EA-35 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:45.140 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device CC-32-E5-4C-B7-0E on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:46.638 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Finished collection maintenance.
10-28-2024 07:49:47.925 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 9C-A2-F4-75-DC-20 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:51.292 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-20-E5-18 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:53.004 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 9C-A2-F4-75-DC-1F on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:49:53.183 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-A9-77-88 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 07:50:11.694 WARN [https-jsse-nio-8043-exec-2] [] c.t.s.o.i.b.k(): OmadacId=02108b9d13eca5c5f9113870ad618a61 find no root users
10-28-2024 07:50:13.167 WARN [https-jsse-nio-8043-exec-6] [] c.t.s.o.m.l.p.c.f(): failed to find site:SiteId(defaultInternetSite) in db, omadacId:OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 07:50:13.170 WARN [https-jsse-nio-8043-exec-6] [] c.t.s.o.m.l.p.c.f(): failed to find site:SiteId(defaultInternetSite) in db, omadacId:OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 07:50:13.232 INFO [comm-pool-9] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 07:50:13.238 INFO [comm-pool-9] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 07:50:13.315 INFO [https-jsse-nio-8043-exec-9] [] c.t.s.o.m.c.d.m.s.w.w.j(): OmadacId(02108b9d13eca5c5f9113870ad618a61) SiteId(defaultInternetSite) init osgModel Config success. Lan Mode Ports = [2, 3, 4, 5], Wan Mode Ports = [1]
10-28-2024 07:50:13.315 INFO [https-jsse-nio-8043-exec-9] [] c.t.s.o.m.c.d.m.s.w.w.i(): Create osgModelConfig by createDefaultInternet, Omadac:02108b9d13eca5c5f9113870ad618a61, siteId:defaultInternetSite
10-28-2024 07:50:13.383 INFO [https-jsse-nio-8043-exec-9] [] c.t.s.o.m.c.d.m.s.w.w.K(): Create default internet by tryGetInternet, Omadac:02108b9d13eca5c5f9113870ad618a61, siteId:defaultInternetSite, type:siteSetting
10-28-2024 07:50:13.431 INFO [https-jsse-nio-8043-exec-9] [] c.t.s.o.m.c.d.m.c.u(): site not exists. omadacId: OmadacId(02108b9d13eca5c5f9113870ad618a61), siteId: SiteId(defaultInternetSite).
10-28-2024 07:51:41.210 INFO [check-update-work-group-0] [] c.t.s.o.m.c.o(): Start sync Cloud Users OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 07:55:13.209 INFO [comm-pool-14] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 07:55:13.211 INFO [comm-pool-14] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 07:55:35.765 INFO [Thread-0] [] c.t.s.o.s.s.b(): Going to stop mongod which pid is 227
10-28-2024 07:55:35.800 INFO [SpringApplicationShutdownHook] [] c.t.s.e.s.EcspTransporterStarter(): going to shutdown ecsp servers & executors
10-28-2024 07:55:35.802 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyUdpServer, bind /0.0.0.0:29810, export localhost/127.0.0.1:29810
10-28-2024 07:55:35.805 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyUdpServer, bind /0.0.0.0:19810, export localhost/127.0.0.1:19810
10-28-2024 07:55:35.811 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29811, export localhost/127.0.0.1:29811
10-28-2024 07:55:35.817 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29812, export localhost/127.0.0.1:29812
10-28-2024 07:55:35.820 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29813, export localhost/127.0.0.1:29813
10-28-2024 07:55:35.823 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29814, export localhost/127.0.0.1:29814
10-28-2024 07:55:35.827 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29815, export localhost/127.0.0.1:29815
10-28-2024 07:55:35.829 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close RttyNettyTcpServer, bind /0.0.0.0:29816, export localhost/127.0.0.1:29816
10-28-2024 07:55:35.833 INFO [SpringApplicationShutdownHook] [] c.t.s.e.s.m(): going to shutdown ecsp thread pools.
10-28-2024 07:55:35.966 INFO [SpringApplicationShutdownHook] [] c.t.s.o.l.p.c.s.a.a(): Canceled APP push task.
10-28-2024 07:55:35.987 INFO [SpringApplicationShutdownHook] [] c.t.s.o.c.c.p.c.o.s.ActiveSiteCacheImpl(): Before shutdown flush schedule job, flush active sites into cache now.
10-28-2024 07:55:35.993 INFO [SpringApplicationShutdownHook] [] c.t.s.o.p.p.r.a.c(): shutdown nioEventLoopGroup when bean RadiusAcctService closed
10-28-2024 07:55:35.999 INFO [SpringApplicationShutdownHook] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): Interrupt log-read-caller-thread
10-28-2024 07:55:35.999 INFO [SpringApplicationShutdownHook] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): Shutdown log-component-work-group
10-28-2024 07:55:35.999 WARN [log-read-caller-thread] [] c.t.s.l.c.s.p.a.c.LogCacheQueueRepositoryImpl(): interrupt exception.when get logs from Queue
10-28-2024 07:55:36.173 INFO [SpringApplicationShutdownHook] [] c.t.s.e.p.b.a(): going to shutdown proxy executors
10-28-2024 07:55:36.173 INFO [SpringApplicationShutdownHook] [] c.t.s.e.p.a(): going to shutdown proxy thread pools.
10-28-2024 08:01:51.634 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: start the omada controller
10-28-2024 08:01:51.638 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: set property finished
10-28-2024 08:01:51.671 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: configure log finished
10-28-2024 08:01:51.674 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap prepare
10-28-2024 08:01:51.710 INFO [main] [] c.t.s.o.c.o.c.b(): success to load configuration omada.properties
10-28-2024 08:01:51.713 INFO [main] [] c.t.s.o.c.o.OmadacType(): omadacType: Local Controller
10-28-2024 08:01:51.713 INFO [main] [] c.t.s.o.s.e.c(): Configuration omadacType is linux
10-28-2024 08:01:51.784 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): going to start local mongod.
10-28-2024 08:01:54.792 INFO [main] [] c.t.s.o.s.s.b(): mongodb process id is 223
10-28-2024 08:01:54.796 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Mongo DB server started
10-28-2024 08:01:54.797 INFO [main] [] c.t.s.o.s.s.b(): Mongo DB server started
10-28-2024 08:01:55.401 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap record finished
10-28-2024 08:01:55.402 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap startup
10-28-2024 08:01:55.473 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: start run omada tasks
10-28-2024 08:01:55.473 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask start
10-28-2024 08:01:56.305 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.14.32.2 using Java 17.0.12 on 6f2f5ca6884a with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.14.32.2.jar started by omada in /opt/tplink/EAPController/lib)
10-28-2024 08:01:56.325 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"
10-28-2024 08:02:10.974 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:11.420 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.0.0.
10-28-2024 08:02:11.421 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.2.0.
10-28-2024 08:02:11.434 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.10.0.
10-28-2024 08:02:11.456 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.0.0.
10-28-2024 08:02:11.456 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.2.0.
10-28-2024 08:02:11.457 INFO [main] [] c.t.s.o.m.c.c.c(): input src version 5.14.32 target version 5.10.0.
10-28-2024 08:02:13.863 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:17.629 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:20.866 WARN [main] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:21.869 INFO [main] [] c.t.s.e.s.c.c(): start schedule remove expire device... period = 10
10-28-2024 08:02:21.881 INFO [main] [] c.t.s.e.s.c.c(): update rateLimiterCache, permitsPerSecond = 5.0
10-28-2024 08:02:22.725 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): upgradeSendReq workGroup core thread num is 1, max thread num is 1
10-28-2024 08:02:27.488 INFO [main] [] c.t.s.o.m.p.c.o.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 08:02:28.329 INFO [main] [] c.t.s.o.m.d.d.m.m.c.DeviceMsgConfig(): setMsgThreadPool thread coreSize is 8, maxSize is 8,queue size is 4500
10-28-2024 08:02:28.970 INFO [main] [] c.t.s.o.m.c.t.e.TemplateSyncExecutorConfiguration(): template sync workGroup core thread num is 30, max thread num is 30
10-28-2024 08:02:29.040 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manager workGroup core thread  num is 16, max thread num is 16
10-28-2024 08:02:31.482 INFO [main] [] c.t.s.o.m.c.d.m.s.w.s.c.WirelessGroupConfig(): ssidWorkerGroup thread size is 4, queue size is 1500
10-28-2024 08:02:31.923 INFO [main] [] c.t.s.o.m.c.d.m.s.d.d(): init token valid duration : 86400
10-28-2024 08:02:32.109 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manage Inform workGroup core thread  num is 16, max thread num is 16
10-28-2024 08:02:33.795 INFO [main] [] c.t.s.o.m.c.d.m.s.a.AbstractActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 08:02:34.248 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): adopt workGroup core thread num is 8, max thread num is8
10-28-2024 08:02:35.791 INFO [main] [] c.t.s.o.c.p.m.d.h.j(): Initializing clientHistoryRestrict: Optional[12000000]
10-28-2024 08:02:40.655 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.666 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.667 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.668 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.668 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.669 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.669 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.670 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.670 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.671 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.673 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.673 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.674 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:40.678 INFO [main] [] c.t.s.o.a.d.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
10-28-2024 08:02:42.508 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting Digest-Attributes with WISPr-Redirection-URL
10-28-2024 08:02:42.508 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting WISPr-Redirection-URL with WISPr-Location-ID
10-28-2024 08:02:42.794 INFO [main] [] c.t.c.c.a.c.a.e.c.ActionConfig(): Success to init eap action dispatcher, num = 20, invokes = {EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_EAP_MD5_CHALLENGE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action5(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action2(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_MSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action8(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action3(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_MD5_ACTION_PRECESS_CHALLENGE_VALUE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket@7180fa60, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action4(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action2(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_MD5_ACTION_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket@7180fa60, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket.action0(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action3(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_FINISH=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action9(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action7(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_ACK=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action4(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_MD5_CHALLENGE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action6(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_MSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action7(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action1(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@169b0f26, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket.action6(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@7e6234e4, method=public com.tplink.cdd.radius.common.eap.packet.EapPacket com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket.action5(com.tplink.cdd.component.aaa.core.authentication.dto.AuthReq))}
10-28-2024 08:02:42.802 INFO [main] [] c.t.c.c.a.c.a.c.EapAuthConfig(): Success to init eap auth dispatcher, num = 4, invokes = {EAP_TLS=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapTlsAuthHandler@6776feeb, EAP_TTLS=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapTTlsAuthHandler@2b5f5d86, PEAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapPeapAuthHandler@1bc9cfc6, EAP_MD5=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapMd5AuthHandler@6ffbbeb5}
10-28-2024 08:02:42.806 INFO [main] [] c.t.c.c.a.c.a.c.AuthConfig(): Success to init radius auth dispatcher, num = 8, invokes = {PPSK_BOUND_MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PpskBoundMacPapAuthHandler@4bcbb962, USER_ACCESS_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessChapAuthHandler@5bde0ea5, MAC_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacEapAuthHandler@58f6ba44, USER_ACCESS_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapAuthHandler@6bf96816, MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacPapAuthHandler@5e4a5106, USER_ACCESS_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessPapAuthHandler@260e2f90, PORTAL_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PapPortalAuthHandler@253cacc2, PORTAL_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.ChapPortalAuthHandler@16d04236}
10-28-2024 08:02:42.888 INFO [main] [] c.t.c.c.a.c.a.a.AccountingConfig(): Success to init radius accounting handler, num = 0, invokes = {}
10-28-2024 08:02:45.281 WARN [https-jsse-nio-8043-exec-8] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:45.310 WARN [https-jsse-nio-8043-exec-8] [] o.s.d.c.CustomConversions(): Registering converter from class java.time.LocalDateTime to class org.joda.time.LocalDateTime as reading converter although it doesn't convert from a store-supported type; You might want to check your annotation setup at the converter implementation
10-28-2024 08:02:45.553 INFO [main] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): log mq consume task is start...
10-28-2024 08:02:45.570 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Started OmadaLinuxMain in 49.958 seconds (JVM running for 54.979)
10-28-2024 08:02:45.764 INFO [main] [] c.t.s.o.m.d.p.b.a(): manager maintenance Handling event: org.springframework.boot.context.event.ApplicationStartedEvent[source=org.springframework.boot.SpringApplication@2eb52007]
10-28-2024 08:02:45.908 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask finished
10-28-2024 08:02:45.908 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before OmadacInitTask
10-28-2024 08:02:46.344 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init bean
10-28-2024 08:02:46.417 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack workGroup core thread  num is 8, max thread num is 8
10-28-2024 08:02:46.927 INFO [main] [] c.t.s.o.p.p.r.a.c(): init nioEventLoopGroup
10-28-2024 08:02:47.026 INFO [main] [] c.t.s.o.c.c.p.c.o.s.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
10-28-2024 08:02:47.216 INFO [main] [] c.t.s.o.m.d.m.v.l(): log mq produce task is starting...
10-28-2024 08:02:47.250 INFO [https-jsse-nio-8043-exec-8] [] c.t.s.o.a.d.InterruptBeforeInitFinishedInterceptor(): Controller is starting up, cannot handle request now.
10-28-2024 08:02:47.263 INFO [main] [] c.t.s.o.m.l.p.e.LicenseEventCenterProperties(): licenseManagerTopic: omada.cloud.license.prd.topics
10-28-2024 08:02:47.350 INFO [https-jsse-nio-8043-exec-5] [] c.t.s.o.a.d.InterruptBeforeInitFinishedInterceptor(): Controller is starting up, cannot handle request now.
10-28-2024 08:02:47.379 INFO [https-jsse-nio-8043-exec-6] [] c.t.s.o.a.d.InterruptBeforeInitFinishedInterceptor(): Controller is starting up, cannot handle request now.
10-28-2024 08:02:47.490 INFO [main] [] c.t.s.o.c.p.e.g(): init DpiTrafficNotifyWatcher
10-28-2024 08:02:47.903 INFO [main] [] c.t.s.o.d.g.c.f.b.c(): file download mq consume task is start...
10-28-2024 08:02:47.969 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): discovery workGroup core thread num is 2, max thread num is 10
10-28-2024 08:02:48.672 INFO [main] [] c.t.s.o.l.p.c.s.a.a(): Scheduled APP push task at fixed rate: PT5M
10-28-2024 08:02:51.620 INFO [main] [] c.t.s.o.c.d.a.m(): Initializing clientRestrict: Optional[12000000]
10-28-2024 08:02:51.732 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack time task workGroup core thread  num is 8, max thread num is 8
10-28-2024 08:02:52.798 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Starting collection maintenance.
10-28-2024 08:02:52.808 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init bean
10-28-2024 08:02:53.025 INFO [main] [] c.t.s.o.s.d.a(): succeed in initializing default omadac OmadacVO(id=02108b9d13eca5c5f9113870ad618a61, name=Omada Controller_618A61, pattern=0)
10-28-2024 08:02:53.025 INFO [main] [] c.t.s.o.s.d.a(): Db unchanged. No need to compatible or init data.
10-28-2024 08:02:53.026 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): succeed get default omadac OmadacVO(id=02108b9d13eca5c5f9113870ad618a61, name=Omada Controller_618A61, pattern=null)
10-28-2024 08:02:53.094 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init for hwc
10-28-2024 08:02:53.095 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init for hwc
10-28-2024 08:02:53.106 INFO [main] [] c.t.s.o.p.r.b.d(): DisconnectRequestServer start
10-28-2024 08:02:53.398 ERROR [main] [] c.t.s.o.s.t.OmadacInitTask(): init ModifyScheduleOnSystemTimeChanged error.
java.lang.NullPointerException: null
	at java.util.Objects.requireNonNull(Objects.java:209) ~[?:?]
	at com.tplink.smb.omada.manager.h.a.a.c.<init>(SourceFile:28) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.port.mongo.configuration.site.i.a(SourceFile:65) ~[manager-port-mongo-5.14.32.2.jar:5.14.32.2]
	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:197) ~[?:?]
	at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1625) ~[?:?]
	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:509) ~[?:?]
	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:499) ~[?:?]
	at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:921) ~[?:?]
	at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[?:?]
	at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:682) ~[?:?]
	at com.tplink.smb.omada.manager.port.mongo.configuration.site.h.a(SourceFile:73) ~[manager-port-mongo-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.configuration.domain.model.site.dst.i.b(SourceFile:123) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.manager.configuration.domain.model.site.dst.i.a(SourceFile:103) ~[manager-core-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.initModifyScheduleOnSystemTimeChanged(SourceFile:380) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.afterOmadacInit(SourceFile:203) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.d.a.a(SourceFile:79) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.OmadacInitTask.execute(SourceFile:94) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.task.c.a(SourceFile:20) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaBootstrap.f(SourceFile:406) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaLinuxMain.a(SourceFile:99) ~[local-starter-5.14.32.2.jar:5.14.32.2]
	at com.tplink.smb.omada.starter.OmadaLinuxMain.main(SourceFile:42) ~[local-starter-5.14.32.2.jar:5.14.32.2]
10-28-2024 08:02:53.572 INFO [main] [] c.t.s.o.s.d.a(): record: after OmadacInitTask
10-28-2024 08:02:53.572 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask start
10-28-2024 08:02:53.573 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask finished
10-28-2024 08:02:53.573 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask start
10-28-2024 08:02:53.573 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask finished
10-28-2024 08:02:53.728 INFO [main] [] c.t.s.o.d.g.a(): Init Local Device-gateway Managed Device: [ap, ems, pro switch, olt, pro gateway, gateway, pro ap, switch]
10-28-2024 08:02:53.776 INFO [main] [] c.t.s.o.d.g.a(): startServers, DISCOVERY host:null.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): discovery Port is 29810.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): manage    port v1 is 29811.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): manage    port v2 is 29814.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): adopt     port v1 is 29812.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): upgrade   port is 29813.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): transfer  port is 29815.
10-28-2024 08:02:53.784 INFO [main] [] c.t.s.o.d.g.a(): rtty port is 29816.
10-28-2024 08:02:53.864 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyUdpServer bind /0.0.0.0:29810, export localhost/127.0.0.1:29810
10-28-2024 08:02:53.866 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyUdpServer bind /0.0.0.0:19810, export localhost/127.0.0.1:19810
10-28-2024 08:02:53.883 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29812, export localhost/127.0.0.1:29812
10-28-2024 08:02:53.886 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29811, export localhost/127.0.0.1:29811
10-28-2024 08:02:53.889 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29813, export localhost/127.0.0.1:29813
10-28-2024 08:02:53.893 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29814, export localhost/127.0.0.1:29814
10-28-2024 08:02:53.895 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start NettyTcpServer bind /0.0.0.0:29815, export localhost/127.0.0.1:29815
10-28-2024 08:02:53.900 INFO [main] [] c.t.s.e.t.a.t.AbstractServer(): Start RttyNettyTcpServer bind /0.0.0.0:29816, export localhost/127.0.0.1:29816
10-28-2024 08:02:53.901 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Device connector server started.
10-28-2024 08:02:53.901 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Omada Controller started
10-28-2024 08:02:53.901 INFO [main] [] c.t.s.o.s.t.SuccessExitTask(): Omada Controller started
10-28-2024 08:02:53.901 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: finished run omada tasks
10-28-2024 08:02:54.310 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Finished collection maintenance.
10-28-2024 08:02:55.172 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 60-A4-B7-CF-EA-38 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:02:55.174 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-A9-77-88 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:02:55.181 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 60-A4-B7-CF-EA-35 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:02:55.835 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 9C-A2-F4-75-DC-20 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:02:55.910 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-A9-77-76 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:02:56.927 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device CC-32-E5-4C-B7-0E on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:03:01.033 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device 9C-A2-F4-75-DC-1F on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:03:03.187 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGE_BY_OTHERS Device D8-07-B6-20-E5-18 on omadac 02108b9d13eca5c5f9113870ad618a61 is discovered.
10-28-2024 08:03:23.584 INFO [comm-pool-9] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 08:03:23.590 INFO [comm-pool-9] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 08:04:00.012 INFO [client-schedule-statistic-task-0] [] c.t.s.o.c.d.a.u(): [STAT] {"wiredClients":0,"statName":"ClientStatInfo","authedClients":0,"wirelessClients":0}
10-28-2024 08:04:05.573 INFO [check-update-work-group-0] [] c.t.s.o.m.c.o(): Start sync Cloud Users OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 08:05:55.021 WARN [https-jsse-nio-8043-exec-5] [] c.t.s.o.i.b.k(): OmadacId=02108b9d13eca5c5f9113870ad618a61 find no root users
10-28-2024 08:05:56.556 WARN [https-jsse-nio-8043-exec-8] [] c.t.s.o.m.l.p.c.f(): failed to find site:SiteId(defaultInternetSite) in db, omadacId:OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 08:05:56.561 WARN [https-jsse-nio-8043-exec-8] [] c.t.s.o.m.l.p.c.f(): failed to find site:SiteId(defaultInternetSite) in db, omadacId:OmadacId(02108b9d13eca5c5f9113870ad618a61).
10-28-2024 08:05:56.703 INFO [https-jsse-nio-8043-exec-1] [] c.t.s.o.m.c.d.m.c.u(): site not exists. omadacId: OmadacId(02108b9d13eca5c5f9113870ad618a61), siteId: SiteId(defaultInternetSite).
10-28-2024 08:08:23.571 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 08:08:23.573 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 08:13:23.569 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 08:13:23.571 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 08:13:57.442 INFO [https-jsse-nio-8043-exec-3] [] c.t.e.c.c.CloudClient(): CloudClient is stopped.
10-28-2024 08:13:57.484 INFO [monitor-data-track-pool-0] [] c.t.s.o.m.d.p.c.c(): getExpImprove failed, omadacId02108b9d13eca5c5f9113870ad618a61
10-28-2024 08:18:23.569 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): Target firmware check task run
10-28-2024 08:18:23.571 INFO [comm-pool-15] [] c.t.s.o.m.d.a.u.f.c(): no omadac need handle target firmware upgrade
10-28-2024 08:21:02.387 INFO [Thread-0] [] c.t.s.o.s.s.b(): Going to stop mongod which pid is 223
10-28-2024 08:21:02.432 INFO [SpringApplicationShutdownHook] [] c.t.s.e.s.EcspTransporterStarter(): going to shutdown ecsp servers & executors
10-28-2024 08:21:02.434 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyUdpServer, bind /0.0.0.0:29810, export localhost/127.0.0.1:29810
10-28-2024 08:21:02.438 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyUdpServer, bind /0.0.0.0:19810, export localhost/127.0.0.1:19810
10-28-2024 08:21:02.444 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29811, export localhost/127.0.0.1:29811
10-28-2024 08:21:02.450 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29812, export localhost/127.0.0.1:29812
10-28-2024 08:21:02.453 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29813, export localhost/127.0.0.1:29813
10-28-2024 08:21:02.459 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29814, export localhost/127.0.0.1:29814
10-28-2024 08:21:02.462 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close NettyTcpServer, bind /0.0.0.0:29815, export localhost/127.0.0.1:29815
10-28-2024 08:21:02.466 INFO [SpringApplicationShutdownHook] [] c.t.s.e.t.a.t.AbstractServer(): Close RttyNettyTcpServer, bind /0.0.0.0:29816, export localhost/127.0.0.1:29816
10-28-2024 08:21:02.469 INFO [SpringApplicationShutdownHook] [] c.t.s.e.s.m(): going to shutdown ecsp thread pools.
10-28-2024 08:21:02.594 INFO [SpringApplicationShutdownHook] [] c.t.s.o.l.p.c.s.a.a(): Canceled APP push task.
10-28-2024 08:21:02.616 INFO [SpringApplicationShutdownHook] [] c.t.s.o.c.c.p.c.o.s.ActiveSiteCacheImpl(): Before shutdown flush schedule job, flush active sites into cache now.
10-28-2024 08:21:02.618 INFO [SpringApplicationShutdownHook] [] c.t.s.o.p.p.r.a.c(): shutdown nioEventLoopGroup when bean RadiusAcctService closed
10-28-2024 08:21:02.621 INFO [SpringApplicationShutdownHook] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): Interrupt log-read-caller-thread
10-28-2024 08:21:02.621 INFO [SpringApplicationShutdownHook] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): Shutdown log-component-work-group
10-28-2024 08:21:02.621 WARN [log-read-caller-thread] [] c.t.s.l.c.s.p.a.c.LogCacheQueueRepositoryImpl(): interrupt exception.when get logs from Queue
10-28-2024 08:21:02.758 INFO [SpringApplicationShutdownHook] [] c.t.s.e.p.b.a(): going to shutdown proxy executors
10-28-2024 08:21:02.759 INFO [SpringApplicationShutdownHook] [] c.t.s.e.p.a(): going to shutdown proxy thread pools.

MongoDB Logs

No response

Additional Context

No response

[mbentley]

So TP-Link removed the ability to upgrade from 4.x to 5.x as of version 5.14.32.2. I would suggest updating your image tag to 5.13, letting it do the upgrade, and then you can to go 5.14.x. See this section of the README about the upgrade path. Also see the release notes for 5.14.32.2 which mention this in note 3.

I know the details of a lot of this are difficult to find so what I should probably do is detect if the user was previously running 4.x in the entrypoint while trying to start 5.14.32.2 or greater and block the container from starting and send them to the instructions of how to proceed.

[Laephis]

Thank you! Looks like I missed that explanation note about the change with 5.14, sorry!

[mbentley]

No worries, I was able to add in a check for #493 which addresses this in the entrypoint to block the controller from starting if the version conditions match.