17:15:38.689 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Neither @ContextConfiguration nor @ContextHierarchy found for test class [CodeControllerIntegrationTest]: using SpringBootContextLoader
17:15:38.707 [Test worker] DEBUG org.springframework.test.context.support.AbstractContextLoader - Could not detect default resource locations for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest]: no resource found for suffixes {-context.xml, Context.groovy}.
17:15:38.711 [Test worker] INFO org.springframework.test.context.support.AnnotationConfigContextLoaderUtils - Could not detect default configuration classes for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest]: CodeControllerIntegrationTest does not declare any static, non-private, non-final, nested classes annotated with @Configuration.
17:15:39.965 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using ContextCustomizers for test class [CodeControllerIntegrationTest]: [MockkContextCustomizer, DisableObservabilityContextCustomizer, PropertyMappingContextCustomizer, Customizer, ImportsContextCustomizer, ExcludeFilterContextCustomizer, DuplicateJsonObjectContextCustomizer, MockitoContextCustomizer, TestRestTemplateContextCustomizer]
17:15:40.240 [Test worker] DEBUG org.springframework.context.annotation.ClassPathScanningCandidateComponentProvider - Identified candidate component class: file [/home/runner/work/codecharacter-server-2023/codecharacter-server-2023/server/build/classes/kotlin/main/delta/codecharacter/server/ServerApplication.class]
17:15:40.242 [Test worker] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Found @SpringBootConfiguration delta.codecharacter.server.ServerApplication for test class delta.codecharacter.server.code.CodeControllerIntegrationTest
17:15:40.470 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners for test class [CodeControllerIntegrationTest]: [ServletTestExecutionListener, DirtiesContextBeforeModesTestExecutionListener, ApplicationEventsTestExecutionListener, MockkTestExecutionListener, MockitoTestExecutionListener, DependencyInjectionTestExecutionListener, DirtiesContextTestExecutionListener, TransactionalTestExecutionListener, SqlScriptsTestExecutionListener, WithSecurityContextTestExecutionListener, EventPublishingTestExecutionListener, ReactorContextTestExecutionListener, ClearMocksTestExecutionListener, RestDocsTestExecutionListener, MockRestServiceServerResetTestExecutionListener, MockMvcPrintOnlyOnFailureTestExecutionListener, WebDriverTestExecutionListener, MockWebServiceServerTestExecutionListener, ResetMocksTestExecutionListener]
17:15:40.473 [Test worker] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test class: class [CodeControllerIntegrationTest], class annotated with @DirtiesContext [false] with mode [null]
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.0.0)
2023-03-13T17:15:41.472Z INFO 2500 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : Starting CodeControllerIntegrationTest using Java 17.0.6 with PID 2500 (started by runner in /home/runner/work/codecharacter-server-2023/codecharacter-server-2023/server)
2023-03-13T17:15:41.474Z INFO 2500 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : No active profile set, falling back to 1 default profile: "default"
2023-03-13T17:15:44.657Z INFO 2500 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data MongoDB repositories in DEFAULT mode.
2023-03-13T17:15:44.892Z INFO 2500 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 225 ms. Found 18 MongoDB repository interfaces.
2023-03-13T17:15:45.689Z INFO 2500 --- [ Test worker] trationDelegate$BeanPostProcessorChecker : Bean 'de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoAutoConfiguration' of type [de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoAutoConfiguration] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-03-13T17:15:45.781Z WARN 2500 --- [ Test worker] ocalVariableTableParameterNameDiscoverer : Using deprecated '-debug' fallback for parameter name resolution. Compile the affected code with '-parameters' instead or avoid its introspection: de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoAutoConfiguration
2023-03-13T17:15:45.809Z INFO 2500 --- [ Test worker] trationDelegate$BeanPostProcessorChecker : Bean 'de.flapdoodle.mongodb.embedded-de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoProperties' of type [de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoProperties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-03-13T17:15:45.826Z INFO 2500 --- [ Test worker] trationDelegate$BeanPostProcessorChecker : Bean 'spring.data.mongodb-org.springframework.boot.autoconfigure.mongo.MongoProperties' of type [org.springframework.boot.autoconfigure.mongo.MongoProperties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-03-13T17:15:45.935Z WARN 2500 --- [ Test worker] ocalVariableTableParameterNameDiscoverer : Using deprecated '-debug' fallback for parameter name resolution. Compile the affected code with '-parameters' instead or avoid its introspection: de.flapdoodle.embed.mongo.spring.autoconfigure.EmbeddedMongoAutoConfiguration$SyncClientServerWrapperConfig
2023-03-13T17:15:46.079Z INFO 2500 --- [ Test worker] d.f.e.m.s.a.SyncClientServerFactory : sync server factory
2023-03-13T17:15:47.025Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : starting...
2023-03-13T17:15:47.030Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 0 %
2023-03-13T17:15:47.376Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 10 %
2023-03-13T17:15:47.621Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 20 %
2023-03-13T17:15:47.854Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 30 %
2023-03-13T17:15:47.990Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 40 %
2023-03-13T17:15:48.080Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 50 %
2023-03-13T17:15:48.154Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 60 %
2023-03-13T17:15:48.224Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 70 %
2023-03-13T17:15:48.299Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 80 %
2023-03-13T17:15:48.367Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : 90 %
2023-03-13T17:15:48.438Z INFO 2500 --- [ Test worker] d.f.e.m.s.autoconfigure.EmbeddedMongo : download https://fastdl.mongodb.org/linux/mongodb-linux-x86_64-ubuntu2204-6.0.4.tgz : finished
2023-03-13T17:15:49.996Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:49.996+00:00"},"s":"I", "c":"CONTROL", "id":23285, "ctx":"-","msg":"Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
2023-03-13T17:15:50.000Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.000+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}}}
2023-03-13T17:15:50.002Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.002+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."}
2023-03-13T17:15:50.004Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.004+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","namespace":"config.tenantMigrationDonors"}}
2023-03-13T17:15:50.005Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.005+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","namespace":"config.tenantMigrationRecipients"}}
2023-03-13T17:15:50.006Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.005+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"ShardSplitDonorService","namespace":"config.tenantSplitDonors"}}
2023-03-13T17:15:50.006Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.006+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
2023-03-13T17:15:50.008Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.007+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":2545,"port":36437,"dbPath":"/tmp/temp--c97231b3-d618-46be-85d2-8aec327c4c34/mongod-database11132772903201780863","architecture":"64-bit","host":"fv-az572-694"}}
2023-03-13T17:15:50.008Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.007+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"6.0.4","gitVersion":"44ff59461c1353638a71e710f385a566bcd2f547","openSSLVersion":"OpenSSL 3.0.2 15 Mar 2022","modules":[],"allocator":"tcmalloc","environment":{"distmod":"ubuntu2204","distarch":"x86_64","target_arch":"x86_64"}}}}
2023-03-13T17:15:50.008Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.007+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"22.04"}}}
2023-03-13T17:15:50.009Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.007+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":36437},"security":{"authorization":"disabled"},"storage":{"dbPath":"/tmp/temp--c97231b3-d618-46be-85d2-8aec327c4c34/mongod-database11132772903201780863","journal":{"enabled":false},"syncPeriodSecs":0}}}}
2023-03-13T17:15:50.013Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.011+00:00"},"s":"I", "c":"STORAGE", "id":22297, "ctx":"initandlisten","msg":"Using the XFS filesystem is strongly recommended with the WiredTiger storage engine. See http://dochub.mongodb.org/core/prodnotes-filesystem","tags":["startupWarnings"]}
2023-03-13T17:15:50.013Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.011+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=2959M,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),"}}
2023-03-13T17:15:50.777Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.776+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":765}}
2023-03-13T17:15:50.778Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.778+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:50.790Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.789+00:00"},"s":"W", "c":"CONTROL", "id":22178, "ctx":"initandlisten","msg":"/sys/kernel/mm/transparent_hugepage/enabled is 'always'. We suggest setting it to 'never'","tags":["startupWarnings"]}
2023-03-13T17:15:50.791Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.791+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"admin.system.version","uuidDisposition":"provided","uuid":{"uuid":{"$uuid":"e9652356-4c29-41d4-baf6-80bfc5b719cb"}},"options":{"uuid":{"$uuid":"e9652356-4c29-41d4-baf6-80bfc5b719cb"}}}}
2023-03-13T17:15:50.802Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.802+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"e9652356-4c29-41d4-baf6-80bfc5b719cb"}},"namespace":"admin.system.version","index":"_id_","ident":"index-1--4017041888978331020","collectionIdent":"collection-0--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:50.803Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.803+00:00"},"s":"I", "c":"REPL", "id":20459, "ctx":"initandlisten","msg":"Setting featureCompatibilityVersion","attr":{"newVersion":"6.0"}}
2023-03-13T17:15:50.804Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.804+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"setFCV"}}
2023-03-13T17:15:50.805Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.805+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}}}
2023-03-13T17:15:50.806Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.805+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}}}
2023-03-13T17:15:50.806Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.806+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"startup"}}
2023-03-13T17:15:50.808Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.807+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
2023-03-13T17:15:50.809Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.807+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
2023-03-13T17:15:50.810Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.808+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tmp/temp--c97231b3-d618-46be-85d2-8aec327c4c34/mongod-database11132772903201780863/diagnostic.data"}}
2023-03-13T17:15:50.818Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.817+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"1b6e68fe-5d9f-44f1-b82d-4a6a44c9696a"}},"options":{"capped":true,"size":10485760}}}
2023-03-13T17:15:50.828Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.828+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"1b6e68fe-5d9f-44f1-b82d-4a6a44c9696a"}},"namespace":"local.startup_log","index":"_id_","ident":"index-3--4017041888978331020","collectionIdent":"collection-2--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:50.829Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.829+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigReplicationDisabled","oldState":"ConfigPreStart"}}
2023-03-13T17:15:50.830Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.830+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"}
2023-03-13T17:15:50.842Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.841+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"cee1e96d-f7c4-408c-8d3c-4581cb5fdbb7"}},"options":{}}}
2023-03-13T17:15:50.844Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.844+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"}}
2023-03-13T17:15:50.845Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.845+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-36437.sock"}}
2023-03-13T17:15:50.847Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.847+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}}
2023-03-13T17:15:50.848Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.847+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":36437,"ssl":"off"}}
2023-03-13T17:15:50.877Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.877+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"cee1e96d-f7c4-408c-8d3c-4581cb5fdbb7"}},"namespace":"config.system.sessions","index":"_id_","ident":"index-5--4017041888978331020","collectionIdent":"collection-4--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:50.878Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:50.877+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"cee1e96d-f7c4-408c-8d3c-4581cb5fdbb7"}},"namespace":"config.system.sessions","index":"lsidTTLIndex","ident":"index-6--4017041888978331020","collectionIdent":"collection-4--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:51.142Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:51.142+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:58090","uuid":"15b57199-fd93-49ae-b7ba-f06c76fa7a4b","connectionId":1,"connectionCount":1}}
2023-03-13T17:15:51.143Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:51.142+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:58092","uuid":"8894d1ed-b847-4f4e-91ee-c0cdb35fe3a7","connectionId":2,"connectionCount":2}}
2023-03-13T17:15:51.163Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:51.163+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn2","msg":"client metadata","attr":{"remote":"127.0.0.1:58092","client":"conn2","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.8.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.15.0-1034-azure"},"platform":"Java/Eclipse Adoptium/17.0.6+10"}}}
2023-03-13T17:15:51.166Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:51.166+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn1","msg":"client metadata","attr":{"remote":"127.0.0.1:58090","client":"conn1","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.8.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.15.0-1034-azure"},"platform":"Java/Eclipse Adoptium/17.0.6+10"}}}
2023-03-13T17:15:51.178Z INFO 2500 --- [ Test worker] org.mongodb.driver.client : MongoClient with metadata {"driver": {"name": "mongo-java-driver|sync|spring-boot", "version": "4.8.0"}, "os": {"type": "Linux", "name": "Linux", "architecture": "amd64", "version": "5.15.0-1034-azure"}, "platform": "Java/Eclipse Adoptium/17.0.6+10"} created with settings MongoClientSettings{readPreference=primary, writeConcern=WriteConcern{w=null, wTimeout=null ms, journal=null}, retryWrites=true, retryReads=true, readConcern=ReadConcern{level=null}, credential=null, streamFactoryFactory=null, commandListeners=[], codecRegistry=ProvidersCodecRegistry{codecProviders=[ValueCodecProvider{}, BsonValueCodecProvider{}, DBRefCodecProvider{}, DBObjectCodecProvider{}, DocumentCodecProvider{}, CollectionCodecProvider{}, IterableCodecProvider{}, MapCodecProvider{}, GeoJsonCodecProvider{}, GridFSFileCodecProvider{}, Jsr310CodecProvider{}, JsonObjectCodecProvider{}, BsonCodecProvider{}, EnumCodecProvider{}, com.mongodb.Jep395RecordCodecProvider@782fb591]}, clusterSettings={hosts=[localhost:36437], srvServiceName=mongodb, mode=SINGLE, requiredClusterType=UNKNOWN, requiredReplicaSetName='null', serverSelector='null', clusterListeners='[]', serverSelectionTimeout='30000 ms', localThreshold='30000 ms'}, socketSettings=SocketSettings{connectTimeoutMS=10000, readTimeoutMS=0, receiveBufferSize=0, sendBufferSize=0}, heartbeatSocketSettings=SocketSettings{connectTimeoutMS=10000, readTimeoutMS=10000, receiveBufferSize=0, sendBufferSize=0}, connectionPoolSettings=ConnectionPoolSettings{maxSize=100, minSize=0, maxWaitTimeMS=120000, maxConnectionLifeTimeMS=0, maxConnectionIdleTimeMS=0, maintenanceInitialDelayMS=0, maintenanceFrequencyMS=60000, connectionPoolListeners=[], maxConnecting=2}, serverSettings=ServerSettings{heartbeatFrequencyMS=10000, minHeartbeatFrequencyMS=500, serverListeners='[]', serverMonitorListeners='[]'}, sslSettings=SslSettings{enabled=false, invalidHostNameAllowed=false, context=null}, applicationName='null', compressorList=[], uuidRepresentation=JAVA_LEGACY, serverApi=null, autoEncryptionSettings=null, contextProvider=null}
2023-03-13T17:15:51.204Z INFO 2500 --- [localhost:36437] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=localhost:36437, type=STANDALONE, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=17, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=58658893}
2023-03-13T17:15:52.572Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.572+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:57804","uuid":"1e731024-5acc-48d4-a9b8-5238a6937e1b","connectionId":3,"connectionCount":3}}
2023-03-13T17:15:52.576Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.575+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn3","msg":"client metadata","attr":{"remote":"127.0.0.1:57804","client":"conn3","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.8.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.15.0-1034-azure"},"platform":"Java/Eclipse Adoptium/17.0.6+10"}}}
2023-03-13T17:15:52.600Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.600+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.public_user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"42bdeecc-6904-4e67-b7d7-d8156d54fb9e"}},"options":{}}}
2023-03-13T17:15:52.615Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.615+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"42bdeecc-6904-4e67-b7d7-d8156d54fb9e"}},"namespace":"test.public_user","index":"_id_","ident":"index-8--4017041888978331020","collectionIdent":"collection-7--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:52.617Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.617+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"42bdeecc-6904-4e67-b7d7-d8156d54fb9e"}},"namespace":"test.public_user","index":"username","ident":"index-9--4017041888978331020","collectionIdent":"collection-7--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:52.642Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.641+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}},"options":{}}}
2023-03-13T17:15:52.659Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.659+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}},"namespace":"test.user","index":"_id_","ident":"index-11--4017041888978331020","collectionIdent":"collection-10--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:52.661Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:52.661+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}},"namespace":"test.user","index":"email","ident":"index-12--4017041888978331020","collectionIdent":"collection-10--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:56.867Z INFO 2500 --- [ Test worker] o.s.s.web.DefaultSecurityFilterChain : Will secure any request with [org.springframework.security.web.session.DisableEncodeUrlFilter@512b7f4b, org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@59c43565, org.springframework.security.web.context.SecurityContextHolderFilter@5d262de5, org.springframework.security.web.header.HeaderWriterFilter@53a858b0, org.springframework.web.filter.CorsFilter@2ef32703, org.springframework.security.web.authentication.logout.LogoutFilter@1c067ae4, org.springframework.security.oauth2.client.web.OAuth2AuthorizationRequestRedirectFilter@65399087, org.springframework.security.oauth2.client.web.OAuth2LoginAuthenticationFilter@32122ad3, delta.codecharacter.server.auth.jwt.JwtRequestFilter@3362b33c, org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@1e89b7d6, org.springframework.security.web.authentication.ui.DefaultLogoutPageGeneratingFilter@498055a9, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@76618828, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@2a5f8c53, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@43dbbaf5, org.springframework.security.web.session.SessionManagementFilter@793f45f2, org.springframework.security.web.access.ExceptionTranslationFilter@230214e0, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@67b12a7]
2023-03-13T17:15:57.649Z INFO 2500 --- [ Test worker] o.s.b.t.m.w.SpringBootMockServletContext : Initializing Spring TestDispatcherServlet ''
2023-03-13T17:15:57.649Z INFO 2500 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Initializing Servlet ''
2023-03-13T17:15:57.651Z INFO 2500 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Completed initialization in 2 ms
2023-03-13T17:15:57.709Z INFO 2500 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Attempting to connect to: [localhost:5672]
2023-03-13T17:15:57.798Z INFO 2500 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Created new connection: rabbitConnectionFactory#1da0256f:0/SimpleConnection@26486e67 [delegate=amqp://guest@127.0.0.1:5672/, localPort=51292]
2023-03-13T17:15:57.881Z INFO 2500 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Starting...
2023-03-13T17:15:57.884Z INFO 2500 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : BrokerAvailabilityEvent[available=true, SimpleBrokerMessageHandler [org.springframework.messaging.simp.broker.DefaultSubscriptionRegistry@cfdfc94]]
2023-03-13T17:15:57.884Z INFO 2500 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Started.
2023-03-13T17:15:57.907Z INFO 2500 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : Started CodeControllerIntegrationTest in 17.341 seconds (process running for 21.964)
2023-03-13T17:15:57.956Z INFO 2500 --- [ Test worker] d.c.server.seeders.DailyChallengeSeeder : Seeding daily_challenges
2023-03-13T17:15:57.957Z ERROR 2500 --- [ Test worker] d.c.server.seeders.DailyChallengeSeeder : dcConstants.json is empty or doesn't exist
2023-03-13T17:15:58.000Z INFO 2500 --- [MessageBroker-1] d.c.server.schedulers.SchedulingService : Practice phase ended!!
2023-03-13T17:15:58.003Z INFO 2500 --- [MessageBroker-1] d.c.s.u.public_user.PublicUserService : Ratings reset after practice phase done
2023-03-13T17:15:58.081Z INFO 2500 --- [MessageBroker-1] d.c.s.u.public_user.PublicUserService : Leaderboard tier set during the start of game phase
2023-03-13T17:15:58.965Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:58.965+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"dbb2284d-26d1-49ad-a6f7-063ec57eb98e"}},"options":{}}}
2023-03-13T17:15:58.977Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:58.977+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"dbb2284d-26d1-49ad-a6f7-063ec57eb98e"}},"namespace":"test.latest_code","index":"_id_","ident":"index-14--4017041888978331020","collectionIdent":"collection-13--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:59.000Z INFO 2500 --- [MessageBroker-2] d.c.server.schedulers.SchedulingService : Practice phase ended!!
2023-03-13T17:15:59.006Z INFO 2500 --- [MessageBroker-2] d.c.s.u.public_user.PublicUserService : Ratings reset after practice phase done
2023-03-13T17:15:59.028Z INFO 2500 --- [MessageBroker-2] d.c.s.u.public_user.PublicUserService : Leaderboard tier set during the start of game phase
2023-03-13T17:15:59.521Z WARN 2500 --- [ Test worker] o.h.v.i.p.javabean.JavaBeanExecutable : HV000254: Missing parameter metadata for LanguageDto(String, int, String), which declares implicit or synthetic parameters. Automatic resolution of generic type information for method parameters may yield incorrect results if multiple parameters have the same erasure. To solve this, compile your code with the '-parameters' flag.
2023-03-13T17:15:59.538Z WARN 2500 --- [ Test worker] o.h.v.i.p.javabean.JavaBeanExecutable : HV000254: Missing parameter metadata for CodeTypeDto(String, int, String), which declares implicit or synthetic parameters. Automatic resolution of generic type information for method parameters may yield incorrect results if multiple parameters have the same erasure. To solve this, compile your code with the '-parameters' flag.
2023-03-13T17:15:59.772Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.771+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2023-03-13T17:15:59.778Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.777+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2023-03-13T17:15:59.778Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.778+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"dbb2284d-26d1-49ad-a6f7-063ec57eb98e"}}}}
2023-03-13T17:15:59.778Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.778+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"dbb2284d-26d1-49ad-a6f7-063ec57eb98e"}},"ident":"index-14--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.778Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.778+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-13--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.781Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.781+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2023-03-13T17:15:59.784Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.784+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2023-03-13T17:15:59.785Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.784+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}}}}
2023-03-13T17:15:59.785Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.784+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.user","uuid":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}},"ident":"index-11--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.785Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.784+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"email","namespace":"test.user","uuid":{"uuid":{"$uuid":"67dd575d-dac6-4cb7-a052-3c7bfd244d2d"}},"ident":"index-12--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.785Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.784+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-10--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.828Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.827+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"865bafdb-d62b-4571-8c98-737eff1fac6f"}},"options":{}}}
2023-03-13T17:15:59.833Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.833+00:00"},"s":"I", "c":"STORAGE", "id":22260, "ctx":"TimestampMonitor","msg":"Removing drop-pending idents with drop timestamps before timestamp","attr":{"timestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.833Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.833+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-14--4017041888978331020","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.840Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.840+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"865bafdb-d62b-4571-8c98-737eff1fac6f"}},"namespace":"test.user","index":"_id_","ident":"index-16--4017041888978331020","collectionIdent":"collection-15--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:59.842Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.842+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-13--4017041888978331020","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.846Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.845+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-11--4017041888978331020","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.847Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.847+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"60c3e256-af13-42a3-a5ee-5e5621f736c4"}},"options":{}}}
2023-03-13T17:15:59.849Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.849+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-12--4017041888978331020","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.852Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.851+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-10--4017041888978331020","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.864Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.864+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"60c3e256-af13-42a3-a5ee-5e5621f736c4"}},"namespace":"test.latest_code","index":"_id_","ident":"index-18--4017041888978331020","collectionIdent":"collection-17--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:59.913Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.913+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2023-03-13T17:15:59.919Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.919+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2023-03-13T17:15:59.919Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.919+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"60c3e256-af13-42a3-a5ee-5e5621f736c4"}}}}
2023-03-13T17:15:59.919Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.919+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"60c3e256-af13-42a3-a5ee-5e5621f736c4"}},"ident":"index-18--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.919Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.919+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-17--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.921Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.921+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2023-03-13T17:15:59.925Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.925+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2023-03-13T17:15:59.926Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.925+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"865bafdb-d62b-4571-8c98-737eff1fac6f"}}}}
2023-03-13T17:15:59.926Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.925+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.user","uuid":{"uuid":{"$uuid":"865bafdb-d62b-4571-8c98-737eff1fac6f"}},"ident":"index-16--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.926Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.925+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-15--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:15:59.951Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.951+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"5c1e0c82-1b71-438a-95ce-ef0d7c70fe94"}},"options":{}}}
2023-03-13T17:15:59.961Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.961+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"5c1e0c82-1b71-438a-95ce-ef0d7c70fe94"}},"namespace":"test.user","index":"_id_","ident":"index-20--4017041888978331020","collectionIdent":"collection-19--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:59.967Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.967+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"c10b5b61-076f-498c-b1dc-1f1677aa88f1"}},"options":{}}}
2023-03-13T17:15:59.979Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:15:59.979+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"c10b5b61-076f-498c-b1dc-1f1677aa88f1"}},"namespace":"test.latest_code","index":"_id_","ident":"index-22--4017041888978331020","collectionIdent":"collection-21--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:15:59.999Z INFO 2500 --- [MessageBroker-1] d.c.server.schedulers.SchedulingService : Practice phase ended!!
2023-03-13T17:16:00.002Z INFO 2500 --- [MessageBroker-1] d.c.s.u.public_user.PublicUserService : Ratings reset after practice phase done
2023-03-13T17:16:00.009Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.008+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:57808","uuid":"0e84926c-0091-41c3-b4e3-b657f9745e47","connectionId":4,"connectionCount":4}}
2023-03-13T17:16:00.012Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.012+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn4","msg":"client metadata","attr":{"remote":"127.0.0.1:57808","client":"conn4","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.8.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.15.0-1034-azure"},"platform":"Java/Eclipse Adoptium/17.0.6+10"}}}
2023-03-13T17:16:00.027Z INFO 2500 --- [MessageBroker-1] d.c.s.u.public_user.PublicUserService : Leaderboard tier set during the start of game phase
2023-03-13T17:16:00.044Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.043+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.locked_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"94316ec5-7f18-4224-b7ce-490753b2c24b"}},"options":{}}}
2023-03-13T17:16:00.054Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.053+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"94316ec5-7f18-4224-b7ce-490753b2c24b"}},"namespace":"test.locked_code","index":"_id_","ident":"index-24--4017041888978331020","collectionIdent":"collection-23--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:16:00.086Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.085+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2023-03-13T17:16:00.095Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.091+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2023-03-13T17:16:00.097Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.097+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"c10b5b61-076f-498c-b1dc-1f1677aa88f1"}}}}
2023-03-13T17:16:00.099Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.098+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"c10b5b61-076f-498c-b1dc-1f1677aa88f1"}},"ident":"index-22--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.099Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.099+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-21--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.103Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.103+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2023-03-13T17:16:00.103Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.103+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.locked_code","uuid":{"uuid":{"$uuid":"94316ec5-7f18-4224-b7ce-490753b2c24b"}}}}
2023-03-13T17:16:00.104Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.104+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.locked_code","uuid":{"uuid":{"$uuid":"94316ec5-7f18-4224-b7ce-490753b2c24b"}},"ident":"index-24--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.105Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.105+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.locked_code","ident":"collection-23--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.106Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.106+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2023-03-13T17:16:00.107Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.107+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"5c1e0c82-1b71-438a-95ce-ef0d7c70fe94"}}}}
2023-03-13T17:16:00.107Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.107+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.user","uuid":{"uuid":{"$uuid":"5c1e0c82-1b71-438a-95ce-ef0d7c70fe94"}},"ident":"index-20--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.108Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.108+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-19--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.130Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.129+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"130dcb30-3e46-4d6d-abda-9328d455bba7"}},"options":{}}}
2023-03-13T17:16:00.140Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.140+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"130dcb30-3e46-4d6d-abda-9328d455bba7"}},"namespace":"test.user","index":"_id_","ident":"index-26--4017041888978331020","collectionIdent":"collection-25--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:16:00.360Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.359+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.code_revision","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"ab149752-0bfa-4c2a-b241-e2c5c3105ec4"}},"options":{}}}
2023-03-13T17:16:00.372Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.372+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"ab149752-0bfa-4c2a-b241-e2c5c3105ec4"}},"namespace":"test.code_revision","index":"_id_","ident":"index-28--4017041888978331020","collectionIdent":"collection-27--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:16:00.401Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.399+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2023-03-13T17:16:00.401Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.399+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"ab149752-0bfa-4c2a-b241-e2c5c3105ec4"}}}}
2023-03-13T17:16:00.401Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.399+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"ab149752-0bfa-4c2a-b241-e2c5c3105ec4"}},"ident":"index-28--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.401Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.399+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.code_revision","ident":"collection-27--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.404Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.404+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2023-03-13T17:16:00.407Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.407+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2023-03-13T17:16:00.411Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.411+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2023-03-13T17:16:00.411Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.411+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"130dcb30-3e46-4d6d-abda-9328d455bba7"}}}}
2023-03-13T17:16:00.411Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.411+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.user","uuid":{"uuid":{"$uuid":"130dcb30-3e46-4d6d-abda-9328d455bba7"}},"ident":"index-26--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.411Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.411+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-25--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.449Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.449+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"2c867dfe-4cb2-4d33-ac24-cd32d6688100"}},"options":{}}}
2023-03-13T17:16:00.461Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.461+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"2c867dfe-4cb2-4d33-ac24-cd32d6688100"}},"namespace":"test.user","index":"_id_","ident":"index-30--4017041888978331020","collectionIdent":"collection-29--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:16:00.465Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.465+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.code_revision","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"8bc04655-e041-4f64-8e4a-ec89b73e71b7"}},"options":{}}}
2023-03-13T17:16:00.476Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.476+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"8bc04655-e041-4f64-8e4a-ec89b73e71b7"}},"namespace":"test.code_revision","index":"_id_","ident":"index-32--4017041888978331020","collectionIdent":"collection-31--4017041888978331020","commitTimestamp":null}}
2023-03-13T17:16:00.608Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.608+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2023-03-13T17:16:00.609Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.608+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"8bc04655-e041-4f64-8e4a-ec89b73e71b7"}}}}
2023-03-13T17:16:00.609Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.608+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"8bc04655-e041-4f64-8e4a-ec89b73e71b7"}},"ident":"index-32--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.609Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.608+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.code_revision","ident":"collection-31--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.612Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.611+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2023-03-13T17:16:00.614Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.614+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2023-03-13T17:16:00.617Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.616+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2023-03-13T17:16:00.617Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.616+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"2c867dfe-4cb2-4d33-ac24-cd32d6688100"}}}}
2023-03-13T17:16:00.617Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.617+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.user","uuid":{"uuid":{"$uuid":"2c867dfe-4cb2-4d33-ac24-cd32d6688100"}},"ident":"index-30--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2023-03-13T17:16:00.617Z INFO 2500 --- [ Thread-6] d.f.e.m.s.autoconfigure.EmbeddedMongo : {"t":{"$date":"2023-03-13T17:16:00.617+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-29--4017041888978331020","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}