search

mbentley / docker-omada-controller

Docker image to run TP-Link Omada Controller
736 stars 110 forks source link

[Bug]: Deep Packet Inspection #406

Closed PappyChappy1 closed 9 months ago

PappyChappy1 commented 9 months ago

Controller Version

5.13.30.4

Describe the Bug

I am not able to get the new deep packet inspection statistics to show; I wanted to see if this was related to running in a docker container on Mac OS?

Expected Behavior

With all compatible hardware updated to the latest firmware, should be able to enable Deep Packet Inspection, and see data populating the statistics tab

Steps to Reproduce

  1. Update all devices to latest firmware
  2. In Network Security > Application Control > enable Deep Packet Inspection and Logging
  3. After some time, check Statistics > Application Statistics. Other people using the controller installed on linux distress report that this tab is populating with data.

How You're Launching the Container

version: "3.8"
services:
  omada-controller:
    container_name: omada-controller
    # 5.5 is the current tag as of this commen, which points to 5.5.6
    image: mbentley/omada-controller:beta
    # restart the service if it isn't running unless manually stopped
    restart: unless-stopped
    # we can't use host network so have to explicitly define all the ports
    ports:
      - 8088:8088
      - 8043:8043
      - 8843:8843
      - 27001:27001/udp
      - 29810:29810/udp
      - 29811:29811
      - 29812:29812
      - 29813:29813
      - 29814:29814
      - 29815:29815
      - 29816:29816
    # create some volumes so data is saved
    volumes:
      - omada-data:/opt/tplink/EAPController/data
      - omada-logs:/opt/tplink/EAPController/logs
volumes:
  omada-data:
  omada-logs:

Container Logs

**Pasting 2nd half of logs in comments due to length**
INFO: Validating user/group (omada:omada) exists with correct UID/GID (508:508)
INFO: Group (omada) doesn't exist; creating
INFO: User (omada) doesn't exist; creating
INFO: Time zone set to 'Etc/UTC'
INFO: Value of 'manage.http.port' already set to 8088 in omada.properties
INFO: Value of 'manage.https.port' already set to 8043 in omada.properties
INFO: Value of 'portal.http.port' already set to 8088 in omada.properties
INFO: Value of 'portal.https.port' already set to 8843 in omada.properties
INFO: Value of 'port.adopt.v1' already set to 29812 in omada.properties
INFO: Value of 'port.app.discovery' already set to 27001 in omada.properties
INFO: Value of 'port.upgrade.v1' already set to 29813 in omada.properties
INFO: Value of 'port.manager.v1' already set to 29811 in omada.properties
INFO: Value of 'port.manager.v2' already set to 29814 in omada.properties
INFO: Value of 'port.discovery' already set to 29810 in omada.properties
INFO: Value of 'port.transfer.v2' already set to 29815 in omada.properties
INFO: Value of 'port.rtty' already set to 29816 in omada.properties
INFO: Value of 'mongo.external' already set to false in omada.properties
INFO: Value of 'eap.mongod.uri' already set to mongodb://127.0.0.1:27217/omada in omada.properties
WARN: Ownership not set correctly on '/opt/tplink/EAPController/properties'; setting correct ownership (omada:omada)
INFO: Version check passed; image version (5.13.30.4) >= the last version ran (5.13.30.4); writing image version to last ran file...
INFO: userland/kernel check passed
INFO: Starting Omada Controller as user omada
01-30-2024 16:26:05.886 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: start the omada controller
01-30-2024 16:26:05.888 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: set property finished
01-30-2024 16:26:05.890 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: configure log finished
01-30-2024 16:26:05.891 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap prepare
01-30-2024 16:26:05.894 INFO [main] [] c.t.s.o.c.o.a.b(): success to load configuration omada.properties
01-30-2024 16:26:05.895 INFO [log4j-thread] [] c.t.s.o.c.o.OmadacType(): omadacType: Local Controller
01-30-2024 16:26:05.906 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): going to start local mongod.
01-30-2024 16:26:07.421 INFO [main] [] c.t.s.o.s.s.b(): mongodb process id is 171
01-30-2024 16:26:07.425 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Mongo DB server started
01-30-2024 16:26:07.425 INFO [main] [] c.t.s.o.s.s.b(): Mongo DB server started
01-30-2024 16:26:07.660 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap record finished
01-30-2024 16:26:07.660 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap startup
01-30-2024 16:26:07.692 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: start run omada tasks
01-30-2024 16:26:07.692 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask start

01-30-2024 16:26:07.973 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.13.30.4 using Java 17.0.9 on 32b59eec9735 with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.13.30.4.jar started by omada in /opt/tplink/EAPController/lib)
01-30-2024 16:26:07.974 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"
01-30-2024 16:26:12.638 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
01-30-2024 16:26:15.017 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
01-30-2024 16:26:15.590 INFO [main] [] c.t.s.o.m.c.d.m.s.d.d(): init token valid duration : 86400
01-30-2024 16:26:16.349 INFO [main] [] c.t.s.o.m.p.c.o.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
01-30-2024 16:26:16.869 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manager workGroup core thread  num is 16, max thread num is 16
01-30-2024 16:26:18.730 INFO [main] [] c.t.s.o.m.c.d.m.s.w.s.c.WirelessGroupConfig(): ssidWorkerGroup thread size is 8, queue size is 1500
01-30-2024 16:26:18.881 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manage Inform workGroup core thread  num is 16, max thread num is 16
01-30-2024 16:26:19.288 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.
01-30-2024 16:26:19.421 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
01-30-2024 16:26:19.608 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): adopt workGroup core thread num is 8, max thread num is8
01-30-2024 16:26:19.747 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): upgradeSendReq workGroup core thread num is 1, max thread num is 1
01-30-2024 16:26:23.702 INFO [main] [] c.t.s.e.s.c.c(): start schedule remove expire device... period = 10
01-30-2024 16:26:23.711 INFO [main] [] c.t.s.e.s.c.c(): update rateLimiterCache, permitsPerSecond = 5.0
01-30-2024 16:26:23.878 INFO [main] [] c.t.s.o.m.l.p.e.LicenseEventCenterProperties(): licenseManagerTopic: omada.cloud.license.prd.topics
01-30-2024 16:26:25.059 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)
01-30-2024 16:26:25.080 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
01-30-2024 16:26:26.040 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
01-30-2024 16:26:26.714 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting Digest-Attributes with WISPr-Redirection-URL
01-30-2024 16:26:26.714 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting WISPr-Redirection-URL with WISPr-Location-ID
01-30-2024 16:26:26.799 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@dca316e, 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)), EAP_TTLS_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_EAP_MD5_CHALLENGE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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)), PEAP_ACTION_PROCESS_TUNNEL_AUTHENTICATION_FINISH=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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_WAIT_MSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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)), PEAP_ACTION_PROCESS_ACK=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_INIT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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)), PEAP_ACTION_SEND_SERVER_HELLO_NEXT_FRAGMENT=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_WAIT_MSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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@dca316e, 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_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_VERIFIED_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_CLIENT_KEY_FULL=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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@4b2f3c6c, 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_CLIENT_HELLO=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.PeapActionBucket@dca316e, 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_MD5_ACTION_PRECESS_CHALLENGE_VALUE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapMd5ActionBucket@4b2f3c6c, 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_PROCESS_TUNNEL_AUTHENTICATION_WAIT_EAPMSCHAPV2_RESPONSE=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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)), EAP_TTLS_ACTION_PROCESS_TUNNEL_AUTHENTICATION_START=ActionBucketMethod(actionBucket=com.tplink.cdd.component.aaa.core.authentication.eap.service.impl.EapTtlsActionBucket@59104f7c, 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))}
01-30-2024 16:26:26.811 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@36b419d, EAP_MD5=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapMd5AuthHandler@3f5e042e, PEAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapPeapAuthHandler@407b4126, EAP_TTLS=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapTTlsAuthHandler@21506a20}
01-30-2024 16:26:26.813 INFO [main] [] c.t.c.c.a.c.a.c.AuthConfig(): Success to init radius auth dispatcher, num = 9, invokes = {PORTAL_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.ChapPortalAuthHandler@695e4801, PPSK_UNBOUND_MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PapPpskUnboundMacAuthHandler@4e4be473, USER_ACCESS_CHAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessChapAuthHandler@7227fcfc, MAC_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacEapAuthHandler@1274dcbd, PORTAL_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PapPortalAuthHandler@e726f59, MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.MacPapAuthHandler@4e095b79, USER_ACCESS_EAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessEapAuthHandler@42d54a9a, PPSK_BOUND_MAC_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.PpskBoundMacPapAuthHandler@d82a328, USER_ACCESS_PAP=com.tplink.cdd.component.aaa.core.authentication.service.handler.UserAccessPapAuthHandler@64e9af19}
01-30-2024 16:26:26.838 INFO [main] [] c.t.c.c.a.c.a.a.AccountingConfig(): Success to init radius accounting handler, num = 0, invokes = {}
01-30-2024 16:26:26.855 INFO [main] [] c.t.s.l.c.s.d.CacheLogConsumeHandler(): log mq consume task is start...
01-30-2024 16:26:26.857 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Started OmadaLinuxMain in 19.119 seconds (JVM running for 21.415)
01-30-2024 16:26:26.867 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@3df8985b]
01-30-2024 16:26:26.964 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask finished
01-30-2024 16:26:26.964 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before OmadacInitTask
01-30-2024 16:26:26.983 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init bean
01-30-2024 16:26:26.997 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init bean
01-30-2024 16:26:27.084 INFO [main] [] c.t.s.o.s.d.a(): succeed in initializing default omadac OmadacVO(id=165628f2f47e9e0ca9b9e2a9ad8bbefe, name=Omada Controller, pattern=0)
01-30-2024 16:26:27.085 INFO [main] [] c.t.s.o.s.d.a(): Db unchanged. No need to compatible or init data.
01-30-2024 16:26:27.086 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): succeed get default omadac OmadacVO(id=165628f2f47e9e0ca9b9e2a9ad8bbefe, name=Omada Controller, pattern=null)
01-30-2024 16:26:27.101 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: before init for hwc
01-30-2024 16:26:27.101 INFO [main] [] c.t.s.o.s.t.OmadacInitTask(): record: after init for hwc
01-30-2024 16:26:27.142 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack workGroup core thread  num is 16, max thread num is 16
01-30-2024 16:26:27.596 INFO [main] [] c.t.s.o.d.g.c.f.b.c(): file download mq consume task is start...
01-30-2024 16:26:27.703 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): discovery workGroup core thread num is 2, max thread num is 10
01-30-2024 16:26:27.883 INFO [main] [] c.t.s.o.c.p.e.d(): init DpiTrafficNotifyWatcher
01-30-2024 16:26:27.953 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.
01-30-2024 16:26:28.032 INFO [main] [] c.t.s.o.p.p.r.a.c(): init nioEventLoopGroup
01-30-2024 16:26:28.915 INFO [main] [] c.t.s.o.d.g.c.d.ThreadConfiguration(): device-gateway datatrack time task workGroup core thread  num is 16, max thread num is 16
01-30-2024 16:26:29.265 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Starting collection maintenance.
01-30-2024 16:26:29.275 INFO [main] [] c.t.s.o.p.b.b.d(): DisconnectRequestServer start
01-30-2024 16:26:29.306 INFO [main] [] c.t.s.o.m.c.d.m.s.d.f(): Add Dst Schedule true for Site global on 1710050400000.
01-30-2024 16:26:29.367 INFO [main] [] c.t.s.o.m.c.d.m.s.d.f(): Add Dst Schedule true for Site 64184792a1c6f121bc55606f on 1710050400000.
01-30-2024 16:26:29.446 INFO [main] [] c.t.s.o.s.d.a(): record: after OmadacInitTask
01-30-2024 16:26:29.446 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask start
01-30-2024 16:26:29.447 INFO [main] [] c.t.s.o.s.t.CloudStartUpTask(): record: CloudStartUpTask finished
01-30-2024 16:26:29.449 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask start
01-30-2024 16:26:29.450 INFO [main] [] c.t.s.o.s.t.OmadacDiscoveryStartUpTask(): record: OmadacDiscoveryStartUpTask finished
01-30-2024 16:26:29.539 INFO [main] [] c.t.s.o.d.g.a(): startServers, DISCOVERY host:null.
01-30-2024 16:26:29.540 INFO [main] [] c.t.s.o.d.g.a(): discovery Port is 29810.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): manage    port v1 is 29811.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): manage    port v2 is 29814.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): adopt     port v1 is 29812.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): upgrade   port is 29813.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): transfer  port is 29815.
01-30-2024 16:26:29.541 INFO [main] [] c.t.s.o.d.g.a(): rtty port is 29816.
01-30-2024 16:26:29.597 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
01-30-2024 16:26:29.604 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
01-30-2024 16:26:29.605 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
01-30-2024 16:26:29.607 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
01-30-2024 16:26:29.609 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
01-30-2024 16:26:29.610 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
01-30-2024 16:26:29.613 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
01-30-2024 16:26:29.613 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Device connector server started.
01-30-2024 16:26:29.613 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Omada Controller started
01-30-2024 16:26:29.613 INFO [main] [] c.t.s.o.s.t.SuccessExitTask(): Omada Controller started
01-30-2024 16:26:29.613 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: finished run omada tasks
01-30-2024 16:26:29.642 INFO [comm-pool-5] [] c.t.s.o.s.t.CloudStartUpTask(): start cloud connect on a reset hwc
01-30-2024 16:26:29.657 INFO [Thread-6] [] c.t.e.c.c.CloudClient(): CloudClient is started.
01-30-2024 16:26:29.657 INFO [Thread-6] [] c.t.e.c.c.CloudClient(): Connect SEF server automatically.
01-30-2024 16:26:29.762 INFO [comm-pool-2] [] c.t.s.o.s.t.OmadacInitTask(): Finished collection maintenance.
01-30-2024 16:26:30.365 INFO [Thread-6] [] c.t.e.c.c.n(): The result of connection is true, service host is n-device-omada.tplinkcloud.com, port is 443.
01-30-2024 16:26:30.365 INFO [Thread-6] [] c.t.e.c.c.CloudClient(): Close connection to SEF server.
01-30-2024 16:26:30.367 INFO [Thread-9] [] c.t.e.c.c.n(): ssl recv thread is stopped.
01-30-2024 16:26:30.375 INFO [Thread-6] [] c.t.e.c.c.CloudClient(): Connect service server automatically, ConnectionType is PERSISTENT_CONNECTION.
01-30-2024 16:26:30.375 INFO [Thread-6] [] c.t.e.c.c.q(): set connection status: CONNECTING
01-30-2024 16:26:30.511 INFO [discovery-work-group-0] [] c.t.s.o.m.d.d.m.d.a(): MANAGED_BY_OWN Device 30-DE-4B-77-67-1B on omadac 165628f2f47e9e0ca9b9e2a9ad8bbefe is discovered.
01-30-2024 16:26:30.643 INFO [Thread-6] [] c.t.e.c.c.q(): set connection status: CONNECTED
01-30-2024 16:26:30.644 INFO [Thread-6] [] c.t.e.c.c.q(): The result of connection is true.
01-30-2024 16:26:31.803 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGED_BY_OWN Device 1C-61-B4-A7-9E-C0 on omadac 165628f2f47e9e0ca9b9e2a9ad8bbefe is discovered.
01-30-2024 16:26:31.924 WARN [log-dst-info-event-pool-1] [] c.t.s.o.l.c.c.b(): Failed to get global notification config.omadacId:null,log key:CONNECTED_CLOUD_ACCESS_SUCCESS
01-30-2024 16:26:32.296 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGED_BY_OWN Device B4-B0-24-9D-95-C7 on omadac 165628f2f47e9e0ca9b9e2a9ad8bbefe is discovered.
01-30-2024 16:26:33.512 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGED_BY_OWN Device 9C-A2-F4-4C-BB-30 on omadac 165628f2f47e9e0ca9b9e2a9ad8bbefe is discovered.
01-30-2024 16:26:35.471 INFO [discovery-work-group-1] [] c.t.s.o.m.d.d.m.d.a(): MANAGED_BY_OWN Device 9C-A2-F4-4C-B1-6A on omadac 165628f2f47e9e0ca9b9e2a9ad8bbefe is discovered.
01-30-2024 16:26:36.089 INFO [adopt-work-group-2] [] c.t.s.o.m.d.d.m.d.a.L(): Ap OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) SiteId(64184792a1c6f121bc55606f) DeviceMac(9C-A2-F4-4C-B1-6A) adopt[auto=true] ok
01-30-2024 16:26:36.089 INFO [adopt-work-group-6] [] c.t.s.o.m.d.d.m.d.a.L(): Ap OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) SiteId(64184792a1c6f121bc55606f) DeviceMac(1C-61-B4-A7-9E-C0) adopt[auto=true] ok
01-30-2024 16:26:36.114 INFO [adopt-work-group-2] [] c.t.s.o.m.d.d.m.a.e(): send empty setting to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-B1-6A)
01-30-2024 16:26:36.115 INFO [adopt-work-group-6] [] c.t.s.o.m.d.d.m.a.e(): send empty setting to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(1C-61-B4-A7-9E-C0)
01-30-2024 16:26:36.339 INFO [manage-work-group-4] [] c.t.s.o.m.d.p.t.c(): Device 9C-A2-F4-4C-B1-6A OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle.
01-30-2024 16:26:36.340 INFO [manage-work-group-5] [] c.t.s.o.m.d.p.t.c(): Device 1C-61-B4-A7-9E-C0 OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle.
01-30-2024 16:26:36.573 INFO [gateway-dev-inform-pool-1] [] c.t.s.o.m.d.d.m.i.a(): got first inform of OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(1C-61-B4-A7-9E-C0)
01-30-2024 16:26:36.584 INFO [gateway-dev-inform-pool-0] [] c.t.s.o.m.d.d.m.i.a(): got first inform of OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-B1-6A)
01-30-2024 16:26:36.618 INFO [manage-work-group-6] [] c.t.s.o.m.d.d.m.i.d(): first inform send same version config to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(1C-61-B4-A7-9E-C0)
01-30-2024 16:26:36.619 INFO [manage-work-group-7] [] c.t.s.o.m.d.d.m.i.d(): first inform send same version config to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-B1-6A)
01-30-2024 16:26:36.898 INFO [setMsgThreadPool-6] [] c.t.s.o.m.d.d.m.d.a.W(): syncConfigurationForSameVersion to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(1C-61-B4-A7-9E-C0), result:SendDeviceMsgResult(success=true, deviceResponse=ApConfigRespBody(super=BaseConfigRespBody(sequenceId=1, errcode=0, configVersion=null, additionalProperties={}), packageCaptureConfigResp=null, lanSetting=null, lanv6Setting=null, bindVlan=null, bindVlans=null, lanPortSettings=null, wirelessBasic2G=null, wirelessBasic5G=null, wirelessBasic5G2=null, wirelessBasic6G=null, wirelessAdv2G=null, wirelessAdv5G=null, wirelessAdv5G2=null, wirelessAdv6G=null, loadBalance2G=null, loadBalance5G=null, loadBalance5G2=null, loadBalance6G=null, bandSteering=null, ssid2G=null, ssid5G=null, ssid5G2=null, ssid6G=null, macFilterGlobal=null, portalFreePolicyConfig=null, macFilterList=null, macFilterAssoc=null, schedulerGlobal=null, schedulerList=null, schedulerAssoc=null, qosConfig2G=null, qosConfig5G=null, qosConfig5G2=null, qosConfig6G=null, logSetting=null, led=null, ssh=null, managementVlan=null, ouiBasedVlan=null, clientConfig=null, limits2G=null, limits5G=null, limits5G2=null, limits6G=null, timeSetting=null, dstConfig=null, rssi2G=null, rssi5G=null, rssi5G2=null, rssi6G=null, clientOperation=null, controllerInfo=null, standaloneMgmt=null, trunkConfig=null, rssiLeds=null, clientRateConfig=null, mesh=null, roaming=null, ipGroup=null, ipv6Group=null, ipPortGroup=null, ipv6PortGroup=null, acl=null, urlFiltering=null, snmp=null, common=null, wifiLedCtrl=null, smartAntenna=null, l3Access=null, energySaving=null, widsWips=null, mdns=null, dot1x=null, lldp=null, greTunnel=null, trunkSetting=null, userAccount=null, privacyPolicy=null))
01-30-2024 16:26:36.902 INFO [setMsgThreadPool-7] [] c.t.s.o.m.d.d.m.d.a.W(): syncConfigurationForSameVersion to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-B1-6A), result:SendDeviceMsgResult(success=true, deviceResponse=ApConfigRespBody(super=BaseConfigRespBody(sequenceId=1, errcode=0, configVersion=null, additionalProperties={}), packageCaptureConfigResp=null, lanSetting=null, lanv6Setting=null, bindVlan=null, bindVlans=null, lanPortSettings=null, wirelessBasic2G=null, wirelessBasic5G=null, wirelessBasic5G2=null, wirelessBasic6G=null, wirelessAdv2G=null, wirelessAdv5G=null, wirelessAdv5G2=null, wirelessAdv6G=null, loadBalance2G=null, loadBalance5G=null, loadBalance5G2=null, loadBalance6G=null, bandSteering=null, ssid2G=null, ssid5G=null, ssid5G2=null, ssid6G=null, macFilterGlobal=null, portalFreePolicyConfig=null, macFilterList=null, macFilterAssoc=null, schedulerGlobal=null, schedulerList=null, schedulerAssoc=null, qosConfig2G=null, qosConfig5G=null, qosConfig5G2=null, qosConfig6G=null, logSetting=null, led=null, ssh=null, managementVlan=null, ouiBasedVlan=null, clientConfig=null, limits2G=null, limits5G=null, limits5G2=null, limits6G=null, timeSetting=null, dstConfig=null, rssi2G=null, rssi5G=null, rssi5G2=null, rssi6G=null, clientOperation=null, controllerInfo=null, standaloneMgmt=null, trunkConfig=null, rssiLeds=null, clientRateConfig=null, mesh=null, roaming=null, ipGroup=null, ipv6Group=null, ipPortGroup=null, ipv6PortGroup=null, acl=null, urlFiltering=null, snmp=null, common=null, wifiLedCtrl=null, smartAntenna=null, l3Access=null, energySaving=null, widsWips=null, mdns=null, dot1x=null, lldp=null, greTunnel=null, trunkSetting=null, userAccount=null, privacyPolicy=null))
01-30-2024 16:26:36.969 INFO [setMsgThreadPool-2] [] c.t.s.o.m.p.m.d.c.b(): mongo.batchInsert deviceConfigHistoryList exception: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e5f') }', details={}}]. ; nested exception is com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e5f') }', details={}}]. 
org.springframework.data.mongodb.BulkOperationException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e5f') }', details={}}]. ; nested exception is com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e5f') }', details={}}]. 
    at org.springframework.data.mongodb.core.DefaultBulkOperations.bulkWriteTo(DefaultBulkOperations.java:324) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at org.springframework.data.mongodb.core.MongoTemplate.execute(MongoTemplate.java:579) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at org.springframework.data.mongodb.core.DefaultBulkOperations.execute(DefaultBulkOperations.java:290) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.a(SourceFile:208) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
    at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.g(SourceFile:189) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
    at java.util.HashMap.forEach(HashMap.java:1421) ~[?:?]
    at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.b(SourceFile:183) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
    at com.tplink.smb.omada.manager.device.domain.model.configsync.cG.a(SourceFile:296) ~[manager-core-5.13.30.4.jar:5.13.30.4]
    at com.tplink.smb.omada.manager.device.domain.model.configsync.cG.a(SourceFile:355) ~[manager-core-5.13.30.4.jar:5.13.30.4]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ~[?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e5f') }', details={}}]. 
    at com.mongodb.internal.connection.BulkWriteBatchCombiner.getError(BulkWriteBatchCombiner.java:167) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.connection.BulkWriteBatchCombiner.throwOnError(BulkWriteBatchCombiner.java:192) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.connection.BulkWriteBatchCombiner.getResult(BulkWriteBatchCombiner.java:136) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.BulkWriteBatch.getResult(BulkWriteBatch.java:237) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.MixedBulkWriteOperation.executeBulkWriteBatch(MixedBulkWriteOperation.java:403) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.MixedBulkWriteOperation.lambda$execute$2(MixedBulkWriteOperation.java:300) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.OperationHelper.lambda$withSourceAndConnection$2(OperationHelper.java:575) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.OperationHelper.withSuppliedResource(OperationHelper.java:600) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.OperationHelper.lambda$withSourceAndConnection$3(OperationHelper.java:574) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.OperationHelper.withSuppliedResource(OperationHelper.java:600) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.OperationHelper.withSourceAndConnection(OperationHelper.java:573) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.MixedBulkWriteOperation.lambda$execute$3(MixedBulkWriteOperation.java:272) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.async.function.RetryingSyncSupplier.get(RetryingSyncSupplier.java:65) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.MixedBulkWriteOperation.execute(MixedBulkWriteOperation.java:308) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.internal.operation.MixedBulkWriteOperation.execute(MixedBulkWriteOperation.java:85) ~[mongodb-driver-core-4.6.1.jar:?]
    at com.mongodb.client.internal.MongoClientDelegate$DelegateOperationExecutor.execute(MongoClientDelegate.java:212) ~[mongodb-driver-sync-4.6.1.jar:?]
    at com.mongodb.client.internal.MongoCollectionImpl.executeBulkWrite(MongoCollectionImpl.java:443) ~[mongodb-driver-sync-4.6.1.jar:?]
    at com.mongodb.client.internal.MongoCollectionImpl.bulkWrite(MongoCollectionImpl.java:423) ~[mongodb-driver-sync-4.6.1.jar:?]
    at org.springframework.data.mongodb.core.DefaultBulkOperations.bulkWriteTo(DefaultBulkOperations.java:311) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    ... 11 more
01-30-2024 16:26:36.978 INFO [setMsgThreadPool-3] [] c.t.s.o.m.p.m.d.c.b(): mongo.batchInsert deviceConfigHistoryList exception: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e60') }', details={}}]. ; nested exception is com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e60') }', details={}}]. 
org.springframework.data.mongodb.BulkOperationException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e60') }', details={}}]. ; nested exception is com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: _id_ dup key: { : ObjectId('65b9233cdd95620e09521e60') }', details={}}]. 
    at org.springframework.data.mongodb.core.DefaultBulkOperations.bulkWriteTo(DefaultBulkOperations.java:324) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at org.springframework.data.mongodb.core.MongoTemplate.execute(MongoTemplate.java:579) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at org.springframework.data.mongodb.core.DefaultBulkOperations.execute(DefaultBulkOperations.java:290) ~[spring-data-mongodb-3.4.13.jar:3.4.13]
    at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.a(SourceFile:208) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
    at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.g(SourceFile:189) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
    at java.util.HashMap.forEach(HashMap.java:1421) ~[?:?]

MongoDB Logs

No response

Additional Context

This is a new feature for Omada, and I just want to make sure it isn't an issue on how the controller works on Mac OS using docker before I ditch it for a raspberry Pi

PappyChappy1 commented 9 months ago 
at com.tplink.smb.omada.manager.port.mongo.device.configsync.b.b(SourceFile:183) ~[manager-port-mongo-5.13.30.4.jar:5.13.30.4]
at com.tplink.smb.omada.manager.device.domain.model.configsync.cG.a(SourceFile:296) ~[manager-core-5.13.30.4.jar:5.13.30.4]
at com.tplink.smb.omada.manager.device.domain.model.configsync.cG.a(SourceFile:355) ~[manager-core-5.13.30.4.jar:5.13.30.4]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ~[?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[?:?]
at java.lang.Thread.run(Thread.java:840) [?:?]

Caused by: com.mongodb.MongoBulkWriteException: Bulk write operation error on server 127.0.0.1:27217. Write errors: [BulkWriteError{index=0, code=11000, message='E11000 duplicate key error collection: omada_data.deviceconfighistory_2024m01_t1s1 index: id dup key: { : ObjectId('65b9233cdd95620e09521e60') }', details={}}]. at com.mongodb.internal.connection.BulkWriteBatchCombiner.getError(BulkWriteBatchCombiner.java:167) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.connection.BulkWriteBatchCombiner.throwOnError(BulkWriteBatchCombiner.java:192) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.connection.BulkWriteBatchCombiner.getResult(BulkWriteBatchCombiner.java:136) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.BulkWriteBatch.getResult(BulkWriteBatch.java:237) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.MixedBulkWriteOperation.executeBulkWriteBatch(MixedBulkWriteOperation.java:403) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.MixedBulkWriteOperation.lambda$execute$2(MixedBulkWriteOperation.java:300) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.OperationHelper.lambda$withSourceAndConnection$2(OperationHelper.java:575) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.OperationHelper.withSuppliedResource(OperationHelper.java:600) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.OperationHelper.lambda$withSourceAndConnection$3(OperationHelper.java:574) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.OperationHelper.withSuppliedResource(OperationHelper.java:600) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.OperationHelper.withSourceAndConnection(OperationHelper.java:573) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.MixedBulkWriteOperation.lambda$execute$3(MixedBulkWriteOperation.java:272) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.async.function.RetryingSyncSupplier.get(RetryingSyncSupplier.java:65) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.MixedBulkWriteOperation.execute(MixedBulkWriteOperation.java:308) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.internal.operation.MixedBulkWriteOperation.execute(MixedBulkWriteOperation.java:85) ~[mongodb-driver-core-4.6.1.jar:?] at com.mongodb.client.internal.MongoClientDelegate$DelegateOperationExecutor.execute(MongoClientDelegate.java:212) ~[mongodb-driver-sync-4.6.1.jar:?] at com.mongodb.client.internal.MongoCollectionImpl.executeBulkWrite(MongoCollectionImpl.java:443) ~[mongodb-driver-sync-4.6.1.jar:?] at com.mongodb.client.internal.MongoCollectionImpl.bulkWrite(MongoCollectionImpl.java:423) ~[mongodb-driver-sync-4.6.1.jar:?] at org.springframework.data.mongodb.core.DefaultBulkOperations.bulkWriteTo(DefaultBulkOperations.java:311) ~[spring-data-mongodb-3.4.13.jar:3.4.13] ... 11 more 01-30-2024 16:26:37.184 WARN [https-jsse-nio-8043-exec-5] [] 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 01-30-2024 16:26:37.194 WARN [https-jsse-nio-8043-exec-5] [] 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 01-30-2024 16:26:37.199 INFO [adopt-work-group-6] [] c.t.s.o.m.d.d.m.d.d.k(): Switch OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) SiteId(64184792a1c6f121bc55606f) DeviceMac(B4-B0-24-9D-95-C7) adopt[auto=true] ok 01-30-2024 16:26:37.210 INFO [adopt-work-group-6] [] c.t.s.o.m.d.d.m.a.e(): send empty setting to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(B4-B0-24-9D-95-C7) 01-30-2024 16:26:37.291 INFO [manage-work-group-9] [] c.t.s.o.m.d.p.t.c(): Device B4-B0-24-9D-95-C7 OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle. 01-30-2024 16:26:37.365 INFO [gateway-dev-inform-pool-2] [] c.t.s.o.m.d.d.m.i.a(): got first inform of OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(B4-B0-24-9D-95-C7) 01-30-2024 16:26:37.388 INFO [manage-work-group-10] [] c.t.s.o.m.d.d.m.i.f(): first inform no need send full config to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(B4-B0-24-9D-95-C7) 01-30-2024 16:26:37.708 INFO [adopt-work-group-4] [] c.t.s.o.m.d.d.m.d.a.L(): Ap OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) SiteId(64184792a1c6f121bc55606f) DeviceMac(9C-A2-F4-4C-BB-30) adopt[auto=true] ok 01-30-2024 16:26:37.712 INFO [adopt-work-group-4] [] c.t.s.o.m.d.d.m.a.e(): send empty setting to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-BB-30) 01-30-2024 16:26:37.775 INFO [manage-work-group-13] [] c.t.s.o.m.d.p.t.c(): Device 9C-A2-F4-4C-BB-30 OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle. 01-30-2024 16:26:37.798 INFO [gateway-dev-inform-pool-3] [] c.t.s.o.m.d.d.m.i.a(): got first inform of OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-BB-30) 01-30-2024 16:26:37.803 INFO [manage-work-group-14] [] c.t.s.o.m.d.d.m.i.d(): first inform send same version config to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-BB-30) 01-30-2024 16:26:37.839 INFO [setMsgThreadPool-2] [] c.t.s.o.m.d.d.m.d.a.W(): syncConfigurationForSameVersion to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(9C-A2-F4-4C-BB-30), result:SendDeviceMsgResult(success=true, deviceResponse=ApConfigRespBody(super=BaseConfigRespBody(sequenceId=1, errcode=0, configVersion=null, additionalProperties={}), packageCaptureConfigResp=null, lanSetting=null, lanv6Setting=null, bindVlan=null, bindVlans=null, lanPortSettings=null, wirelessBasic2G=null, wirelessBasic5G=null, wirelessBasic5G2=null, wirelessBasic6G=null, wirelessAdv2G=null, wirelessAdv5G=null, wirelessAdv5G2=null, wirelessAdv6G=null, loadBalance2G=null, loadBalance5G=null, loadBalance5G2=null, loadBalance6G=null, bandSteering=null, ssid2G=null, ssid5G=null, ssid5G2=null, ssid6G=null, macFilterGlobal=null, portalFreePolicyConfig=null, macFilterList=null, macFilterAssoc=null, schedulerGlobal=null, schedulerList=null, schedulerAssoc=null, qosConfig2G=null, qosConfig5G=null, qosConfig5G2=null, qosConfig6G=null, logSetting=null, led=null, ssh=null, managementVlan=null, ouiBasedVlan=null, clientConfig=null, limits2G=null, limits5G=null, limits5G2=null, limits6G=null, timeSetting=null, dstConfig=null, rssi2G=null, rssi5G=null, rssi5G2=null, rssi6G=null, clientOperation=null, controllerInfo=null, standaloneMgmt=null, trunkConfig=null, rssiLeds=null, clientRateConfig=null, mesh=null, roaming=null, ipGroup=null, ipv6Group=null, ipPortGroup=null, ipv6PortGroup=null, acl=null, urlFiltering=null, snmp=null, common=null, wifiLedCtrl=null, smartAntenna=null, l3Access=null, energySaving=null, widsWips=null, mdns=null, dot1x=null, lldp=null, greTunnel=null, trunkSetting=null, userAccount=null, privacyPolicy=null)) 01-30-2024 16:26:37.976 INFO [adopt-work-group-0] [] c.t.s.o.m.d.d.m.d.o.e(): Gateway OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) SiteId(64184792a1c6f121bc55606f) DeviceMac(30-DE-4B-77-67-1B) adopt[auto=true] ok 01-30-2024 16:26:38.139 INFO [adopt-work-group-0] [] c.t.s.o.m.d.d.m.a.e(): send empty setting to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(30-DE-4B-77-67-1B) 01-30-2024 16:26:40.778 INFO [manage-work-group-1] [] c.t.s.o.m.d.p.t.c(): Device 30-DE-4B-77-67-1B OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle. 01-30-2024 16:26:42.392 INFO [gateway-dev-inform-pool-4] [] c.t.s.o.m.d.d.m.i.a(): got first inform of OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(30-DE-4B-77-67-1B) 01-30-2024 16:26:42.400 INFO [manage-work-group-2] [] c.t.s.o.m.d.d.m.i.e(): first inform no need send full config to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(30-DE-4B-77-67-1B) 01-30-2024 16:26:42.406 INFO [manage-work-group-2] [] c.t.s.o.m.c.d.m.s.d.d(): create new token for omadacId : 165628f2f47e9e0ca9b9e2a9ad8bbefe deviceMac : 30-DE-4B-77-67-1B 01-30-2024 16:26:45.190 INFO [setMsgThreadPool-5] [] c.t.s.o.m.d.d.m.d.o.F(): syncConfigurationForSameVersion to OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) DeviceMac(30-DE-4B-77-67-1B), result:SendDeviceMsgResult(success=true, deviceResponse=OsgConfigureRespBody(super=BaseConfigRespBody(sequenceId=1, errcode=0, configVersion=null, additionalProperties={}), userAccount=null, logSetting=null, controllerInfo=null, standaloneMgmt=null, privacyPolicy=null, wanBasicSetting=null, onlineDetection=null, wanLoadBalance=null, attackDefense=null, firewallConfig=null, ips=null, dpiProtocols=null, natAlg=null, snmp=null, upnp=null, ssh=null, dnsProxy=null, dnsCache=null, led=null, hwOffload=null, lldp=null, echoServer=null, dst=null, timeSetting=null, clientOpt=null, portalFreePolicy=null, mail=null, wanIpv4=null, wanIpv6=null, wanIpv4Usb=null, wanMac=null, network=null, acl=null, wirelessAcl=null, customAcl=null, urlFiltering=null, wirelessUrlFiltering=null, ipsWhiteList=null, ipsBlackList=null, signatureList=null, blockCountry=null, staticRouting=null, policyRouting=null, oneToOneNat=null, natPf=null, vpn=null, vpnUsers=null, macFilter=null, ipMacBinding=null, sessionLimit=null, bandwidthCtrl=null, qos=null, sslVpn=null, wireguard=null, mdns=null, iptv=null, ipsecFailover=null, timeRange=null, ipGroup=null, ipv6Group=null, ipPortGroup=null, ipv6PortGroup=null, macGroup=null, domainGroup=null, countryGroup=null, serviceType=null, ldap=null, clientIpBinding=null, ddns=null, port=null, speedDuplex=null, mirror=null, poe=null, lte=null, packageCaptureConfigResp=null, wirelessBasic2G=null, wirelessBasic5G=null, wirelessBasic5G2=null, wirelessAdv2G=null, wirelessAdv5G=null, wirelessAdv5G2=null, ssid2G=null, ssid5G=null, ssid5G2=null, limits2G=null, limits5G=null, limits5G2=null, loadBalance2G=null, loadBalance5G=null, loadBalance5G2=null, rssi2G=null, rssi5G=null, rssi5G2=null, bandSteering=null, macFilterGlobal=null, macFilterList=null, macFilterAssoc=null, schedulerGlobal=null, schedulerList=null, schedulerAssoc=null, qosConfig2G=null, qosConfig5G=null, qosConfig5G2=null, clientOperation=null, clientRateConfig=null, mesh=null, issuedRoamingInfo=null)) 01-30-2024 16:26:57.404 INFO [https-jsse-nio-8043-exec-10] [] c.t.s.o.i.p.c.b.h(): saveSession. session idle timeout is invalid. idleTimeout: null, tenantId: 64184f18e1bd532b221acea7. 01-30-2024 16:26:58.862 INFO [https-jsse-nio-8043-exec-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:27:01.619 INFO [https-jsse-nio-8043-exec-1] [] c.t.s.o.b.c.d.a(): init backup limit 50, restore limit 1 01-30-2024 16:27:12.815 INFO [tcp-message-executor-17-1] [] c.t.s.e.s.c.c(): need not set server route expire for device 30-DE-4B-77-67-1B, as device reconnect: /172.18.0.2:29814 -> /192.168.65.1:49979 01-30-2024 16:27:12.832 INFO [manage-work-group-3] [] c.t.s.o.m.d.p.t.c(): Device 30-DE-4B-77-67-1B OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) changed to status CONNECTED, which don't need to handle. 01-30-2024 16:27:12.852 INFO [manage-work-group-4] [] c.t.s.o.m.d.d.m.r.d(): send v2 rebuild reply[reset=false] to omadacId OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) & mac DeviceMac(30-DE-4B-77-67-1B) 01-30-2024 16:27:17.451 INFO [check-update-work-group-0] [] c.t.s.o.m.c.o(): Start sync Cloud Users OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe). 01-30-2024 16:27:27.954 INFO [scheduled-pool-3] [] c.t.s.o.m.s.c(): change omadacId : OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) siteId : [] to busy 01-30-2024 16:29:59.066 INFO [https-jsse-nio-8043-exec-7] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:31:29.443 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:32:28.766 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 16:32:28.768 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 16:33:20.444 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (manager_vpn_certificate_clear, null) but did not execute because corresponding handler manager_vpn_certificate_clear doesn't exist 01-30-2024 16:33:20.444 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (voucher_clear_task, null) but did not execute because corresponding handler voucher_clear_task doesn't exist 01-30-2024 16:36:29.450 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:40:29.491 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:41:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:43:45.049 INFO [common-block-pool-0] [] c.t.s.o.m.d.c.a.a(): no msp history data found. 01-30-2024 16:46:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:46:29.494 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:50:29.499 INFO [monitor-topology-pool-0] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:51:29.444 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:56:29.443 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 16:56:29.491 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 16:56:55.605 INFO [client-inform-work-group-1] [] c.t.s.o.c.d.a.z(): Known wireless client CC-A7-C1-4F-D2-C0 is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 17:01:29.450 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:02:10.120 INFO [client-schedule-statistic-task-0] [] c.t.s.o.c.d.a.t(): [STAT] {"wiredClients":13,"statName":"ClientStatInfo","authedClients":0,"wirelessClients":34} 01-30-2024 17:02:29.488 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:02:51.712 WARN [scheduled-pool-5] [] c.t.s.o.c.d.m.d.k(): Not the same connection and has changed before, create new clientHistory 01-30-2024 17:21:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:22:29.482 INFO [monitor-topology-pool-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:26:27.822 INFO [scheduled-pool-4] [] c.t.s.o.c.p.c.i(): Scheduling executed, set updateOmadacCacheEnable: false 01-30-2024 17:26:29.443 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:27:29.482 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:31:29.450 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:33:20.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (manager_vpn_certificate_clear, null) but did not execute because corresponding handler manager_vpn_certificate_clear doesn't exist 01-30-2024 17:33:29.465 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:35:01.416 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 17:35:04.406 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 17:35:04.681 INFO [https-jsse-nio-8043-exec-10] [] c.t.s.o.i.p.c.b.h(): saveSession. session idle timeout is invalid. idleTimeout: null, tenantId: 64184f18e1bd532b221acea7. 01-30-2024 17:35:05.408 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 17:35:09.495 INFO [nioEventLoopGroup-4-1] [] c.t.s.o.s.s.b.a(): Cannot find local ip belong to the same subnet, remote IP is 192.168.65.1. 01-30-2024 17:35:27.847 INFO [scheduled-pool-5] [] c.t.s.o.m.s.c(): change omadacId : OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) siteId : [] to busy 01-30-2024 17:36:26.573 INFO [https-jsse-nio-8043-exec-2] [] c.t.s.o.p.p.b.k(): Received invalid PortalBatchQueryDTO: PortalBatchQueryDTO(omadacId=165628f2f47e9e0ca9b9e2a9ad8bbefe, siteId=64184792a1c6f121bc55606f, portalIds=[]) 01-30-2024 17:36:29.464 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:36:36.614 INFO [https-jsse-nio-8043-exec-5] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:40:29.486 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:41:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:43:45.015 INFO [common-block-pool-12] [] c.t.s.o.m.d.c.a.a(): no msp history data found. 01-30-2024 17:46:29.442 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:46:29.495 INFO [monitor-topology-pool-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:50:29.494 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:51:29.442 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:54:29.498 INFO [monitor-topology-pool-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 17:56:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 17:59:29.492 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 18:00:03.853 INFO [autoBackupHandler] [] c.t.s.o.m.c.b.a.a.b.b(): Delete auto backup file /opt/tplink/EAPController/data/autobackup/autobackup_5.13.23_30days_2024-01-23_12-00-00_1706032800020.cfg succeed. 01-30-2024 18:01:29.447 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:02:10.048 INFO [client-schedule-statistic-task-0] [] c.t.s.o.c.d.a.t(): [STAT] {"wiredClients":12,"statName":"ClientStatInfo","authedClients":0,"wirelessClients":34} 01-30-2024 18:21:29.441 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:26:25.453 INFO [client-inform-work-group-7] [] c.t.s.o.c.d.a.z(): Known wireless client 18-B4-30-3A-83-6E is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 18:26:29.447 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:26:29.493 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 18:26:29.529 WARN [monitor-topology-pool-2] [] c.t.s.o.c.d.a.u(): Client:18-B4-30-9C-87-D3 is not OswClientImage, Cannot modify TrafficRequireMark. omadac:165628f2f47e9e0ca9b9e2a9ad8bbefe, site:64184792a1c6f121bc55606f 01-30-2024 18:26:57.659 INFO [client-inform-work-group-5] [] c.t.s.o.c.d.a.z(): Known wireless client CC-A7-C1-4F-D9-C9 is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 18:26:57.677 INFO [client-inform-work-group-5] [] c.t.s.o.c.d.a.z(): Known wireless client CC-A7-C1-4F-D0-BA is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 18:27:29.504 WARN [monitor-topology-pool-3] [] c.t.s.o.c.d.a.u(): Client:18-B4-30-9C-87-D3 is not OswClientImage, Cannot modify TrafficRequireMark. omadac:165628f2f47e9e0ca9b9e2a9ad8bbefe, site:64184792a1c6f121bc55606f 01-30-2024 18:27:51.939 INFO [client-inform-work-group-3] [] c.t.s.o.c.d.a.z(): Known wireless client 18-B4-30-3C-5B-CC is informed by gateway: 30-DE-4B-77-67-1B, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 18:31:29.445 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:31:29.494 INFO [monitor-topology-pool-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 18:31:29.514 WARN [monitor-topology-pool-3] [] c.t.s.o.c.d.a.u(): Client:18-B4-30-3C-5B-CC is not OswClientImage, Cannot modify TrafficRequireMark. omadac:165628f2f47e9e0ca9b9e2a9ad8bbefe, site:64184792a1c6f121bc55606f 01-30-2024 18:33:20.443 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (manager_vpn_certificate_clear, null) but did not execute because corresponding handler manager_vpn_certificate_clear doesn't exist 01-30-2024 18:36:29.446 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:39:22.392 WARN [scheduled-pool-3] [] c.t.s.o.c.d.m.d.k(): Not the same connection and has changed before, create new clientHistory 01-30-2024 18:39:29.495 INFO [monitor-topology-pool-3] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 18:41:29.444 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 18:43:45.048 INFO [common-block-pool-13] [] c.t.s.o.m.d.c.a(): clear omadac data with omadacId [165628f2f47e9e0ca9b9e2a9ad8bbefe], mspId [] 01-30-2024 18:43:45.057 INFO [common-block-pool-13] [] c.t.s.o.m.d.c.a.a(): no msp history data found. 01-30-2024 18:43:45.078 INFO [manager-data-retention-pool-0] [] c.t.s.c.u.l.LocalUpgradeFileCache(): delete expired upgrade file, number = 0 01-30-2024 18:44:29.487 INFO [monitor-topology-pool-0] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:02:10.061 INFO [client-schedule-statistic-task-0] [] c.t.s.o.c.d.a.t(): [STAT] {"wiredClients":12,"statName":"ClientStatInfo","authedClients":0,"wirelessClients":34} 01-30-2024 19:02:29.485 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:19:37.991 INFO [client-inform-work-group-0] [] c.t.s.o.c.d.a.z(): Known wireless client 58-55-95-3D-3C-8F is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 19:21:29.444 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 19:23:29.499 INFO [monitor-topology-pool-0] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:26:29.449 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 19:27:29.487 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:31:29.444 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 19:33:20.443 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (manager_vpn_certificate_clear, null) but did not execute because corresponding handler manager_vpn_certificate_clear doesn't exist 01-30-2024 19:33:29.488 INFO [monitor-topology-pool-0] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:36:29.450 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 19:37:29.504 INFO [monitor-topology-pool-0] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:41:29.444 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 19:43:45.017 INFO [common-block-pool-14] [] c.t.s.o.m.d.c.a.a(): no msp history data found. 01-30-2024 19:44:29.494 INFO [monitor-topology-pool-2] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 19:58:52.763 WARN [scheduled-pool-0] [] c.t.s.o.c.d.m.d.k(): Not the same connection and has changed before, create new clientHistory 01-30-2024 19:59:02.693 INFO [client-inform-work-group-7] [] c.t.s.o.c.d.a.z(): Known wireless client 58-55-95-3D-3C-8F is informed by switch: B4-B0-24-9D-95-C7, omadac: 165628f2f47e9e0ca9b9e2a9ad8bbefe, site: 64184792a1c6f121bc55606f, handle as wired client now. 01-30-2024 20:00:29.479 INFO [monitor-topology-pool-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 20:01:29.443 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 20:02:10.062 INFO [client-schedule-statistic-task-0] [] c.t.s.o.c.d.a.t(): [STAT] {"wiredClients":13,"statName":"ClientStatInfo","authedClients":0,"wirelessClients":34} 01-30-2024 20:04:52.801 INFO [scheduled-pool-3] [] c.t.s.o.c.d.m.d.k(): Need override old client history for client 58-55-95-3D-3C-8F with lastSeen 1706644774494 01-30-2024 20:31:29.443 WARN [quartzScheduler_Worker-2] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 20:31:36.784 INFO [https-jsse-nio-8043-exec-4] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 20:32:27.589 INFO [scheduled-pool-7] [] c.t.s.o.m.s.c(): change omadacId : OmadacId(165628f2f47e9e0ca9b9e2a9ad8bbefe) siteId : [] to busy 01-30-2024 20:33:00.308 INFO [https-jsse-nio-8043-exec-5] [] c.t.s.o.p.p.b.k(): Received invalid PortalBatchQueryDTO: PortalBatchQueryDTO(omadacId=165628f2f47e9e0ca9b9e2a9ad8bbefe, siteId=64184792a1c6f121bc55606f, portalIds=[]) 01-30-2024 20:33:20.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (manager_vpn_certificate_clear, null) but did not execute because corresponding handler manager_vpn_certificate_clear doesn't exist 01-30-2024 20:34:59.008 INFO [comm-pool-14] [] c.t.s.o.m.d.a.d.c(): start handle transfer pre-connected info. 01-30-2024 20:34:59.012 INFO [comm-pool-14] [] c.t.s.o.m.c.d.m.s.d.d(): create new token for omadacId : 165628f2f47e9e0ca9b9e2a9ad8bbefe deviceMac : 9C-A2-F4-4C-BB-30 01-30-2024 20:34:59.019 INFO [comm-pool-14] [] c.t.s.o.m.d.a.d.c(): send transfer channel pre-connected info response to dev: 9C-A2-F4-4C-BB-30 01-30-2024 20:34:59.100 INFO [device-gateway-transfer-work-group-0] [] c.t.s.o.m.a.a.a.i(): copy result is :51046 01-30-2024 20:35:45.580 INFO [https-jsse-nio-8043-exec-10] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 20:36:29.442 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist 01-30-2024 20:38:46.196 INFO [https-jsse-nio-8043-exec-1] [] c.t.s.o.c.u.e.a(): list local interface macs: [02-42-AC-12-00-02] 01-30-2024 20:41:15.700 INFO [comm-pool-13] [] c.t.s.o.m.d.a.d.c(): start handle transfer pre-connected info. 01-30-2024 20:41:15.704 INFO [comm-pool-13] [] c.t.s.o.m.d.a.d.c(): send transfer channel pre-connected info response to dev: 30-DE-4B-77-67-1B 01-30-2024 20:41:29.447 WARN [quartzScheduler_Worker-1] [] c.t.s.c.s.c.TaskExecutorService(): receive scheduled event with identity (log_device_status_push, null) but did not execute because corresponding handler log_device_status_push doesn't exist

PappyChappy1 commented 9 months ago

Disregard, this somehow resolved itself randomly