15:02:10.091 [Test worker] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating CacheAwareContextLoaderDelegate from class [org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate]
15:02:10.115 [Test worker] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating BootstrapContext using constructor [public org.springframework.test.context.support.DefaultBootstrapContext(java.lang.Class,org.springframework.test.context.CacheAwareContextLoaderDelegate)]
15:02:10.178 [Test worker] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating TestContextBootstrapper for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest] from class [org.springframework.boot.test.context.SpringBootTestContextBootstrapper]
15:02:10.195 [Test worker] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Neither @ContextConfiguration nor @ContextHierarchy found for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest], using SpringBootContextLoader
15:02:10.202 [Test worker] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest]: class path resource [delta/codecharacter/server/code/CodeControllerIntegrationTest-context.xml] does not exist
15:02:10.203 [Test worker] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [delta.codecharacter.server.code.CodeControllerIntegrationTest]: class path resource [delta/codecharacter/server/code/CodeControllerIntegrationTestContext.groovy] does not exist
15:02:10.203 [Test worker] INFO 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}.
15:02:10.205 [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.
15:02:10.279 [Test worker] DEBUG org.springframework.test.context.support.ActiveProfilesUtils - Could not find an 'annotation declaring class' for annotation type [org.springframework.test.context.ActiveProfiles] and class [delta.codecharacter.server.code.CodeControllerIntegrationTest]
15:02:10.387 [Test worker] DEBUG org.springframework.context.annotation.ClassPathScanningCandidateComponentProvider - Identified candidate component class: file [/home/runner/work/codecharacter-server-2022/codecharacter-server-2022/server/build/classes/kotlin/main/delta/codecharacter/server/ServerApplication.class]
15:02:10.389 [Test worker] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Found @SpringBootConfiguration delta.codecharacter.server.ServerApplication for test class delta.codecharacter.server.code.CodeControllerIntegrationTest
15:02:10.516 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - @TestExecutionListeners is not present for class [delta.codecharacter.server.code.CodeControllerIntegrationTest]: using defaults.
15:02:10.517 [Test worker] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [com.ninjasquad.springmockk.MockkTestExecutionListener, com.ninjasquad.springmockk.ClearMocksTestExecutionListener, org.springframework.security.test.context.support.WithSecurityContextTestExecutionListener, org.springframework.security.test.context.support.ReactorContextTestExecutionListener, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener, org.springframework.boot.test.autoconfigure.webservices.client.MockWebServiceServerTestExecutionListener, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener, org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.event.ApplicationEventsTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener, org.springframework.test.context.event.EventPublishingTestExecutionListener]
15:02:11.245 [Test worker] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@3df04fa1, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@5c25daad, org.springframework.test.context.event.ApplicationEventsTestExecutionListener@6fdc2db6, com.ninjasquad.springmockk.MockkTestExecutionListener@5be69f7e, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@64544ee4, org.springframework.boot.test.autoconfigure.SpringBootDependencyInjectionTestExecutionListener@16d61071, org.springframework.test.context.support.DirtiesContextTestExecutionListener@18692e80, org.springframework.test.context.transaction.TransactionalTestExecutionListener@779b4f9c, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@31fe0ce5, org.springframework.security.test.context.support.WithSecurityContextTestExecutionListener@178dc733, org.springframework.test.context.event.EventPublishingTestExecutionListener@4641f66c, org.springframework.security.test.context.support.ReactorContextTestExecutionListener@6b0ba697, com.ninjasquad.springmockk.ClearMocksTestExecutionListener@2a531637, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener@539ee811, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener@1aa59698, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener@2cacb445, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener@1e7cfd49, org.springframework.boot.test.autoconfigure.webservices.client.MockWebServiceServerTestExecutionListener@3a8640f7, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@ca10069]
15:02:11.252 [Test worker] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test class: context [DefaultTestContext@229cb4d8 testClass = CodeControllerIntegrationTest, testInstance = [null], testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@76e9eed8 testClass = CodeControllerIntegrationTest, locations = '{}', classes = '{class delta.codecharacter.server.ServerApplication}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true}', contextCustomizers = set[MockkContextCustomizer(definitions=[]), org.springframework.boot.test.autoconfigure.actuate.metrics.MetricsExportContextCustomizerFactory$DisableMetricExportContextCustomizer@7b948f3e, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@4b3fa0b3, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@76ad6715, [ImportsContextCustomizer@30ef32eb key = [org.springframework.boot.test.autoconfigure.web.servlet.MockMvcAutoConfiguration, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcWebClientAutoConfiguration, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcWebDriverAutoConfiguration, org.springframework.boot.autoconfigure.security.oauth2.client.servlet.OAuth2ClientAutoConfiguration, org.springframework.boot.autoconfigure.security.oauth2.resource.servlet.OAuth2ResourceServerAutoConfiguration, org.springframework.boot.autoconfigure.security.servlet.SecurityAutoConfiguration, org.springframework.boot.autoconfigure.security.servlet.SecurityFilterAutoConfiguration, org.springframework.boot.autoconfigure.security.servlet.UserDetailsServiceAutoConfiguration, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcSecurityConfiguration, org.springframework.boot.test.autoconfigure.web.reactive.WebTestClientAutoConfiguration]], org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@489091bd, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@3743539f, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.web.client.TestRestTemplateContextCustomizer@2f4e40d7, org.springframework.boot.test.context.SpringBootTestArgs@1, org.springframework.boot.test.context.SpringBootTestWebEnvironment@52350abb], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.boot.test.context.SpringBootContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.activateListener' -> true]], class annotated with @DirtiesContext [false] with mode [null].
15:02:11.322 [Test worker] DEBUG org.springframework.boot.ApplicationServletEnvironment - Activating profiles []
15:02:11.326 [Test worker] DEBUG org.springframework.test.context.support.TestPropertySourceUtils - Adding inlined properties to environment: {spring.jmx.enabled=false, org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true}
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.2)
2022-03-02 15:02:12.056 INFO 2310 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : Starting CodeControllerIntegrationTest using Java 17.0.2 on fv-az128-13 with PID 2310 (started by runner in /home/runner/work/codecharacter-server-2022/codecharacter-server-2022/server)
2022-03-02 15:02:12.058 INFO 2310 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : No active profile set, falling back to default profiles: default
2022-03-02 15:02:14.913 INFO 2310 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data MongoDB repositories in DEFAULT mode.
2022-03-02 15:02:15.015 INFO 2310 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 95 ms. Found 15 MongoDB repository interfaces.
2022-03-02 15:02:16.027 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : starting...
2022-03-02 15:02:16.983 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : DownloadSize: 85639871
2022-03-02 15:02:16.984 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 0 %
2022-03-02 15:02:17.250 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 10 %
2022-03-02 15:02:17.409 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 20 %
2022-03-02 15:02:17.561 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 30 %
2022-03-02 15:02:17.759 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 40 %
2022-03-02 15:02:18.049 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 50 %
2022-03-02 15:02:18.240 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 60 %
2022-03-02 15:02:18.524 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 70 %
2022-03-02 15:02:18.875 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 80 %
2022-03-02 15:02:19.148 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 90 %
2022-03-02 15:02:19.374 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : 100 %
2022-03-02 15:02:19.375 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : downloaded with 41816kb/s
2022-03-02 15:02:19.474 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Download Version{5.0.5}:Platform{operatingSystem=Linux, architecture=X86_64, distribution=Ubuntu, version=Ubuntu_20_04} : finished
2022-03-02 15:02:19.476 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Extract /home/runner/.embedmongo/linux/mongodb-linux-x86_64-ubuntu2004-5.0.5.tgz : starting...
2022-03-02 15:02:20.621 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Extract /home/runner/.embedmongo/linux/mongodb-linux-x86_64-ubuntu2004-5.0.5.tgz : extract mongodb-linux-x86_64-ubuntu2004-5.0.5/bin/mongod
2022-03-02 15:02:20.622 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Extract /home/runner/.embedmongo/linux/mongodb-linux-x86_64-ubuntu2004-5.0.5.tgz : nothing left
2022-03-02 15:02:20.623 INFO 2310 --- [ Test worker] o.s.b.a.mongo.embedded.EmbeddedMongo : Extract /home/runner/.embedmongo/linux/mongodb-linux-x86_64-ubuntu2004-5.0.5.tgz : finished
2022-03-02 15:02:20.740 INFO 2310 --- [ Test worker] d.f.e.process.runtime.ProcessControl : stopOrDestroyProcess: process hasn't exited
2022-03-02 15:02:20.763 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.763+00:00"},"s":"I", "c":"CONTROL", "id":23285, "ctx":"-","msg":"Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
2022-03-02 15:02:20.765 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.765+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":13},"outgoing":{"minWireVersion":0,"maxWireVersion":13},"isInternalClient":true}}}
2022-03-02 15:02:20.766 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.766+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"}
2022-03-02 15:02:20.766 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.766+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."}
2022-03-02 15:02:20.768 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.767+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"}
2022-03-02 15:02:20.768 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.768+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","ns":"config.tenantMigrationDonors"}}
2022-03-02 15:02:20.768 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.768+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","ns":"config.tenantMigrationRecipients"}}
2022-03-02 15:02:20.768 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.768+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
2022-03-02 15:02:20.769 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.769+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":2391,"port":46261,"dbPath":"/tmp/embedmongo-db-9c16594c-071b-47be-a034-3f5abf0ab553","architecture":"64-bit","host":"fv-az128-13"}}
2022-03-02 15:02:20.769 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.769+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"5.0.5","gitVersion":"d65fd89df3fc039b5c55933c0f71d647a54510ae","openSSLVersion":"OpenSSL 1.1.1f 31 Mar 2020","modules":[],"allocator":"tcmalloc","environment":{"distmod":"ubuntu2004","distarch":"x86_64","target_arch":"x86_64"}}}}
2022-03-02 15:02:20.770 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.769+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"20.04"}}}
2022-03-02 15:02:20.770 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.769+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":46261},"security":{"authorization":"disabled"},"storage":{"dbPath":"/tmp/embedmongo-db-9c16594c-071b-47be-a034-3f5abf0ab553","journal":{"enabled":false},"syncPeriodSecs":0}}}}
2022-03-02 15:02:20.774 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.773+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"]}
2022-03-02 15:02:20.775 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:20.773+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=2961M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,archive=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=250),statistics_log=(wait=0),verbose=[recovery_progress,checkpoint_progress,compact_progress],,log=(enabled=false),"}}
2022-03-02 15:02:21.559 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.559+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1646233341:559118][2391:0x7fa353d2dc80], txn-recover: [WT_VERB_RECOVERY_ALL] Set global recovery timestamp: (0, 0)"}}
2022-03-02 15:02:21.559 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.559+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1646233341:559169][2391:0x7fa353d2dc80], txn-recover: [WT_VERB_RECOVERY_ALL] Set global oldest timestamp: (0, 0)"}}
2022-03-02 15:02:21.566 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.566+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":793}}
2022-03-02 15:02:21.567 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.567+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:21.579 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.579+00:00"},"s":"I", "c":"STORAGE", "id":4366408, "ctx":"initandlisten","msg":"No table logging settings modifications are required for existing WiredTiger tables","attr":{"loggingEnabled":true}}
2022-03-02 15:02:21.580 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.579+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"}
2022-03-02 15:02:21.584 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.583+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"]}
2022-03-02 15:02:21.585 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.585+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"admin.system.version","uuidDisposition":"provided","uuid":{"uuid":{"$uuid":"235cfa20-f5cf-4bfa-af6a-24a561599825"}},"options":{"uuid":{"$uuid":"235cfa20-f5cf-4bfa-af6a-24a561599825"}}}}
2022-03-02 15:02:21.593 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"admin.system.version","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:21.593 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"REPL", "id":20459, "ctx":"initandlisten","msg":"Setting featureCompatibilityVersion","attr":{"newVersion":"5.0"}}
2022-03-02 15:02:21.593 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":13},"outgoing":{"minWireVersion":0,"maxWireVersion":13},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":13,"maxWireVersion":13},"outgoing":{"minWireVersion":13,"maxWireVersion":13},"isInternalClient":true}}}
2022-03-02 15:02:21.593 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":13,"maxWireVersion":13},"outgoing":{"minWireVersion":13,"maxWireVersion":13},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":13,"maxWireVersion":13},"outgoing":{"minWireVersion":13,"maxWireVersion":13},"isInternalClient":true}}}
2022-03-02 15:02:21.594 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
2022-03-02 15:02:21.594 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.593+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
2022-03-02 15:02:21.598 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.594+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tmp/embedmongo-db-9c16594c-071b-47be-a034-3f5abf0ab553/diagnostic.data"}}
2022-03-02 15:02:21.599 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.594+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"d94c2d9a-a2e7-45bc-8938-089ba3ff9257"}},"options":{"capped":true,"size":10485760}}}
2022-03-02 15:02:21.605 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.604+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"local.startup_log","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:21.605 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.604+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigReplicationDisabled","oldState":"ConfigPreStart"}}
2022-03-02 15:02:21.608 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.608+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"2394fef2-f228-4b67-84e1-90cfce01a881"}},"options":{}}}
2022-03-02 15:02:21.610 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.610+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"}}
2022-03-02 15:02:21.610 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.610+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-46261.sock"}}
2022-03-02 15:02:21.611 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.611+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}}
2022-03-02 15:02:21.614 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.614+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":46261,"ssl":"off"}}
2022-03-02 15:02:21.615 INFO 2310 --- [ Test worker] d.f.embed.mongo.MongodExecutable : start MongodConfig{version=Version{5.0.5}, timeout=de.flapdoodle.embed.mongo.config.Timeout@60d5c16, net=de.flapdoodle.embed.mongo.config.Net@67a84d80, cmdOptions=MongoCmdOptions{syncDelay=0, useDefaultSyncDelay=false, isVerbose=false, useNoPrealloc=true, useSmallFiles=true, useNoJournal=true, enableTextSearch=false, auth=false, master=false}, password=, userName=, replication=de.flapdoodle.embed.mongo.config.Storage@21d297a, isConfigServer=false, isShardServer=false, processListener=de.flapdoodle.embed.mongo.config.processlistener.NoopProcessListener@7879348, params={}, args={}, pidFile=mongod.pid, supportConfig=de.flapdoodle.embed.mongo.config.SupportConfig@6b869242}
2022-03-02 15:02:21.636 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.636+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.system.sessions","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:21.636 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.636+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"LogicalSessionCacheRefresh","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.system.sessions","index":"lsidTTLIndex","commitTimestamp":null}}
2022-03-02 15:02:21.742 INFO 2310 --- [ Test worker] org.mongodb.driver.cluster : Cluster created with settings {hosts=[localhost:46261], mode=SINGLE, requiredClusterType=UNKNOWN, serverSelectionTimeout='30000 ms'}
2022-03-02 15:02:21.830 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.830+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:49456","uuid":"065930ae-25c4-49b6-aa88-0133d6fb7554","connectionId":1,"connectionCount":1}}
2022-03-02 15:02:21.831 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.831+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:49458","uuid":"88cecbd5-5194-4170-bc82-1107541b7eaf","connectionId":2,"connectionCount":2}}
2022-03-02 15:02:21.848 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.848+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn1","msg":"client metadata","attr":{"remote":"127.0.0.1:49456","client":"conn1","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.4.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.11.0-1028-azure"},"platform":"Java/Eclipse Adoptium/17.0.2+8"}}}
2022-03-02 15:02:21.850 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:21.849+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn2","msg":"client metadata","attr":{"remote":"127.0.0.1:49458","client":"conn2","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.4.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.11.0-1028-azure"},"platform":"Java/Eclipse Adoptium/17.0.2+8"}}}
2022-03-02 15:02:21.902 INFO 2310 --- [localhost:46261] org.mongodb.driver.connection : Opened connection [connectionId{localValue:1, serverValue:2}] to localhost:46261
2022-03-02 15:02:21.904 INFO 2310 --- [localhost:46261] org.mongodb.driver.connection : Opened connection [connectionId{localValue:2, serverValue:1}] to localhost:46261
2022-03-02 15:02:21.905 INFO 2310 --- [localhost:46261] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=localhost:46261, type=STANDALONE, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=70876285}
2022-03-02 15:02:22.397 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.397+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:49460","uuid":"fdca10e7-fc3f-470a-a5f7-9b51058f779f","connectionId":3,"connectionCount":3}}
2022-03-02 15:02:22.402 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.402+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn3","msg":"client metadata","attr":{"remote":"127.0.0.1:49460","client":"conn3","doc":{"driver":{"name":"mongo-java-driver|sync|spring-boot","version":"4.4.0"},"os":{"type":"Linux","name":"Linux","architecture":"amd64","version":"5.11.0-1028-azure"},"platform":"Java/Eclipse Adoptium/17.0.2+8"}}}
2022-03-02 15:02:22.408 INFO 2310 --- [ Test worker] org.mongodb.driver.connection : Opened connection [connectionId{localValue:3, serverValue:3}] to localhost:46261
2022-03-02 15:02:22.420 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.419+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"96208b7f-c61b-4e83-a0ea-004b8a3f9cba"}},"options":{}}}
2022-03-02 15:02:22.433 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.433+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:22.433 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.433+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"email","commitTimestamp":null}}
2022-03-02 15:02:22.442 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.441+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.public_user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"18df1715-79f6-4511-b6a1-624e3b5185b0"}},"options":{}}}
2022-03-02 15:02:22.456 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.456+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.public_user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:22.457 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:22.457+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.public_user","index":"username","commitTimestamp":null}}
2022-03-02 15:02:25.535 INFO 2310 --- [ Test worker] o.s.s.web.DefaultSecurityFilterChain : Will secure any request with [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@421e8dfa, org.springframework.security.web.context.SecurityContextPersistenceFilter@7c35782e, org.springframework.security.web.header.HeaderWriterFilter@56232a19, org.springframework.web.filter.CorsFilter@568bac83, org.springframework.security.web.authentication.logout.LogoutFilter@4ba64e10, org.springframework.security.oauth2.client.web.OAuth2AuthorizationRequestRedirectFilter@587c276c, org.springframework.security.oauth2.client.web.OAuth2LoginAuthenticationFilter@141d9b6e, delta.codecharacter.server.auth.jwt.JwtRequestFilter@3ea71809, org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@37d3415a, org.springframework.security.web.authentication.ui.DefaultLogoutPageGeneratingFilter@1e847672, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@5c084812, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@1efedaae, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@28434905, org.springframework.security.web.session.SessionManagementFilter@38823d86, org.springframework.security.web.access.ExceptionTranslationFilter@4566d9a5, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@4c401907]
2022-03-02 15:02:25.964 INFO 2310 --- [ Test worker] o.s.b.t.m.w.SpringBootMockServletContext : Initializing Spring TestDispatcherServlet ''
2022-03-02 15:02:25.964 INFO 2310 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Initializing Servlet ''
2022-03-02 15:02:25.966 INFO 2310 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Completed initialization in 2 ms
2022-03-02 15:02:26.885 INFO 2310 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Attempting to connect to: [localhost:5672]
2022-03-02 15:02:26.960 INFO 2310 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Created new connection: rabbitConnectionFactory#2ca92eb2:0/SimpleConnection@5047a809 [delegate=amqp://guest@127.0.0.1:5672/, localPort= 37184]
2022-03-02 15:02:27.020 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Starting...
2022-03-02 15:02:27.020 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : BrokerAvailabilityEvent[available=true, SimpleBrokerMessageHandler [org.springframework.messaging.simp.broker.DefaultSubscriptionRegistry@e6d2e5]]
2022-03-02 15:02:27.021 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Started.
2022-03-02 15:02:27.031 INFO 2310 --- [ Test worker] d.c.s.c.CodeControllerIntegrationTest : Started CodeControllerIntegrationTest in 15.703 seconds (JVM running for 18.784)
2022-03-02 15:02:27.686 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:27.686+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"f05c761b-4e9a-416f-84c2-13c9628ff1f6"}},"options":{}}}
2022-03-02 15:02:27.696 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:27.696+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.latest_code","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:27.980 WARN 2310 --- [ 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.
2022-03-02 15:02:28.221 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.221+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2022-03-02 15:02:28.239 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.239+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2022-03-02 15:02:28.239 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.239+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"f05c761b-4e9a-416f-84c2-13c9628ff1f6"}}}}
2022-03-02 15:02:28.239 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.239+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":"f05c761b-4e9a-416f-84c2-13c9628ff1f6"}},"ident":"index-14--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.239 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.239+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-13--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.242 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.242+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2022-03-02 15:02:28.244 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.244+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:28.244 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.244+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"96208b7f-c61b-4e83-a0ea-004b8a3f9cba"}}}}
2022-03-02 15:02:28.245 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.244+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":"96208b7f-c61b-4e83-a0ea-004b8a3f9cba"}},"ident":"index-8--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.245 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.244+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":"96208b7f-c61b-4e83-a0ea-004b8a3f9cba"}},"ident":"index-9--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.245 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.244+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-7--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.273 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.273+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"15e1b11a-ccac-433c-8fef-0fcfd808bf07"}},"options":{}}}
2022-03-02 15:02:28.283 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.283+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.287 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.286+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"b9adf0ef-b5cf-4166-ab1e-ecb7ae778769"}},"options":{}}}
2022-03-02 15:02:28.296 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.296+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.latest_code","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.338 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.338+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2022-03-02 15:02:28.341 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.340+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2022-03-02 15:02:28.349 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.341+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"b9adf0ef-b5cf-4166-ab1e-ecb7ae778769"}}}}
2022-03-02 15:02:28.349 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.341+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":"b9adf0ef-b5cf-4166-ab1e-ecb7ae778769"}},"ident":"index-18--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.350 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.341+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-17--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.351 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.351+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2022-03-02 15:02:28.353 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.353+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:28.353 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.353+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"15e1b11a-ccac-433c-8fef-0fcfd808bf07"}}}}
2022-03-02 15:02:28.353 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.353+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":"15e1b11a-ccac-433c-8fef-0fcfd808bf07"}},"ident":"index-16--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.353 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.353+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-15--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.364 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.363+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"d14d2e63-dccf-4587-bffe-4c91592a646b"}},"options":{}}}
2022-03-02 15:02:28.373 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.373+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.376 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.376+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.latest_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"0a2f259b-7cf3-4c59-8c9e-ef7efc354427"}},"options":{}}}
2022-03-02 15:02:28.384 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.384+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.latest_code","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.437 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.436+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.locked_code","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"bc128872-94db-4438-80ef-01d24778b6da"}},"options":{}}}
2022-03-02 15:02:28.448 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.448+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.locked_code","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.463 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.463+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2022-03-02 15:02:28.465 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.465+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2022-03-02 15:02:28.466 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.465+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.latest_code","uuid":{"uuid":{"$uuid":"0a2f259b-7cf3-4c59-8c9e-ef7efc354427"}}}}
2022-03-02 15:02:28.466 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.465+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":"0a2f259b-7cf3-4c59-8c9e-ef7efc354427"}},"ident":"index-22--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.466 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.465+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.latest_code","ident":"collection-21--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.468 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.467+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2022-03-02 15:02:28.468 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.467+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.locked_code","uuid":{"uuid":{"$uuid":"bc128872-94db-4438-80ef-01d24778b6da"}}}}
2022-03-02 15:02:28.468 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.467+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":"bc128872-94db-4438-80ef-01d24778b6da"}},"ident":"index-24--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.468 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.467+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.locked_code","ident":"collection-23--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.471 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.471+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:28.472 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.471+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"d14d2e63-dccf-4587-bffe-4c91592a646b"}}}}
2022-03-02 15:02:28.472 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.471+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":"d14d2e63-dccf-4587-bffe-4c91592a646b"}},"ident":"index-20--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.472 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.471+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-19--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.522 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.522+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"03ed0e1e-9b63-4306-bc31-1089fe23b3f9"}},"options":{}}}
2022-03-02 15:02:28.531 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.530+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.582 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.582+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}}}}
2022-03-02 15:02:28.582 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.582+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-14--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.586 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.586+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-13--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.588 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.588+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-8--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.591 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.591+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-9--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.593 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.593+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-7--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.595 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.595+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-18--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.597 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.597+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-17--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.600 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.600+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-16--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.603 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.603+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-15--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.607 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.607+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-22--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.611 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.610+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-21--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.616 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.615+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-24--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.619 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.619+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-23--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.627 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.626+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-20--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.629 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.628+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-19--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.630 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.630+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.code_revision","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"26ccc9c0-9cae-4061-9899-770f973e3f12"}},"options":{}}}
2022-03-02 15:02:28.641 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.640+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.code_revision","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.659 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.659+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2022-03-02 15:02:28.659 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.659+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"26ccc9c0-9cae-4061-9899-770f973e3f12"}}}}
2022-03-02 15:02:28.659 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.659+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":"26ccc9c0-9cae-4061-9899-770f973e3f12"}},"ident":"index-28--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.659 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.659+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.code_revision","ident":"collection-27--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.660 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.660+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2022-03-02 15:02:28.662 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.662+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2022-03-02 15:02:28.663 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.663+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:28.663 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.663+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"03ed0e1e-9b63-4306-bc31-1089fe23b3f9"}}}}
2022-03-02 15:02:28.663 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.663+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":"03ed0e1e-9b63-4306-bc31-1089fe23b3f9"}},"ident":"index-26--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.663 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.663+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-25--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.672 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.672+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"a7c92d99-2b5f-458d-b7cb-72fb662585c3"}},"options":{}}}
2022-03-02 15:02:28.682 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.682+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.user","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.685 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.685+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.code_revision","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"ba400804-75cf-4a35-b3df-9e284da493b5"}},"options":{}}}
2022-03-02 15:02:28.694 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.693+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.code_revision","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:28.734 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.734+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.code_revision"}}
2022-03-02 15:02:28.735 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.734+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.code_revision","uuid":{"uuid":{"$uuid":"ba400804-75cf-4a35-b3df-9e284da493b5"}}}}
2022-03-02 15:02:28.735 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.734+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":"ba400804-75cf-4a35-b3df-9e284da493b5"}},"ident":"index-32--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.735 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.734+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.code_revision","ident":"collection-31--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.738 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.738+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.latest_code"}}
2022-03-02 15:02:28.740 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.739+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.locked_code"}}
2022-03-02 15:02:28.741 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.741+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:28.741 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.741+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"a7c92d99-2b5f-458d-b7cb-72fb662585c3"}}}}
2022-03-02 15:02:28.741 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.741+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":"a7c92d99-2b5f-458d-b7cb-72fb662585c3"}},"ident":"index-30--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:28.741 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:28.741+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-29--3213531206115516651","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}