Trouble with starting conductor.

[sorenwacker]

Hi, I am having difficulties starting the conductor platform setup on my MacBook.

Docker configuration

Output

make platform
PROFILE=platform docker compose --profile platform up --attach conductor
[+] Building 0.0s (0/0)                                                                                                                                                                                                                   docker:desktop-linux
[+] Running 8/0
 ✔ Container minio          Created                                                                                                                                                                                                                       0.0s 
 ✔ Container kafka          Created                                                                                                                                                                                                                       0.0s 
 ✔ Container elasticsearch  Created                                                                                                                                                                                                                       0.0s 
 ✔ Container conductor      Created                                                                                                                                                                                                                       0.0s 
 ✔ Container keycloak-db    Created                                                                                                                                                                                                                       0.0s 
 ✔ Container song-db        Created                                                                                                                                                                                                                       0.0s 
 ✔ Container keycloak       Created                                                                                                                                                                                                                       0.0s 
 ✔ Container score          Created                                                                                                                                                                                                                       0.0s 
Attaching to conductor
conductor  | Profile is set to: platform
conductor  | Running platform deployment...
conductor  | ╔═══════════════════════════════════════════════════╗
conductor  | ║    Welcome to the Overture Platform QuickStart    ║
conductor  | ╚═══════════════════════════════════════════════════╝
conductor  | [1/9] Setting up Song & Keycloak databases
conductor  | Setting up empty directories for Songs postgres database
conductor  | Setting up empty directories for Keycloaks postgres database
conductor  | [2/9] Checking Minio Object Storage
conductor  | Minio: Not yet reachable, checking again in 15 seconds
canceled
conductor  | Success: Minio is reachable
conductor  | [3/9] Checking on Score
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Success: Score is now reachable
conductor  | [4/9] Checking on Song
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds

What can I do to troubleshoot this?

[MitchellShiell]

Hey Soren,

From the container tab in docker desktop can you select songDb and post the logs here? Additionally if you can provide Songs logs that should give me enough information to find you a solution. You should be able to see an error in either one so feel free to share just that if you spot it.

[MitchellShiell]

Closing this discussion thread due to inactivity but let us know if you managed to figure it out. If you're still experiencing this issue please feel free to reopen this discussion I'll be happy to help troubleshoot.

[sorenwacker]

Hi Mitchell,

sorry about the radio-silence, I had lost the link to this forum.

This is how it looks right now on my MacBook:

conductor  | Running platform deployment...
conductor  | ╔═══════════════════════════════════════════════════╗
conductor  | ║    Welcome to the Overture Platform QuickStart    ║
conductor  | ╚═══════════════════════════════════════════════════╝
conductor  | [1/9] Setting up Song & Keycloak databases
conductor  | Setting up empty directories for Songs postgres database
conductor  | Setting up empty directories for Keycloaks postgres database
conductor  | [2/9] Checking Minio Object Storage
conductor  | Minio: Not yet reachable, checking again in 15 seconds
conductor  | Success: Minio is reachable
conductor  | [3/9] Checking on Score
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Success: Score is now reachable
conductor  | [4/9] Checking on Song
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds

Score logs:

2025-02-06 10:49:18    _____                                 _____                          
2025-02-06 10:49:18   / ___/_________  ________             / ___/___  ______   _____  _____
2025-02-06 10:49:18   \__ \/ ___/ __ \/ ___/ _ \   ______   \__ \/ _ \/ ___/ | / / _ \/ ___/
2025-02-06 10:49:18  ___/ / /__/ /_/ / /  /  __/  /_____/  /__/ /  __/ /   | |/ /  __/ /    
2025-02-06 10:49:18 /____/\___/\____/_/   \___/           /____/\___/_/    |___/\___/_/     
2025-02-06 10:49:18 
2025-02-06 10:49:18 
2025-02-06 10:49:19 2025-02-06 09:49:19.496  INFO 8 --- [           main] bio.overture.score.server.ServerMain     : The following 5 profiles are active: "default", "collaboratory", "prod", "secure", "jwt"
2025-02-06 10:49:29 2025-02-06 09:49:29.204  INFO 8 --- [           main] o.s.cloud.context.scope.GenericScope     : BeanFactory id=fc45dd2f-0062-3a58-b3fb-5f2834d52949
2025-02-06 10:49:30 2025-02-06 09:49:30.334  INFO 8 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'methodSecurityConfig' of type [bio.overture.score.server.config.MethodSecurityConfig$$EnhancerBySpringCGLIB$$9ae499a3] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2025-02-06 10:49:33 2025-02-06 09:49:33.685  INFO 8 --- [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat initialized with port(s): 8087 (http)
2025-02-06 10:49:33 2025-02-06 09:49:33.794  INFO 8 --- [           main] o.apache.catalina.core.StandardService   : Starting service [Tomcat]
2025-02-06 10:49:33 2025-02-06 09:49:33.798  INFO 8 --- [           main] org.apache.catalina.core.StandardEngine  : Starting Servlet engine: [Apache Tomcat/9.0.60]
2025-02-06 10:49:34 2025-02-06 09:49:34.616  INFO 8 --- [           main] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring embedded WebApplicationContext
2025-02-06 10:49:34 2025-02-06 09:49:34.617  INFO 8 --- [           main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 14760 ms
2025-02-06 10:49:39 2025-02-06 09:49:38.998  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : ----------------------------------------------------------------------------------------------------
2025-02-06 10:49:39 2025-02-06 09:49:39.092  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : Command:  java -Dlog.path=/score-server/logs -jar classes! ...
2025-02-06 10:49:39 2025-02-06 09:49:39.097  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : Version:  5.11.0
2025-02-06 10:49:39 2025-02-06 09:49:39.098  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : Built:    5.11
2025-02-06 10:49:39 2025-02-06 09:49:39.099  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : SCM:
2025-02-06 10:49:39 2025-02-06 09:49:39.533  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : Profiles: [default, collaboratory, prod, secure, jwt]
2025-02-06 10:49:39 2025-02-06 09:49:39.534  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : ApplicationServletEnvironment {activeProfiles=[default, collaboratory, prod, secure, jwt], defaultProfiles=[default], propertySources=[ConfigurationPropertySourcesPropertySource {name='configurationProperties'}, SimpleCommandLinePropertySource {name='commandLineArgs'}, StubPropertySource {name='servletConfigInitParams'}, ServletContextPropertySource {name='servletContextInitParams'}, PropertiesPropertySource {name='systemProperties'}, OriginAwareSystemEnvironmentPropertySource {name='systemEnvironment'}, RandomValuePropertySource {name='random'}, CachedRandomPropertySource {name='cachedrandom'}, MapPropertySource {name='springCloudClientHostInfo'}, OriginTrackedMapPropertySource {name='Config resource 'class path resource [bootstrap.properties]' via location 'classpath:/bootstrap.properties''}, OriginTrackedMapPropertySource {name='Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #6)'}, OriginTrackedMapPropertySource {name='Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #5)'}, OriginTrackedMapPropertySource {name='Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #3)'}, OriginTrackedMapPropertySource {name='Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #0)'}, MapPropertySource {name='springCloudDefaultProperties'}]}:
2025-02-06 10:49:39 2025-02-06 09:49:39.535  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          configurationProperties:
2025-02-06 10:49:39 2025-02-06 09:49:39.535  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          commandLineArgs:
2025-02-06 10:49:39 2025-02-06 09:49:39.584  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.config.location: classpath:/application.yml,classpath:/bootstrap.properties
2025-02-06 10:49:39 2025-02-06 09:49:39.584  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          servletConfigInitParams:
2025-02-06 10:49:39 2025-02-06 09:49:39.585  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          servletContextInitParams:
2025-02-06 10:49:39 2025-02-06 09:49:39.612  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          systemProperties:
2025-02-06 10:49:39 2025-02-06 09:49:39.631  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - CONSOLE_LOG_CHARSET: UTF-8
2025-02-06 10:49:39 2025-02-06 09:49:39.635  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - FILE_LOG_CHARSET: UTF-8
2025-02-06 10:49:39 2025-02-06 09:49:39.635  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - PID: 8
2025-02-06 10:49:39 2025-02-06 09:49:39.635  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - awt.toolkit: sun.awt.X11.XToolkit
2025-02-06 10:49:39 2025-02-06 09:49:39.635  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - catalina.base: /tmp/tomcat.8087.13702649264380361141
2025-02-06 10:49:39 2025-02-06 09:49:39.635  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - catalina.home: /tmp/tomcat.8087.13702649264380361141
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - catalina.useNaming: false
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - file.encoding: UTF-8
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - file.separator: /
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.awt.graphicsenv: sun.awt.X11GraphicsEnvironment
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.awt.headless: true
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.awt.printerjob: sun.print.PSPrinterJob
2025-02-06 10:49:39 2025-02-06 09:49:39.636  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.class.path: /score-server.jar
2025-02-06 10:49:39 2025-02-06 09:49:39.637  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.class.version: 55.0
2025-02-06 10:49:39 2025-02-06 09:49:39.637  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.home: /opt/java/openjdk
2025-02-06 10:49:39 2025-02-06 09:49:39.639  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.io.tmpdir: /tmp
2025-02-06 10:49:39 2025-02-06 09:49:39.649  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.library.path: /usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
2025-02-06 10:49:39 2025-02-06 09:49:39.650  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.protocol.handler.pkgs: org.springframework.boot.loader
2025-02-06 10:49:39 2025-02-06 09:49:39.652  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.runtime.name: OpenJDK Runtime Environment
2025-02-06 10:49:39 2025-02-06 09:49:39.652  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.runtime.version: 11.0.6+10
2025-02-06 10:49:39 2025-02-06 09:49:39.652  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.specification.name: Java Platform API Specification
2025-02-06 10:49:39 2025-02-06 09:49:39.653  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.specification.vendor: Oracle Corporation
2025-02-06 10:49:39 2025-02-06 09:49:39.653  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.specification.version: 11
2025-02-06 10:49:39 2025-02-06 09:49:39.653  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vendor: AdoptOpenJDK
2025-02-06 10:49:39 2025-02-06 09:49:39.653  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vendor.url: https://adoptopenjdk.net/
2025-02-06 10:49:39 2025-02-06 09:49:39.653  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vendor.url.bug: https://github.com/AdoptOpenJDK/openjdk-support/issues
2025-02-06 10:49:39 2025-02-06 09:49:39.654  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vendor.version: AdoptOpenJDK
2025-02-06 10:49:39 2025-02-06 09:49:39.656  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.version: 11.0.6
2025-02-06 10:49:39 2025-02-06 09:49:39.659  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.version.date: 2020-01-14
2025-02-06 10:49:39 2025-02-06 09:49:39.659  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.compressedOopsMode: Zero based
2025-02-06 10:49:39 2025-02-06 09:49:39.659  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.info: mixed mode
2025-02-06 10:49:39 2025-02-06 09:49:39.659  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.name: OpenJDK 64-Bit Server VM
2025-02-06 10:49:39 2025-02-06 09:49:39.659  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.specification.name: Java Virtual Machine Specification
2025-02-06 10:49:39 2025-02-06 09:49:39.660  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.specification.vendor: Oracle Corporation
2025-02-06 10:49:39 2025-02-06 09:49:39.668  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.specification.version: 11
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.vendor: AdoptOpenJDK
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - java.vm.version: 11.0.6+10
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - jdk.debug: release
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - line.separator: 
2025-02-06 10:49:39 
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - log.path: /score-server/logs
2025-02-06 10:49:39 2025-02-06 09:49:39.670  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - os.arch: amd64
2025-02-06 10:49:39 2025-02-06 09:49:39.671  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - os.name: Linux
2025-02-06 10:49:39 2025-02-06 09:49:39.678  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - os.version: 6.4.16-linuxkit
2025-02-06 10:49:39 2025-02-06 09:49:39.678  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - path.separator: :
2025-02-06 10:49:39 2025-02-06 09:49:39.678  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.beaninfo.ignore: true
2025-02-06 10:49:39 2025-02-06 09:49:39.678  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.arch.data.model: 64
2025-02-06 10:49:39 2025-02-06 09:49:39.692  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.boot.library.path: /opt/java/openjdk/lib
2025-02-06 10:49:39 2025-02-06 09:49:39.693  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.cpu.endian: little
2025-02-06 10:49:39 2025-02-06 09:49:39.693  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.cpu.isalist: 
2025-02-06 10:49:39 2025-02-06 09:49:39.693  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.io.unicode.encoding: UnicodeLittle
2025-02-06 10:49:39 2025-02-06 09:49:39.694  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.java.command: /score-server.jar --spring.config.location=classpath:/application.yml,classpath:/bootstrap.properties
2025-02-06 10:49:39 2025-02-06 09:49:39.694  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.java.launcher: SUN_STANDARD
2025-02-06 10:49:39 2025-02-06 09:49:39.695  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.jnu.encoding: UTF-8
2025-02-06 10:49:39 2025-02-06 09:49:39.695  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.management.compiler: HotSpot 64-Bit Tiered Compilers
2025-02-06 10:49:39 2025-02-06 09:49:39.696  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - sun.os.patch.level: unknown
2025-02-06 10:49:39 2025-02-06 09:49:39.696  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.country: US
2025-02-06 10:49:39 2025-02-06 09:49:39.697  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.dir: /score-server
2025-02-06 10:49:39 2025-02-06 09:49:39.697  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.home: /home/score
2025-02-06 10:49:39 2025-02-06 09:49:39.697  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.language: en
2025-02-06 10:49:39 2025-02-06 09:49:39.697  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.name: score
2025-02-06 10:49:39 2025-02-06 09:49:39.698  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - user.timezone: GMT
2025-02-06 10:49:39 2025-02-06 09:49:39.698  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          cachedrandom:
2025-02-06 10:49:39 2025-02-06 09:49:39.699  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          springCloudClientHostInfo:
2025-02-06 10:49:39 2025-02-06 09:49:39.712  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.cloud.client.hostname: 94b440bc3d05
2025-02-06 10:49:39 2025-02-06 09:49:39.716  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.cloud.client.ip-address: 172.20.0.9
2025-02-06 10:49:39 2025-02-06 09:49:39.716  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          Config resource 'class path resource [bootstrap.properties]' via location 'classpath:/bootstrap.properties':
2025-02-06 10:49:39 2025-02-06 09:49:39.717  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.cloud.vault.enabled: false
2025-02-06 10:49:39 2025-02-06 09:49:39.717  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #6):
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.clientID: score
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.clientSecret: scoresecret
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.keycloak.host: http://localhost
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.keycloak.realm: myrealm
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.provider: ego
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.download.study.prefix: PROGRAMDATA-
2025-02-06 10:49:39 2025-02-06 09:49:39.718  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.download.study.suffix: .READ
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.download.system: score.READ
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.upload.study.prefix: PROGRAMDATA-
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.upload.study.suffix: .WRITE
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.scope.upload.system: score.WRITE
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.tokenName: apiToken
2025-02-06 10:49:39 2025-02-06 09:49:39.719  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.server.url: https://localhost:8443/oauth/check_token
2025-02-06 10:49:39 2025-02-06 09:49:39.724  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.config.activate.on-profile: secure
2025-02-06 10:49:39 2025-02-06 09:49:39.725  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.security.oauth2.resourceserver.jwt.public-key-location: https://localhost:8443/oauth/token/public_key
2025-02-06 10:49:39 2025-02-06 09:49:39.726  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #5):
2025-02-06 10:49:39 2025-02-06 09:49:39.726  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - metadata.url: https://meta.icgc.org
2025-02-06 10:49:39 2025-02-06 09:49:39.726  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.secured: true
2025-02-06 10:49:39 2025-02-06 09:49:39.726  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.config.activate.on-profile: prod
2025-02-06 10:49:39 2025-02-06 09:49:39.726  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #3):
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - metadata.url: https://song.cancercollaboratory.org
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - metadata.useLegacyMode: false
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.endpoint: https://object.cancercollaboratory.org:9080
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.masterEncryptionKeyId: 
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.config.activate.on-profile: collaboratory
2025-02-06 10:49:39 2025-02-06 09:49:39.727  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          Config resource 'class path resource [application.yml]' via location 'classpath:/application.yml' (document #0):
2025-02-06 10:49:39 2025-02-06 09:49:39.728  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.connection.initialBackoff: 15000
2025-02-06 10:49:39 2025-02-06 09:49:39.728  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.connection.maxRetries: 5
2025-02-06 10:49:39 2025-02-06 09:49:39.728  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - auth.connection.multiplier: 2.0
2025-02-06 10:49:39 2025-02-06 09:49:39.737  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - bucket.name.object: oicr.icgc
2025-02-06 10:49:39 2025-02-06 09:49:39.738  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - bucket.name.state: oicr.icgc
2025-02-06 10:49:39 2025-02-06 09:49:39.739  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - bucket.size.key: 2
2025-02-06 10:49:39 2025-02-06 09:49:39.739  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - bucket.size.pool: 0
2025-02-06 10:49:39 2025-02-06 09:49:39.739  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - collaboratory.data.directory: data
2025-02-06 10:49:39 2025-02-06 09:49:39.742  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - collaboratory.download.expiration: 1
2025-02-06 10:49:39 2025-02-06 09:49:39.742  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - collaboratory.upload.directory: upload
2025-02-06 10:49:39 2025-02-06 09:49:39.742  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - collaboratory.upload.expiration: 6
2025-02-06 10:49:39 2025-02-06 09:49:39.742  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - logging.level.org.springframework.web: INFO
2025-02-06 10:49:39 2025-02-06 09:49:39.742  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - logging.level.root: INFO
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - management.endpoints.web.cors.allowedOrigins: http://localhost:8081
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - metadata.useLegacyMode: false
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - object.sentinel: heliograph
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.endpoint: s3-external-1.amazonaws.com
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.preSignedUrl.override.host: 
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.secured: true
2025-02-06 10:49:39 2025-02-06 09:49:39.743  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - s3.sigV4Enabled: true
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - server.compression.enabled: true
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - server.compression.mime-types: application/json
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - server.cors.allowedOrigins: http://localhost:8081
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - server.port: 5431
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - spring.mvc.pathmatch.matching-strategy: ant_path_matcher
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - upload.clean.cron: 0 0 0 * * ?
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - upload.clean.enabled: true
2025-02-06 10:49:39 2025-02-06 09:49:39.744  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - upload.connection.timeout: 15000
2025-02-06 10:49:39 2025-02-06 09:49:39.745  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - upload.partsize: 20000000
2025-02-06 10:49:39 2025-02-06 09:49:39.745  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :             - upload.retry.limit: 5
2025-02-06 10:49:39 2025-02-06 09:49:39.745  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     :          springCloudDefaultProperties:
2025-02-06 10:49:39 2025-02-06 09:49:39.745  INFO 8 --- [           main] b.o.score.server.config.ServerBanner     : ----------------------------------------------------------------------------------------------------
2025-02-06 10:49:39 
2025-02-06 10:49:39 
2025-02-06 10:49:44 2025-02-06 09:49:44.288  INFO 8 --- [           main] b.overture.score.server.config.S3Config  : master key id : ''
2025-02-06 10:49:44 2025-02-06 09:49:44.292  INFO 8 --- [           main] b.overture.score.server.config.S3Config  : Using AWSS3V4SignerType
2025-02-06 10:49:51 2025-02-06 09:49:51.931  INFO 8 --- [           main] o.s.b.a.e.web.EndpointLinksResolver      : Exposing 1 endpoint(s) beneath base path '/actuator'
2025-02-06 10:49:54 2025-02-06 09:49:54.811  INFO 8 --- [           main] pertySourcedRequestMappingHandlerMapping : Mapped URL path [/v2/api-docs] onto method [springfox.documentation.swagger2.web.Swagger2Controller#getDocumentation(String, HttpServletRequest)]
2025-02-06 10:49:55 2025-02-06 09:49:55.847  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using uploadSystemScope: score.WRITE
2025-02-06 10:49:55 2025-02-06 09:49:55.852  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using uploadStudyScopePrefix: PROGRAMDATA-
2025-02-06 10:49:55 2025-02-06 09:49:55.852  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using uploadStudyScopeSuffix: .WRITE
2025-02-06 10:49:55 2025-02-06 09:49:55.852  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using downloadSystemScope: score.READ
2025-02-06 10:49:55 2025-02-06 09:49:55.852  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using downloadStudyScopePrefix: PROGRAMDATA-
2025-02-06 10:49:55 2025-02-06 09:49:55.852  INFO 8 --- [           main] b.o.s.server.properties.ScopeProperties  : Using downloadStudyScopeSuffix: .READ
2025-02-06 10:49:56 2025-02-06 09:49:56.033  INFO 8 --- [           main] b.o.score.server.config.SecurityConfig   : initialization done
2025-02-06 10:49:56 2025-02-06 09:49:56.196  INFO 8 --- [           main] o.s.s.web.DefaultSecurityFilterChain     : Will not secure any request
2025-02-06 10:49:57 2025-02-06 09:49:57.585  INFO 8 --- [           main] o.s.b.a.w.s.WelcomePageHandlerMapping    : Adding welcome page: class path resource [static/index.html]
2025-02-06 10:49:59 2025-02-06 09:49:59.070  INFO 8 --- [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat started on port(s): 8087 (http) with context path ''
2025-02-06 10:49:59 2025-02-06 09:49:59.096  INFO 8 --- [           main] d.s.w.p.DocumentationPluginsBootstrapper : Context refreshed
2025-02-06 10:49:59 2025-02-06 09:49:59.239  INFO 8 --- [           main] d.s.w.p.DocumentationPluginsBootstrapper : Found 1 custom documentation plugin(s)
2025-02-06 10:49:59 2025-02-06 09:49:59.568  INFO 8 --- [           main] s.d.s.w.s.ApiListingReferenceScanner     : Scanning for api listing references
2025-02-06 10:50:02 2025-02-06 09:50:02.829  INFO 8 --- [           main] bio.overture.score.server.ServerMain     : Started ServerMain in 55.578 seconds (JVM running for 61.374)
2025-02-06 10:50:15 2025-02-06 09:50:15.708  INFO 8 --- [nio-8087-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2025-02-06 10:50:15 2025-02-06 09:50:15.712  INFO 8 --- [nio-8087-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2025-02-06 10:50:15 2025-02-06 09:50:15.718  INFO 8 --- [nio-8087-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 3 ms
2025-02-06 10:50:16 2025-02-06 09:50:16.098  INFO 8 --- [nio-8087-exec-1] b.o.s.s.controller.DownloadController    : Requesting download of sentinel object id with access token 70c37e016dff127248771761ddac05a7 (MD5) from 172.20.0.4 and client version unknown

Here it stops.

[sorenwacker]

I don't see a mechanism here to reopen the discussion on my end.

[MitchellShiell]

No worries Soren, everything looks good here, but these are the Score logs, which from your initial post we can see are passing the checks, I'm more interested in the Song and SongDb logs they should be able to give us more information on whats going on here.

[sorenwacker]

Ok,

make platform
PROFILE=platform docker compose --profile platform up --attach conductor
[+] Building 0.0s (0/0)                                                                                                                                             docker:desktop-linux
[+] Running 8/0
 ✔ Container elasticsearch  Created                                                                                                                                                 0.0s 
 ✔ Container conductor      Created                                                                                                                                                 0.0s 
 ✔ Container minio          Created                                                                                                                                                 0.0s 
 ✔ Container kafka          Created                                                                                                                                                 0.0s 
 ✔ Container song-db        Created                                                                                                                                                 0.0s 
 ✔ Container keycloak-db    Created                                                                                                                                                 0.0s 
 ✔ Container keycloak       Created                                                                                                                                                 0.0s 
 ✔ Container score          Created                                                                                                                                                 0.0s 
Attaching to conductor
conductor  | Profile is set to: platform
conductor  | Running platform deployment...
conductor  | ╔═══════════════════════════════════════════════════╗
conductor  | ║    Welcome to the Overture Platform QuickStart    ║
conductor  | ╚═══════════════════════════════════════════════════╝
conductor  | [1/9] Setting up Song & Keycloak databases
conductor  | Setting up empty directories for Songs postgres database
conductor  | Setting up empty directories for Keycloaks postgres database
conductor  | [2/9] Checking Minio Object Storage
conductor  | Minio: Not yet reachable, checking again in 15 seconds
conductor  | Success: Minio is reachable
conductor  | [3/9] Checking on Score
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Score: Not yet reachable, checking again in 20 seconds
conductor  | Success: Score is now reachable
conductor  | [4/9] Checking on Song
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds
conductor  | Song: Not yet reachable, checking again in 20 seconds

song-db logs:

2025-02-12 15:50:00 2025-02-12 14:50:00.188 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2025-02-12 15:50:00 2025-02-12 14:50:00.189 UTC [1] LOG:  listening on IPv6 address "::", port 5432
2025-02-12 15:50:00 2025-02-12 14:50:00.198 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2025-02-12 15:50:00 2025-02-12 14:50:00.300 UTC [55] LOG:  database system was shut down at 2025-02-12 14:49:57 UTC
2025-02-12 15:50:00 2025-02-12 14:50:00.348 UTC [1] LOG:  database system is ready to accept connections

song logs:

(Song did not create any logs.)

Resource usage

Most of the CPU usage comes from Kafka.

[MitchellShiell]

That's odd but helpful, based on the docker compose Song waits for keycloak and the keycloakDb to return healthy are either of those failing? The fact song is there but not starting suggests one of those services (or there health checks) is failing.

Have not been able to reproduce this on my machine if you could (1) check keycloak and keycloakDb and (2) give me some info on your docker version and hardware that could help me investigate further.

[sorenwacker]

Docker

Version

4.24.2 (124339)

Engine: 24.0.6

Compose: v2.22.0-desktop.2

Credential Helper: v0.7.0

Kubernetes: v1.27.2

Hardware

MacBook with M2 Pro
32GB
macOS 15.3.1

Logs

keycloak

2025-02-17 09:26:35 2025-02-17 08:26:21,114 INFO  [org.keycloak.quarkus.runtime.hostname.DefaultHostnameProvider] (main) Hostname settings: Base URL: <unset>, Hostname: localhost, Strict HTTPS: false, Path: <request>, Strict BackChannel: false, Admin URL: <unset>, Admin: <request>, Port: 8180, Proxied: false
2025-02-17 09:26:35 2025-02-17 08:26:34,946 WARN  [io.quarkus.agroal.runtime.DataSources] (main) Datasource <default> enables XA but transaction recovery is not enabled. Please enable transaction recovery by setting quarkus.transaction-manager.enable-recovery=true, otherwise data may be lost if the application is terminated abruptly
2025-02-17 09:26:40 2025-02-17 08:26:40,882 WARN  [org.infinispan.PERSISTENCE] (keycloak-cache-init) ISPN000554: jboss-marshalling is deprecated and planned for removal
2025-02-17 09:26:41 2025-02-17 08:26:41,413 WARN  [org.infinispan.CONFIG] (keycloak-cache-init) ISPN000569: Unable to persist Infinispan internal caches as no global state enabled
2025-02-17 09:26:42 2025-02-17 08:26:42,380 INFO  [org.infinispan.CONTAINER] (keycloak-cache-init) ISPN000556: Starting user marshaller 'org.infinispan.jboss.marshalling.core.JBossUserMarshaller'
2025-02-17 09:26:45 2025-02-17 08:26:45,725 INFO  [org.keycloak.broker.provider.AbstractIdentityProviderMapper] (main) Registering class org.keycloak.broker.provider.mappersync.ConfigSyncEventListener
2025-02-17 09:26:46 2025-02-17 08:26:46,097 INFO  [org.keycloak.connections.infinispan.DefaultInfinispanConnectionProviderFactory] (main) Node name: node_197570, Site name: null
2025-02-17 09:26:54 2025-02-17 08:26:54,054 INFO  [org.keycloak.exportimport.dir.DirImportProvider] (main) Importing from directory /opt/keycloak/bin/../data/import
2025-02-17 09:26:54 2025-02-17 08:26:54,058 INFO  [org.keycloak.services] (main) KC-SERVICES0030: Full model import requested. Strategy: IGNORE_EXISTING
2025-02-17 09:26:55 2025-02-17 08:26:55,693 INFO  [org.keycloak.exportimport.util.ImportUtils] (main) Realm 'myrealm' already exists. Import skipped
2025-02-17 09:26:55 2025-02-17 08:26:55,753 INFO  [org.keycloak.services] (main) KC-SERVICES0032: Import finished successfully
2025-02-17 09:26:57 2025-02-17 08:26:57,480 INFO  [io.quarkus] (main) Keycloak 22.0.5 on JVM (powered by Quarkus 3.2.7.Final) started in 49.097s. Listening on: http://0.0.0.0:8080
2025-02-17 09:26:57 2025-02-17 08:26:57,484 INFO  [io.quarkus] (main) Profile dev activated. 
2025-02-17 09:26:57 2025-02-17 08:26:57,485 INFO  [io.quarkus] (main) Installed features: [agroal, cdi, hibernate-orm, jdbc-h2, jdbc-mariadb, jdbc-mssql, jdbc-mysql, jdbc-oracle, jdbc-postgresql, keycloak, logging-gelf, micrometer, narayana-jta, reactive-routes, resteasy, resteasy-jackson, smallrye-context-propagation, smallrye-health, vertx]
2025-02-17 09:26:57 2025-02-17 08:26:57,521 WARN  [org.keycloak.quarkus.runtime.KeycloakMain] (main) Running the server in development mode. DO NOT use this configuration in production.

keycloak-db

2025-02-17 09:25:38 2025-02-17 08:25:38.754 UTC [1] LOG:  starting PostgreSQL 14.15 (Debian 14.15-1.pgdg120+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 12.2.0-14) 12.2.0, 64-bit
2025-02-17 09:25:38 2025-02-17 08:25:38.758 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2025-02-17 09:25:38 2025-02-17 08:25:38.759 UTC [1] LOG:  listening on IPv6 address "::", port 5432
2025-02-17 09:25:38 2025-02-17 08:25:38.765 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2025-02-17 09:25:38 2025-02-17 08:25:38.816 UTC [67] LOG:  database system was shut down at 2025-02-12 14:57:26 UTC
2025-02-17 09:25:38 2025-02-17 08:25:38.874 UTC [1] LOG:  database system is ready to accept connections

song-db

2025-02-17 09:25:40 2025-02-17 08:25:40.099 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2025-02-17 09:25:40 2025-02-17 08:25:40.100 UTC [1] LOG:  listening on IPv6 address "::", port 5432
2025-02-17 09:25:40 2025-02-17 08:25:40.105 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2025-02-17 09:25:40 2025-02-17 08:25:40.179 UTC [55] LOG:  database system was shut down at 2025-02-12 14:57:16 UTC
2025-02-17 09:25:40 2025-02-17 08:25:40.220 UTC [1] LOG:  database system is ready to accept connections

song

no logs