Closed vigamage closed 1 month ago
michaelmosmann
commented
2 months ago @vigamage .. hmm.. strange.. the log shows that the mongodb starts.. but i can not see something like "listening to port... " which is expected... strange. Do you have any log from a successful test run?
michaelmosmann
commented
2 months ago @vigamage i guess it is a timeout issue.. it will wait 20s for a successfull start message.. i will make this configurable and provide a better error message
vigamage
commented
2 months ago @michaelmosmann Here is a extract from a success log
2024-05-10 08:22:01.540 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] .h.s.i.SessionFactoryServiceRegistryImpl : EventListenerRegistry access via ServiceRegistry is deprecated. Use `sessionFactory.getEventEngine().getListenerRegistry()` instead
2024-05-10 08:22:01.542 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] h.e.i.EntityCopyObserverFactoryInitiator : Configured EntityCopyObserver strategy: disallow
2024-05-10 08:22:01.680 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.boot.internal.ClassLoaderAccessImpl : Not known whether passed class name [myapp.CustomerCreateData] is safe
2024-05-10 08:22:01.680 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.boot.internal.ClassLoaderAccessImpl : No temp ClassLoader provided; using live ClassLoader for loading potentially unsafe class : myapp.CustomerCreateData
2024-05-10 08:22:01.718 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.b.i.bytebuddy.BytecodeProviderImpl : HHH000513: Unable to create the ReflectionOptimizer for [myapp.CustomerCreateData]: private accessor [CreatedAt]
2024-05-10 08:22:01.721 INFO [service : myapp, trace_id : ] 113 --- [ main] [] o.h.m.i.EntityInstantiatorPojoStandard : HHH000182: No default (no-argument) constructor for class: myapp.CustomerCreateData (class must be instantiated by Interceptor)
2024-05-10 08:22:01.789 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.hibernate.orm.model.mapping.creation : Starting post-init callbacks
2024-05-10 08:22:01.789 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.hibernate.orm.model.mapping.creation : Starting PostInitCallbackEntry : Entity(myapp.CustomerCreateData) `staticFetchableList` generator
2024-05-10 08:22:02.099 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.orm.sql.ast.create : Created new SQL alias : ccd1_0
2024-05-10 08:22:02.103 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.orm.sql.ast.create : Registration of TableGroup [StandardTableGroup(myapp.CustomerCreateData)] with identifierForTableGroup [myapp.CustomerCreateData] for NavigablePath [myapp.CustomerCreateData]
2024-05-10 08:22:02.148 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.orm.results.graph.AST : DomainResult Graph:
\-EntityResultImpl [myapp.CustomerCreateData]
| +-BasicFetch [myapp.CustomerCreateData.CreatedAt]
| +-BasicFetch [myapp.CustomerCreateData.event]
| +-BasicFetch [myapp.CustomerCreateData.requestId]
| \-BasicFetch [myapp.CustomerCreateData.result]
2024-05-10 08:22:02.152 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.orm.sql.ast.tree : SQL AST Tree:
SelectStatement {
FromClause {
StandardTableGroup (ccd1 : myapp.CustomerCreateData) {
primaryTableReference : myapp_event as ccd1_0
}
}
}
2024-05-10 08:22:02.164 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.p.entity.AbstractEntityPersister : Static SQL for entity: myapp.CustomerCreateData
2024-05-10 08:22:02.164 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.p.entity.AbstractEntityPersister : Version select: select id from myapp_event where id=?
2024-05-10 08:22:02.165 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.p.entity.AbstractEntityPersister : Insert (0): insert into myapp_event (created_at,event,request_id,result,id) values (?,?,?,?,?)
2024-05-10 08:22:02.165 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.p.entity.AbstractEntityPersister : Update (0): update myapp_event set created_at=?,event=?,request_id=?,result=? where id=?
2024-05-10 08:22:02.165 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.p.entity.AbstractEntityPersister : Delete (0): delete from myapp_event where id=?
2024-05-10 08:22:02.198 INFO [service : myapp, trace_id : ] 113 --- [ main] [] o.h.e.t.j.p.i.JtaPlatformInitiator : HHH000489: No JTA platform available (set 'hibernate.transaction.jta.platform' to enable JTA platform integration)
2024-05-10 08:22:02.280 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.type.spi.TypeConfiguration$Scope : Scoping TypeConfiguration [org.hibernate.type.spi.TypeConfiguration@59176e9b] to SessionFactoryImplementor [org.hibernate.internal.SessionFactoryImpl@59d3495a]
2024-05-10 08:22:02.282 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.query.named.NamedObjectRepository : Checking 0 named HQL queries
2024-05-10 08:22:02.282 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.query.named.NamedObjectRepository : Checking 0 named SQL queries
2024-05-10 08:22:02.309 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.SQL : drop table if exists myapp_event cascade
2024-05-10 08:22:02.328 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.hibernate.SQL : create table myapp_event (created_at timestamp(6), event varchar(255), id varchar(255) not null, request_id varchar(255), result varchar(255), primary key (id))
2024-05-10 08:22:02.344 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.internal.SessionFactoryRegistry : Initializing SessionFactoryRegistry : org.hibernate.internal.SessionFactoryRegistry@54e71fa5
2024-05-10 08:22:02.345 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.internal.SessionFactoryRegistry : Registering SessionFactory: 1362b182-44cb-4613-b0cf-69965729b430 (<unnamed>)
2024-05-10 08:22:02.345 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.h.internal.SessionFactoryRegistry : Not binding SessionFactory to JNDI, no JNDI name configured
2024-05-10 08:22:02.345 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.hibernate.internal.SessionFactoryImpl : Instantiated SessionFactory
2024-05-10 08:22:02.345 INFO [service : myapp, trace_id : ] 113 --- [ main] [] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2024-05-10 08:22:02.361 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'objectCreationUtils'
2024-05-10 08:22:02.369 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'healthCheckController'
2024-05-10 08:22:02.375 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'globalExceptionHandler'
2024-05-10 08:22:02.382 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'customerController'
2024-05-10 08:22:02.387 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'customerServiceImpl'
2024-05-10 08:22:02.400 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'packageOverrideService'
2024-05-10 08:22:02.401 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'packageOverrideService' via constructor to bean named 'catalogAdaptorImpl'
2024-05-10 08:22:02.405 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'customerAccountService'
2024-05-10 08:22:02.406 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'myappEventDataRepository'
2024-05-10 08:22:02.409 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongo.named-queries#1'
2024-05-10 08:22:02.414 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongodb.myappEventDataRepository.fragments#0'
2024-05-10 08:22:02.427 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoTemplate'
2024-05-10 08:22:02.427 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.data.mongo.MongoDatabaseFactoryDependentConfiguration'
2024-05-10 08:22:02.429 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoDatabaseFactory'
2024-05-10 08:22:02.429 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.data.mongo.MongoDatabaseFactoryConfiguration'
2024-05-10 08:22:02.431 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'syncClientServerWrapper'
2024-05-10 08:22:02.431 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoAutoConfiguration$SyncClientServerWrapperConfig'
2024-05-10 08:22:02.433 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'version'
2024-05-10 08:22:02.434 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'version' via factory method to bean named 'de.flapdoodle.mongodb.embedded-de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoProperties'
2024-05-10 08:22:02.460 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongod'
2024-05-10 08:22:02.461 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongodArguments'
2024-05-10 08:22:02.481 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'processOutput'
2024-05-10 08:22:02.496 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'progressListener'
2024-05-10 08:22:02.501 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongod' via factory method to bean named 'mongodArguments'
2024-05-10 08:22:02.502 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongod' via factory method to bean named 'processOutput'
2024-05-10 08:22:02.502 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongod' via factory method to bean named 'net'
2024-05-10 08:22:02.502 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongod' via factory method to bean named 'progressListener'
2024-05-10 08:22:02.502 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongod' via factory method to bean named 'de.flapdoodle.mongodb.embedded-de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoProperties'
2024-05-10 08:22:02.616 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'syncClientServerWrapper' via factory method to bean named 'version'
2024-05-10 08:22:02.616 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'syncClientServerWrapper' via factory method to bean named 'spring.data.mongodb-org.springframework.boot.autoconfigure.mongo.MongoProperties'
2024-05-10 08:22:02.617 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'syncClientServerWrapper' via factory method to bean named 'mongod'
2024-05-10 08:22:02.617 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'syncClientServerWrapper' via factory method to bean named 'mongodArguments'
2024-05-10 08:22:02.680 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.a.SyncClientServerFactory : sync server factory
2024-05-10 08:22:04.009 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : starting...
2024-05-10 08:22:04.013 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 0 %
2024-05-10 08:22:04.254 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 10 %
2024-05-10 08:22:04.451 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 20 %
2024-05-10 08:22:04.558 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 30 %
2024-05-10 08:22:04.623 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 40 %
2024-05-10 08:22:04.672 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 50 %
2024-05-10 08:22:04.722 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 60 %
2024-05-10 08:22:04.785 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 70 %
2024-05-10 08:22:04.917 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 80 %
2024-05-10 08:22:04.951 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : 90 %
2024-05-10 08:22:04.987 INFO [service : myapp, trace_id : ] 113 --- [ main] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz : finished
2024-05-10 08:22:07.330 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.329+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":17},"outgoing":{"minWireVersion":6,"maxWireVersion":17},"isInternalClient":true}}}
2024-05-10 08:22:07.330 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.329+00:00"},"s":"I", "c":"CONTROL", "id":23285, "ctx":"main","msg":"Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
2024-05-10 08:22:07.330 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.329+00:00"},"s":"I", "c":"NETWORK", "id":4648601, "ctx":"main","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize."}
2024-05-10 08:22:07.331 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","namespace":"config.tenantMigrationDonors"}}
2024-05-10 08:22:07.331 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","namespace":"config.tenantMigrationRecipients"}}
2024-05-10 08:22:07.331 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"ShardSplitDonorService","namespace":"config.tenantSplitDonors"}}
2024-05-10 08:22:07.332 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
2024-05-10 08:22:07.332 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":209,"port":35935,"dbPath":"/tmp/temp--dd1d2c7c-3ffd-4eac-9826-b69ffbd49d1c/mongod-database8580490160203605844","architecture":"64-bit","host":"runner-2-hts8s7x-project-53685688-concurrent-0-9s0l54vn"}}
2024-05-10 08:22:07.332 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.331+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"6.0.4","gitVersion":"44ff59461c1353638a71e710f385a566bcd2f547","openSSLVersion":"OpenSSL 1.1.1n 15 Mar 2022","modules":[],"allocator":"tcmalloc","environment":{"distmod":"debian11","distarch":"x86_64","target_arch":"x86_64"}}}}
2024-05-10 08:22:07.332 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.332+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"PRETTY_NAME=\"Debian GNU/Linux 11 (bullseye)\"","version":"Kernel 5.15.146+"}}}
2024-05-10 08:22:07.332 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.332+00:00"},"s":"I", "c":"CONTROL", "id":21951, "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"net":{"bindIp":"127.0.0.1","port":35935},"security":{"authorization":"disabled"},"storage":{"dbPath":"/tmp/temp--dd1d2c7c-3ffd-4eac-9826-b69ffbd49d1c/mongod-database8580490160203605844","journal":{"enabled":false},"syncPeriodSecs":0}}}}
2024-05-10 08:22:07.333 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:07.333+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=15541M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,remove=true,path=journal,compressor=snappy),builtin_extension_config=(zstd=(compression_level=6)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,backup:0,checkpoint:0,compact:0,evict:0,history_store:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],,log=(enabled=false),"}}
2024-05-10 08:22:08.540 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.540+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":1207}}
2024-05-10 08:22:08.540 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.540+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2024-05-10 08:22:08.576 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.576+00:00"},"s":"W", "c":"CONTROL", "id":22138, "ctx":"initandlisten","msg":"You are running this process as the root user, which is not recommended","tags":["startupWarnings"]}
2024-05-10 08:22:08.577 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.576+00:00"},"s":"W", "c":"CONTROL", "id":5123300, "ctx":"initandlisten","msg":"vm.max_map_count is too low","attr":{"currentValue":65530,"recommendedMinimum":1677720,"maxConns":838860},"tags":["startupWarnings"]}
2024-05-10 08:22:08.577 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.576+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"admin.system.version","uuidDisposition":"provided","uuid":{"uuid":{"$uuid":"e2e3cb91-be7b-4a25-9b18-48b26829843c"}},"options":{"uuid":{"$uuid":"e2e3cb91-be7b-4a25-9b18-48b26829843c"}}}}
2024-05-10 08:22:08.597 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.596+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"e2e3cb91-be7b-4a25-9b18-48b26829843c"}},"namespace":"admin.system.version","index":"_id_","ident":"index-1-1052391394066743884","collectionIdent":"collection-0-1052391394066743884","commitTimestamp":null}}
2024-05-10 08:22:08.598 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.597+00:00"},"s":"I", "c":"REPL", "id":20459, "ctx":"initandlisten","msg":"Setting featureCompatibilityVersion","attr":{"newVersion":"6.0"}}
2024-05-10 08:22:08.598 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"setFCV"}}
2024-05-10 08:22:08.598 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":17},"outgoing":{"minWireVersion":6,"maxWireVersion":17},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true}}}
2024-05-10 08:22:08.599 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true}}}
2024-05-10 08:22:08.599 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"startup"}}
2024-05-10 08:22:08.599 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
2024-05-10 08:22:08.599 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
2024-05-10 08:22:08.599 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.598+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tmp/temp--dd1d2c7c-3ffd-4eac-9826-b69ffbd49d1c/mongod-database8580490160203605844/diagnostic.data"}}
2024-05-10 08:22:08.601 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.600+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"2738d9fb-7e40-4774-8fca-eeb0a4cdca2d"}},"options":{"capped":true,"size":10485760}}}
2024-05-10 08:22:08.771 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.770+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"2738d9fb-7e40-4774-8fca-eeb0a4cdca2d"}},"namespace":"local.startup_log","index":"_id_","ident":"index-3-1052391394066743884","collectionIdent":"collection-2-1052391394066743884","commitTimestamp":null}}
2024-05-10 08:22:08.771 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.771+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigReplicationDisabled","oldState":"ConfigPreStart"}}
2024-05-10 08:22:08.776 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.771+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"}
2024-05-10 08:22:08.776 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.776+00:00"},"s":"I", "c":"CONTROL", "id":20712, "ctx":"LogicalSessionCacheReap","msg":"Sessions collection is not set up; waiting until next sessions reap interval","attr":{"error":"NamespaceNotFound: config.system.sessions does not exist"}}
2024-05-10 08:22:08.777 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.777+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-35935.sock"}}
2024-05-10 08:22:08.777 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.777+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}}
2024-05-10 08:22:08.777 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.777+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":35935,"ssl":"off"}}
2024-05-10 08:22:08.777 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.777+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"a0acc523-d09f-4816-9200-a208068c2fd3"}},"options":{}}}
2024-05-10 08:22:08.782 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongo'
2024-05-10 08:22:08.784 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.mongo.MongoAutoConfiguration'
2024-05-10 08:22:08.797 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoClientSettings'
2024-05-10 08:22:08.797 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.mongo.MongoAutoConfiguration$MongoClientSettingsConfiguration'
2024-05-10 08:22:08.899 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongo' via factory method to bean named 'mongoClientSettings'
2024-05-10 08:22:08.915 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoMetricsConnectionPoolListenerClientSettingsBuilderCustomizer'
2024-05-10 08:22:08.915 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.actuate.autoconfigure.metrics.mongo.MongoMetricsAutoConfiguration$MongoConnectionPoolMetricsConfiguration'
2024-05-10 08:22:08.919 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoMetricsConnectionPoolListener'
2024-05-10 08:22:08.923 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoConnectionPoolTagsProvider'
2024-05-10 08:22:08.926 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsConnectionPoolListener' via factory method to bean named 'simpleMeterRegistry'
2024-05-10 08:22:08.926 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsConnectionPoolListener' via factory method to bean named 'mongoConnectionPoolTagsProvider'
2024-05-10 08:22:08.938 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsConnectionPoolListenerClientSettingsBuilderCustomizer' via factory method to bean named 'mongoMetricsConnectionPoolListener'
2024-05-10 08:22:08.941 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoMetricsCommandListenerClientSettingsBuilderCustomizer'
2024-05-10 08:22:08.941 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'org.springframework.boot.actuate.autoconfigure.metrics.mongo.MongoMetricsAutoConfiguration$MongoCommandMetricsConfiguration'
2024-05-10 08:22:08.945 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoMetricsCommandListener'
2024-05-10 08:22:08.946 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoCommandTagsProvider'
2024-05-10 08:22:08.952 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsCommandListener' via factory method to bean named 'simpleMeterRegistry'
2024-05-10 08:22:08.952 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsCommandListener' via factory method to bean named 'mongoCommandTagsProvider'
2024-05-10 08:22:08.957 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoMetricsCommandListenerClientSettingsBuilderCustomizer' via factory method to bean named 'mongoMetricsCommandListener'
2024-05-10 08:22:08.959 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.958+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"a0acc523-d09f-4816-9200-a208068c2fd3"}},"namespace":"config.system.sessions","index":"_id_","ident":"index-5-1052391394066743884","collectionIdent":"collection-4-1052391394066743884","commitTimestamp":null}}
2024-05-10 08:22:08.959 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.958+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"a0acc523-d09f-4816-9200-a208068c2fd3"}},"namespace":"config.system.sessions","index":"lsidTTLIndex","ident":"index-6-1052391394066743884","collectionIdent":"collection-4-1052391394066743884","commitTimestamp":null}}
2024-05-10 08:22:08.959 INFO [service : myapp, trace_id : ] 113 --- [ Thread-3] [] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2024-05-10T08:22:08.958+00:00"},"s":"I", "c":"COMMAND", "id":51803, "ctx":"LogicalSessionCacheRefresh","msg":"Slow query","attr":{"type":"command","ns":"config.system.sessions","command":{"createIndexes":"system.sessions","v":2,"indexes":[{"key":{"lastUse":1},"name":"lsidTTLIndex","expireAfterSeconds":1800}],"ignoreUnknownIndexOptions":false,"writeConcern":{},"$db":"config"},"numYields":0,"reslen":114,"locks":{"ParallelBatchWriterMode":{"acquireCount":{"r":5}},"FeatureCompatibilityVersion":{"acquireCount":{"r":5,"w":1}},"ReplicationStateTransition":{"acquireCount":{"w":5}},"Global":{"acquireCount":{"r":5,"w":1}},"Database":{"acquireCount":{"r":4,"w":1}},"Collection":{"acquireCount":{"r":5,"w":1}},"Mutex":{"acquireCount":{"r":8}}},"storage":{},"protocol":"op_msg","durationMillis":181}}
2024-05-10 08:22:08.960 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'standardMongoSettingsCustomizer'
2024-05-10 08:22:08.962 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Creating shared instance of singleton bean 'mongoConnectionDetails'
2024-05-10 08:22:08.962 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'mongoConnectionDetails' via factory method to bean named 'spring.data.mongodb-org.springframework.boot.autoconfigure.mongo.MongoProperties'
2024-05-10 08:22:08.970 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'standardMongoSettingsCustomizer' via factory method to bean named 'spring.data.mongodb-org.springframework.boot.autoconfigure.mongo.MongoProperties'
2024-05-10 08:22:08.970 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] o.s.b.f.s.DefaultListableBeanFactory : Autowiring by type from bean name 'standardMongoSettingsCustomizer' via factory method to bean named 'mongoConnectionDetails'
2024-05-10 08:22:09.070 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.mongodb.driver.connection : Connection pool created for localhost:35935 using options maxIdleTimeMS=0, minPoolSize=0, maxPoolSize=100, maxConnecting=2, waitQueueTimeoutMS=120000
2024-05-10 08:22:09.094 DEBUG [service : myapp, trace_id : ] 113 --- [ main] [] org.mongodb.driver.cluster : Updating cluster description to {type=UNKNOWN, servers=[{address=localhost:35935, type=UNKNOWN, state=CONNECTING}]
2024-05-10 08:22:09.111 INFO [service : myapp, trace_id : ] 113 --- [I cannot see listening to port... there either
vigamage
commented
2 months ago @michaelmosmann Any workaround you can think of until we have a proper solution ?
vigamage
commented
2 months ago @michaelmosmann Do we have any clue from the logs on why it does not start within 20 seconds on Debian GNU/Linux 11
michaelmosmann
commented
2 months ago @vigamage its ""ctx":"listener","msg":"Waiting for connections","attr":{"port":35935,"ssl":"off"}}"
michaelmosmann
commented
2 months ago @vigamage .. i can not imagine a work around.. i will fix this soon (if thats the problem)
michaelmosmann
commented
2 months ago @vigamage i made a new release of de.flapdoodle.embed.mongo with version 4.13.1 where you can customize the startup timeout (see https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo/blob/main/docs/Customizations.md#customize-starttimeout) .. and increased it from 20s to 30s. Will be in maven central in some hours...
vigamage
commented
2 months ago @michaelmosmann Can it be controlled from the property file or do I have to create a customised bean for that? In the current implementation, I have not done any coding as shown in the link you provided. I have just added the embed.mongo as a dependency in the test scope and have provided the connection config in the property file. Any customisations needed ?
Thanks
vigamage
commented
2 months ago @michaelmosmann I tested it out and realized that the timeout has been defaulted to 30 seconds.
However, even with that, I get the following error.
Caused by: java.lang.RuntimeException:
Could not start process:
----------------------
Hmm.. no failure or success message after 30000ms ..
...the cause must be somewhere in the process output:
- 8<- - - - - - - - - -
{"t":{"$date":"2024-05-13T03:22:41.772+00:00"},"s":"I", "c":"CONTROL", "id":23285, "ctx":"-","msg":"Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
{"t":{"$date":"2024-05-13T03:22:41.773+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"-","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":17},"outgoing":{"minWireVersion":6,"maxWireVersion":17},"isInternalClient":true}}}
{"t":{"$date":"2024-05-13T03:22:41.774+00:00"},"s":"I", "c":"NETWORK", "id":4648601, "ctx":"main","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize."}
{"t":{"$date":"2024-05-13T03:22:41.776+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","namespace":"config.tenantMigrationDonors"}}
{"t":{"$date":"2024-05-13T03:22:41.776+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","namespace":"config.tenantMigrationRecipients"}}
{"t":{"$date":"2024-05-13T03:22:41.776+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"ShardSplitDonorService","namespace":"config.tenantSplitDonors"}}
{"t":{"$date":"2024-05-13T03:22:41.776+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
{"t":{"$date":"2024-05-13T03:22:41.777+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":251,"port":36903,"dbPath":"/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801","architecture":"64-bit","host":"runner-2-hts8s7x-project-53685688-concurrent-6-8g3bh1r8"}}
{"t":{"$date":"2024-05-13T03:22:41.777+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"6.0.4","gitVersion":"44ff59461c1353638a71e710f385a566bcd2f547","openSSLVersion":"OpenSSL 1.1.1n 15 Mar 2022","modules":[],"allocator":"tcmalloc","environment":{"distmod":"debian11","distarch":"x86_64","target_arch":"x86_64"}}}}
{"t":{"$date":"2024-05-13T03:22:41.777+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"PRETTY_NAME=\"Debian GNU/Linux 11 (bullseye)\"","version":"Kernel 5.15.146+"}}}
{"t":{"$date":"2024-05-13T03:22:41.777+00:00"},"s":"I", "c":"CONTROL", "id":21951, "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"net":{"bindIp":"127.0.0.1","port":36903},"security":{"authorization":"disabled"},"storage":{"dbPath":"/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801","journal":{"enabled":false},"syncPeriodSecs":0}}}}
{"t":{"$date":"2024-05-13T03:22:41.778+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=15541M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,remove=true,path=journal,compressor=snappy),builtin_extension_config=(zstd=(compression_level=6)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,backup:0,checkpoint:0,compact:0,evict:0,history_store:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],,log=(enabled=false),"}}
{"t":{"$date":"2024-05-13T03:22:48.902+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":7124}}
{"t":{"$date":"2024-05-13T03:22:48.902+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
{"t":{"$date":"2024-05-13T03:22:53.465+00:00"},"s":"W", "c":"CONTROL", "id":22138, "ctx":"initandlisten","msg":"You are running this process as the root user, which is not recommended","tags":["startupWarnings"]}
{"t":{"$date":"2024-05-13T03:22:53.465+00:00"},"s":"W", "c":"CONTROL", "id":5123300, "ctx":"initandlisten","msg":"vm.max_map_count is too low","attr":{"currentValue":65530,"recommendedMinimum":1677720,"maxConns":838860},"tags":["startupWarnings"]}
{"t":{"$date":"2024-05-13T03:22:53.465+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"admin.system.version","uuidDisposition":"provided","uuid":{"uuid":{"$uuid":"9546a248-f299-45ac-95c2-35ff3c3cbf66"}},"options":{"uuid":{"$uuid":"9546a248-f299-45ac-95c2-35ff3c3cbf66"}}}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"9546a248-f299-45ac-95c2-35ff3c3cbf66"}},"namespace":"admin.system.version","index":"_id_","ident":"index-1-4600591093588758143","collectionIdent":"collection-0-4600591093588758143","commitTimestamp":null}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"REPL", "id":20459, "ctx":"initandlisten","msg":"Setting featureCompatibilityVersion","attr":{"newVersion":"6.0"}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"setFCV"}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":17},"outgoing":{"minWireVersion":6,"maxWireVersion":17},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true}}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":17},"incomingInternalClient":{"minWireVersion":17,"maxWireVersion":17},"outgoing":{"minWireVersion":17,"maxWireVersion":17},"isInternalClient":true}}}
{"t":{"$date":"2024-05-13T03:23:05.655+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"startup"}}
{"t":{"$date":"2024-05-13T03:23:05.767+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
{"t":{"$date":"2024-05-13T03:23:05.767+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
{"t":{"$date":"2024-05-13T03:23:05.767+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801/diagnostic.data"}}
{"t":{"$date":"2024-05-13T03:23:05.769+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"fd3df703-f2c8-4031-8e2a-1b30ec88329c"}},"options":{"capped":true,"size":10485760}}}
- >8- - - - - - - - - -Caused by: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'mongoTemplate' defined in class path resource [org/springframework/boot/autoconfigure/data/mongo/MongoDatabaseFactoryDependentConfiguration.class]: Unsatisfied dependency expressed through method 'mongoTemplate' parameter 0: Error creating bean with name 'mongoDatabaseFactory' defined in class path resource [org/springframework/boot/autoconfigure/data/mongo/MongoDatabaseFactoryConfiguration.class]: Unsatisfied dependency expressed through method 'mongoDatabaseFactory' parameter 0: Error creating bean with name 'syncClientServerWrapper' defined in class path resource [de/flapdoodle/embed/mongo/spring/autoconfigure/EmbeddedMongoAutoConfiguration$SyncClientServerWrapperConfig.class]: rollback after error on transition to State(RunningMongodProcess), successful reached:
State(ExtractedFileSet)=ExtractedFileSet{baseDir=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af, executable=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af/mongod, libraryFiles=[]},
State(Archive)=Archive(/root/.embedmongo/archives/https-fastdl-mongodb-org/56cb3d10404e5b9df5358ac4ee83efcb/linuxmongodb-linux-x86-64-debian11-6-0-4-tgz/2630c6d7a299b198a1e958029b6d9932e6d49647fd870929c6146ba4007a87b1/archive.tgz),
State(DownloadCache)=de.flapdoodle.embed.process.store.LocalDownloadCache@61f9e861,
State(Package)=Package{archiveType=TGZ, fileSet=FileSet{entries=[Entry{type=Executable, destination=mongod, uncompiledMatchingPattern=UncompiledPattern{regex=.*mongod, flags=2}}]}, url=https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz},
State(ExtractedFileSetStore)=de.flapdoodle.embed.process.store.ContentHashExtractedFileSetStore@12d54905,
State(ProcessArguments)=ProcessArguments([--dbpath, /tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801, --noauth, --nojournal, --port, 36903, --bind_ip, 127.0.0.1, --syncdelay=0]),
State(ProcessWorkingDir)=ProcessWorkingDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/workingDir145564083381994544),
State(Distribution)=GenericFeatureAwareVersion{6.0.4}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(SupportConfig)=SupportConfig{name=mongod, supportUrl=https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo/issues, messageOnException=de.flapdoodle.embed.mongo.transitions.ProcessDefaults$$Lambda$1814/0x0000000801873cd0@29bb4533},
State(Name)=Name(mongod),
State(PersistentDir)=ImmutablePersistentDir(/root/.embedmongo),
State(DatabaseDir)=DatabaseDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801),
State(TempDir)=ImmutableTempDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6),
State(DistributionBaseUrl)=DistributionBaseUrl(https://fastdl.mongodb.org),
State(Platform)=Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(ProcessConfig)=ProcessConfig{daemonProcess=false, stopTimeoutInMillis=5000},
State(ProcessEnv)=ProcessEnv({}),
State(ProcessOutput)=ProcessOutput{output=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@65def021, error=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@5aeb42b7, commands=de.flapdoodle.embed.process.io.NamedOutputStreamProcessor@200f1f0c},
State(StartTimeout)=StartTimeout(30000),
State(SystemEnv)=SystemEnv({PATH=/usr/local/openjdk-18/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,State(ProgressListener)=de.flapdoodle.embed.process.io.progress.Slf4jProgressListener@a81685b,
State(Command)=MongoD,
State(Version)=GenericFeatureAwareVersion{6.0.4},
State(Net)=Net{bindIp=127.0.0.1, port=36903, isIpv6=false},
State(MongodArguments)=MongodArguments{syncDelay=0, useDefaultSyncDelay=false, isVerbose=false, verbosityLevel=1, isQuiet=false, useNoPrealloc=true, useSmallFiles=true, useNoJournal=true, enableTextSearch=false, auth=false, master=false, isConfigServer=false, isShardServer=false, params={}, args={}}
at org.springframework.beans.factory.support.ConstructorResolver.createArgumentArray(ConstructorResolver.java:798)
at org.springframework.beans.factory.support.ConstructorResolver.instantiateUsingFactoryMethod(ConstructorResolver.java:542)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateUsingFactoryMethod(AbstractAutowireCapableBeanFactory.java:1335)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1165)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:562)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:522)
at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:325)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:323)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:199)
at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:254)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1443)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1353)
at org.springframework.beans.factory.support.ConstructorResolver.resolveAutowiredArgument(ConstructorResolver.java:907)
at org.springframework.beans.factory.support.ConstructorResolver.createArgumentArray(ConstructorResolver.java:785)
... 150 common frames omitted
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'syncClientServerWrapper' defined in class path resource [de/flapdoodle/embed/mongo/spring/autoconfigure/EmbeddedMongoAutoConfiguration$SyncClientServerWrapperConfig.class]: rollback after error on transition to State(RunningMongodProcess), successful reached:
State(ExtractedFileSet)=ExtractedFileSet{baseDir=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af, executable=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af/mongod, libraryFiles=[]},
State(Archive)=Archive(/root/.embedmongo/archives/https-fastdl-mongodb-org/56cb3d10404e5b9df5358ac4ee83efcb/linuxmongodb-linux-x86-64-debian11-6-0-4-tgz/2630c6d7a299b198a1e958029b6d9932e6d49647fd870929c6146ba4007a87b1/archive.tgz),
State(DownloadCache)=de.flapdoodle.embed.process.store.LocalDownloadCache@61f9e861,
State(Package)=Package{archiveType=TGZ, fileSet=FileSet{entries=[Entry{type=Executable, destination=mongod, uncompiledMatchingPattern=UncompiledPattern{regex=.*mongod, flags=2}}]}, url=https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz},
State(ExtractedFileSetStore)=de.flapdoodle.embed.process.store.ContentHashExtractedFileSetStore@12d54905,
State(ProcessArguments)=ProcessArguments([--dbpath, /tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801, --noauth, --nojournal, --port, 36903, --bind_ip, 127.0.0.1, --syncdelay=0]),
State(ProcessWorkingDir)=ProcessWorkingDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/workingDir145564083381994544),
State(Distribution)=GenericFeatureAwareVersion{6.0.4}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(SupportConfig)=SupportConfig{name=mongod, supportUrl=https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo/issues, messageOnException=de.flapdoodle.embed.mongo.transitions.ProcessDefaults$$Lambda$1814/0x0000000801873cd0@29bb4533},
State(Name)=Name(mongod),
State(PersistentDir)=ImmutablePersistentDir(/root/.embedmongo),
State(DatabaseDir)=DatabaseDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801),
State(TempDir)=ImmutableTempDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6),
State(DistributionBaseUrl)=DistributionBaseUrl(https://fastdl.mongodb.org),
State(Platform)=Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(ProcessConfig)=ProcessConfig{daemonProcess=false, stopTimeoutInMillis=5000},
State(ProcessEnv)=ProcessEnv({}),
State(ProcessOutput)=ProcessOutput{output=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@65def021, error=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@5aeb42b7, commands=de.flapdoodle.embed.process.io.NamedOutputStreamProcessor@200f1f0c},
State(StartTimeout)=StartTimeout(30000),
State(SystemEnv)=SystemEnv({PATH=/usr/local/openjdk-18/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin,State(ProgressListener)=de.flapdoodle.embed.process.io.progress.Slf4jProgressListener@a81685b,
State(Command)=MongoD,
State(Version)=GenericFeatureAwareVersion{6.0.4},
State(Net)=Net{bindIp=127.0.0.1, port=36903, isIpv6=false},
State(MongodArguments)=MongodArguments{syncDelay=0, useDefaultSyncDelay=false, isVerbose=false, verbosityLevel=1, isQuiet=false, useNoPrealloc=true, useSmallFiles=true, useNoJournal=true, enableTextSearch=false, auth=false, master=false, isConfigServer=false, isShardServer=false, params={}, args={}}
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1786)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:600)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:522)
at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:325)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:323)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:199)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:312)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:199)
at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:254)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1443)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1353)
at org.springframework.beans.factory.support.ConstructorResolver.resolveAutowiredArgument(ConstructorResolver.java:907)
at org.springframework.beans.factory.support.ConstructorResolver.createArgumentArray(ConstructorResolver.java:785)
... 164 common frames omitted
Caused by: java.lang.RuntimeException: rollback after error on transition to State(RunningMongodProcess), successful reached:
State(ExtractedFileSet)=ExtractedFileSet{baseDir=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af, executable=/root/.embedmongo/fileSets/033637a5fbeb90c16a89c186c9ed64370dfd0b834bcc7d000dc509d3d0bdf6af/mongod, libraryFiles=[]},
State(Archive)=Archive(/root/.embedmongo/archives/https-fastdl-mongodb-org/56cb3d10404e5b9df5358ac4ee83efcb/linuxmongodb-linux-x86-64-debian11-6-0-4-tgz/2630c6d7a299b198a1e958029b6d9932e6d49647fd870929c6146ba4007a87b1/archive.tgz),
State(DownloadCache)=de.flapdoodle.embed.process.store.LocalDownloadCache@61f9e861,
State(Package)=Package{archiveType=TGZ, fileSet=FileSet{entries=[Entry{type=Executable, destination=mongod, uncompiledMatchingPattern=UncompiledPattern{regex=.*mongod, flags=2}}]}, url=https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-debian11-6.0.4.tgz},
State(ExtractedFileSetStore)=de.flapdoodle.embed.process.store.ContentHashExtractedFileSetStore@12d54905,
State(ProcessArguments)=ProcessArguments([--dbpath, /tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801, --noauth, --nojournal, --port, 36903, --bind_ip, 127.0.0.1, --syncdelay=0]),
State(ProcessWorkingDir)=ProcessWorkingDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/workingDir145564083381994544),
State(Distribution)=GenericFeatureAwareVersion{6.0.4}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(SupportConfig)=SupportConfig{name=mongod, supportUrl=https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo/issues, messageOnException=de.flapdoodle.embed.mongo.transitions.ProcessDefaults$$Lambda$1814/0x0000000801873cd0@29bb4533},
State(Name)=Name(mongod),
State(PersistentDir)=ImmutablePersistentDir(/root/.embedmongo),
State(DatabaseDir)=DatabaseDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6/mongod-database10732476208858549801),
State(TempDir)=ImmutableTempDir(/tmp/temp--54019f4b-f0d4-486c-b4b7-03e7f9ecadf6),
State(DistributionBaseUrl)=DistributionBaseUrl(https://fastdl.mongodb.org),
State(Platform)=Platform{operatingSystem=Linux, architecture=X86_64, distribution=Debian, version=DEBIAN_11},
State(ProcessConfig)=ProcessConfig{daemonProcess=false, stopTimeoutInMillis=5000},
State(ProcessEnv)=ProcessEnv({}),
State(ProcessOutput)=ProcessOutput{output=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@65def021, error=de.flapdoodle.embed.process.io.Slf4jStreamProcessor@5aeb42b7, commands=de.flapdoodle.embed.process.io.NamedOutputStreamProcessor@200f1f0c},
State(StartTimeout)=StartTimeout(30000),
State(SystemEnv)=SystemEnv({PATH=/usr/local/openjdk-18/bin:/usr/local/sbin:/usr/local/bin@vigamage .. hmm.. so 30s are not enough.. which is kind of interesting.. so you might try to increase the timeout.. currently there is no property for that.. but you can do this with code. This is how you customize stuff in a spring framework surrounding:
https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo.spring/blob/main/HowTo.md#customize-mongod and this is the change you need: https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo/blob/main/docs/Customizations.md#customize-starttimeout
vigamage
commented
1 month ago @michaelmosmann I customised the bean as you suggested. Now it will wait for 1 minute. I am experimenting with that in the pipeline and will observe for couple of days to see if it has fixed the issue.
Ill give an update here as soon as possible.
Thanks for your help
michaelmosmann
commented
1 month ago @vigamage i will see if i can make an property for that so that less code change is needed..
vigamage
commented
1 month ago @michaelmosmann I think we can close this now. I did not get the issue after setting the time out to 1 minute. Thank you very much for your prompt support.
michaelmosmann
commented
1 month ago @vigamage thanks.. i think i will introduce a property for that so that ( now that i know that it works:))
michaelmosmann
commented
1 month ago @vigamage there is now a property for that, see https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo.spring/blob/main/src/test/java/de/flapdoodle/embed/mongo/spring/autoconfigure/simple/CustomStartTimeoutTest.java
release is 4.14.0
vigamage
commented
1 month ago Thanks a lot @michaelmosmann This is great. appreciate your effort :)
Hi,
I am experiencing the issue mentioned in the topic when my integration tests are running in the pipeline only. Locally everything is successful.
Details of the OS setup where the tests are failing
I have created my project in a way that it complies with minimal spring applications available here. https://github.com/flapdoodle-oss/de.flapdoodle.embed.mongo.canary
However, there is no sample available there for spring boot 3.2(my version). I followed the one available there for spring boot 3.1.
In my pom file, I have these dependencies.
In my test property file, I have these entries.
Here is a log output.
Some more logs from the same output
I have made sure that only one spring context is loaded and it is cached and reused for all the test cases, and more importantly, this is a random issue. This does not happen everytime. but it will happen 7 out of 10 times. But locally, all are working.
Any help is much appriciated.
Thanks..