[Help]: Fail to start omada-controller - falling back to 1 default profile: "default" #465

trenton1999 · 2024-09-09T00:08:48Z

trenton1999
Sep 9, 2024

Controller Version

latest

Describe Your Issue or Question

I recently tried to install the omada-controller using the [mbentley] package and seems like everything went well until I waited almost 30 minutes for the container to start and never did. I went into the logs from portainer and I am getting the following error.

. ____ _ __ _ _
/\ / ' __ _ () __ __ _ \ \ \
( ( )__ | '_ | '| | ' / ` | \ \ \
\/ )| |)| | | | | || (| | ) ) ) )
' || .__|| ||| |_, | / / / /
=========||==============|/=////
:: Spring Boot :: (v2.7.18)
09-09-2024 00:02:28.013 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.14.26.1 using Java 17.0.12 on trenton with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.14.26.1.jar started by omada in /opt/tplink/EAPController/lib)
09-09-2024 00:02:28.034 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"

Apparently the No active profile set is causing the issue but no other components of why this is happening... I made sure I enabled all the necessary ports and restarted the console. No luck.

Expected Behavior

here is the command that I used to build the omada container:
docker volume create omada-data
docker volume create omada-work
docker volume create omada-logs

--name omada-controller
--restart unless-stopped
--net host
-e MANAGE_HTTP_PORT=8088
-e MANAGE_HTTPS_PORT=8043
-e PORTAL_HTTP_PORT=8088
-e PORTAL_HTTPS_PORT=8843
-e SHOW_SERVER_LOGS=true
-e SHOW_MONGODB_LOGS=false
-e SSL_CERT_NAME="tls.crt"
-e SSL_KEY_NAME="tls.key"
-e TZ=Etc/UTC
-v omada-data:/opt/tplink/EAPController/data
-v omada-work:/opt/tplink/EAPController/work
-v omada-logs:/opt/tplink/EAPController/logs
mbentley/omada-controller:latest

Steps to Reproduce

n/a

How You're Launching the Container

Automatically
docker run

Container Logs

09-09-2024 00:07:07.841 ERROR [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): Cannot start up springboot. reson:Error creating bean with name 'com.tplink.smb.omada.identityaccess.domain.model.openapi.c.q': Unsatisfied dependency expressed through field 'e'; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'com.tplink.smb.omada.identityaccess.port.common.a.a': Unsatisfied dependency expressed through field 'c'; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'com.tplink.smb.omada.identityaccess.port.common.e.a': Unsatisfied dependency expressed through field 'b'; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'com.tplink.smb.omada.identityaccess.b.p': Unsatisfied dependency expressed through field 'm'; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'com.tplink.smb.omada.identityaccess.domain.model.user.A': Unsatisfied dependency expressed through field 'c'; nested exception is org.springframework.beans.factory.BeanCurrentlyInCreationException: Error creating bean with name 'com.tplink.smb.omada.identityaccess.port.mongo.adaptor.persistence.tenant.a': Bean with name 'com.tplink.smb.omada.identityaccess.port.mongo.adaptor.persistence.tenant.a' has been injected into other beans [com.tplink.smb.omada.identityaccess.domain.model.d.q] in its raw version as part of a circular reference, but has eventually been wrapped. This means that said other beans do not use the final version of the bean. This is often the result of over-eager type matching - consider using 'getBeanNamesForType' with the 'allowEagerInit' flag turned off, for example.
09-09-2024 00:07:07.841 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): Try to reset default certificate and restart springBoot.

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::               (v2.7.18)

09-09-2024 00:07:08.260 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.14.26.1 using Java 17.0.12 on trenton with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.14.26.1.jar started by omada in /opt/tplink/EAPController/lib)
09-09-2024 00:07:08.261 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"

MongoDB Logs

I noticed that the tplink directory was not created in /opt/

Additional Context

No response

Answered by mbentley

Sep 9, 2024

Duplicate of #418

There is a software bug in the 5.14 version of the controller and that’s on TP-Link. Use 5.13 for now.

View full answer

mbentley · 2024-09-09T00:12:11Z

mbentley
Sep 9, 2024
Maintainer

Duplicate of #418

There is a software bug in the 5.14 version of the controller and that’s on TP-Link. Use 5.13 for now.

1 reply

trenton1999 Sep 9, 2024
Author

all good. fixed!

Engine1992 · 2024-12-08T09:29:14Z

Engine1992
Dec 8, 2024

Hi! Same problem for version 5.15. Bug is still unresolved? When trying to downgrade to 15.13, I get the following message:

ERROR: The version from the image (5.13.30.8) is older than the last version executed (5.15.6.7)!  Refusing to start to prevent data loss!
  To bypass this check, remove /opt/tplink/EAPController/data/LAST_RAN_OMADA_VER.txt only if you REALLY know what you're doing!

Will I lose my data if I try to force downgrade? I have lots of data and settings, so don't want to try it without making sure 😅

LOGS:

INFO: Starting Omada Controller as user omada
12-08-2024 10:08:32.064 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: start the omada controller
12-08-2024 10:08:32.086 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: set property finished
12-08-2024 10:08:32.191 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): record: configure log finished
12-08-2024 10:08:32.209 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap prepare
12-08-2024 10:08:32.242 INFO [main] [] c.t.s.o.s.g.c(): Current dir omadac type is linux
12-08-2024 10:08:32.304 INFO [main] [] c.t.s.o.c.o.c.b(): success to load configuration omada.properties
12-08-2024 10:08:32.306 INFO [main] [] c.t.s.o.c.o.OmadacType(): omadacType: Local Controller
12-08-2024 10:08:32.306 INFO [main] [] c.t.s.o.s.g.c(): Configuration omadacType is linux
12-08-2024 10:08:32.483 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): going to start local mongod.
12-08-2024 10:08:42.015 INFO [main] [] c.t.s.o.s.s.b(): mongodb process id is 242
12-08-2024 10:08:42.020 ERROR [main] [] c.t.s.f.c.FacadeUtils(): facadeMsgEnable is not enable, msg: Mongo DB server started
12-08-2024 10:08:42.021 INFO [main] [] c.t.s.o.s.s.b(): Mongo DB server started
12-08-2024 10:08:43.763 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap record finished
12-08-2024 10:08:43.764 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: bootstrap startup
12-08-2024 10:08:43.971 INFO [main] [] c.t.s.o.s.OmadaBootstrap(): record: start run omada tasks
12-08-2024 10:08:43.972 INFO [main] [] c.t.s.o.s.t.SpringBootStartUpTask(): record: task SpringBootStartupTask start
  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::               (v2.7.18)
12-08-2024 10:08:45.391 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): Starting OmadaLinuxMain v5.15.6.7 using Java 17.0.13 on Docker with PID 1 (/opt/tplink/EAPController/lib/local-starter-5.15.6.7.jar started by omada in /opt/tplink/EAPController/lib)
**> 12-08-2024 10:08:45.393 INFO [main] [] c.t.s.o.s.OmadaLinuxMain(): No active profile set, falling back to 1 default profile: "default"
12-08-2024 10:09:19.318 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**
12-08-2024 10:09:35.197 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
12-08-2024 10:09:36.935 INFO [main] [] c.t.s.e.s.c.c(): start schedule remove expire device... period = 10
12-08-2024 10:09:37.211 INFO [main] [] c.t.s.e.s.c.c(): update rateLimiterCache, permitsPerSecond = 5.0
12-08-2024 10:09:38.031 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): upgradeSendReq workGroup core thread num is 1, max thread num is 1
12-08-2024 10:09:40.395 INFO [main] [] c.t.s.o.m.p.c.o.ActiveSiteCacheImpl(): Scheduled ActiveSitesCache period flush buf task at fixed rate of 30000 millis.
12-08-2024 10:09:44.109 INFO [main] [] c.t.s.o.m.d.d.m.m.c.DeviceMsgConfig(): setMsgThreadPool thread coreSize is 60, maxSize is 60,queue size is 4500
12-08-2024 10:09:46.183 INFO [main] [] c.t.s.o.m.c.t.e.TemplateSyncExecutorConfiguration(): template sync workGroup core thread num is 30, max thread num is 30
12-08-2024 10:09:46.276 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manager workGroup core thread  num is 16, max thread num is 16
12-08-2024 10:09:54.610 INFO [main] [] c.t.s.o.m.c.d.m.s.w.s.c.WirelessGroupConfig(): ssidWorkerGroup thread size is 40, queue size is 1500
12-08-2024 10:09:58.040 INFO [main] [] c.t.s.o.m.c.d.m.s.d.d(): init token valid duration : 86400
12-08-2024 10:09:58.806 INFO [main] [] c.t.s.o.c.p.m.d.h.j(): Initializing clientHistoryRestrict: Optional[12000000]
12-08-2024 10:10:00.272 INFO [main] [] c.t.s.o.m.d.p.t.TransportConfiguration(): manage Inform workGroup core thread  num is 16, max thread num is 16
12-08-2024 10:10:01.736 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.752 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.753 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.754 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.754 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.755 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.755 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.756 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.756 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.757 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.757 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.758 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.758 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.764 INFO [main] [] c.t.s.o.o.c.SwaggerConfig(): initOpenAPI.docInfo=DocInfo(title=Omada Open API, description=Omada Open API, version=v0.1, websiteName=Omada Controller, websiteUrl=http://omada.tplink.com)
12-08-2024 10:10:01.821 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
12-08-2024 10:10:09.854 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
12-08-2024 10:10:15.790 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting Digest-Attributes with WISPr-Redirection-URL
12-08-2024 10:10:15.791 WARN [main] [] c.t.c.r.c.d.MemoryDictionary(): Duplicate type code [-1,207], overwriting WISPr-Redirection-URL with WISPr-Location-ID

7 replies

mbentley Dec 8, 2024
Maintainer

So there is a small chance that the controller doesn't start to the point where it performs the database migrations. You can make sure the container is stopped, create a backup of your persistent data (creating a simple tarball of the persistent directory would be good enough) and then try to force the older version to start by removing the version check file. If it has performed the db migrations, it'll almost certainly brick the controller but you'd have the backup, although it's not really going to be helpful.

It seems like there is something in the config settings in database that it is seeing as some sort of critical error, preventing startup. I don't have the ability to dig into much inside the controller app itself unfortunately since it's a closed source app.

Engine1992 Dec 8, 2024

ok, so in this case, set everything up again, configure it from scratch and be sure to run backups 😅
I think this will be the best and fastest solution

Engine1992 Dec 8, 2024

Ok, I set everything up again, cleanly, but I still get the warning:

12-08-2024 21:42:05.268 WARN [https-jsse-nio-8043-exec-2] [] 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

Is that ok? Shouldn't there be this?

mbentley Dec 8, 2024
Maintainer

That's something I see normally in my logs; I wouldn't be concerned with that.

Engine1992 Dec 8, 2024

Ok thanks a lot! :)

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[Help]: Fail to start omada-controller - falling back to 1 default profile: "default" #465

{{title}}

Replies: 2 comments 8 replies

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

[Help]: Fail to start omada-controller - falling back to 1 default profile: "default" #465

trenton1999 Sep 9, 2024

Controller Version

Describe Your Issue or Question

Expected Behavior

Steps to Reproduce

How You're Launching the Container

Container Logs

MongoDB Logs

Additional Context

Replies: 2 comments · 8 replies

mbentley Sep 9, 2024 Maintainer

trenton1999 Sep 9, 2024 Author

Engine1992 Dec 8, 2024

mbentley Dec 8, 2024 Maintainer

Engine1992 Dec 8, 2024

Engine1992 Dec 8, 2024

mbentley Dec 8, 2024 Maintainer

Engine1992 Dec 8, 2024

trenton1999
Sep 9, 2024

Replies: 2 comments 8 replies

mbentley
Sep 9, 2024
Maintainer

trenton1999 Sep 9, 2024
Author

Engine1992
Dec 8, 2024

mbentley Dec 8, 2024
Maintainer

mbentley Dec 8, 2024
Maintainer