2022-03-02 15:02:38.743 INFO 2310 --- [ Test worker] .b.t.c.SpringBootTestContextBootstrapper : Neither @ContextConfiguration nor @ContextHierarchy found for test class [delta.codecharacter.server.notifications.NotificationIntegrationTest], using SpringBootContextLoader
2022-03-02 15:02:38.744 INFO 2310 --- [ Test worker] o.s.t.c.support.AbstractContextLoader : Could not detect default resource locations for test class [delta.codecharacter.server.notifications.NotificationIntegrationTest]: no resource found for suffixes {-context.xml, Context.groovy}.
2022-03-02 15:02:38.744 INFO 2310 --- [ Test worker] t.c.s.AnnotationConfigContextLoaderUtils : Could not detect default configuration classes for test class [delta.codecharacter.server.notifications.NotificationIntegrationTest]: NotificationIntegrationTest does not declare any static, non-private, non-final, nested classes annotated with @Configuration.
2022-03-02 15:02:38.755 INFO 2310 --- [ Test worker] .b.t.c.SpringBootTestContextBootstrapper : Found @SpringBootConfiguration delta.codecharacter.server.ServerApplication for test class delta.codecharacter.server.notifications.NotificationIntegrationTest
2022-03-02 15:02:38.757 INFO 2310 --- [ Test worker] .b.t.c.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]
2022-03-02 15:02:38.759 INFO 2310 --- [ Test worker] .b.t.c.SpringBootTestContextBootstrapper : Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@564d0eba, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@23b8685, org.springframework.test.context.event.ApplicationEventsTestExecutionListener@72210ef4, com.ninjasquad.springmockk.MockkTestExecutionListener@6d02480e, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@5384a3ca, org.springframework.boot.test.autoconfigure.SpringBootDependencyInjectionTestExecutionListener@dd51891, org.springframework.test.context.support.DirtiesContextTestExecutionListener@7779f171, org.springframework.test.context.transaction.TransactionalTestExecutionListener@84485a8, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@7e28ed99, org.springframework.security.test.context.support.WithSecurityContextTestExecutionListener@5b1c735d, org.springframework.test.context.event.EventPublishingTestExecutionListener@6356e336, org.springframework.security.test.context.support.ReactorContextTestExecutionListener@4f134331, com.ninjasquad.springmockk.ClearMocksTestExecutionListener@76ee2b6f, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener@4e502874, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener@1acb2ddb, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener@4f14ef78, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener@482610a6, org.springframework.boot.test.autoconfigure.webservices.client.MockWebServiceServerTestExecutionListener@77a825e, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@449cd29f]
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.2)
2022-03-02 15:02:38.870 INFO 2310 --- [ Test worker] d.c.s.n.NotificationIntegrationTest : Starting NotificationIntegrationTest 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:38.871 INFO 2310 --- [ Test worker] d.c.s.n.NotificationIntegrationTest : No active profile set, falling back to default profiles: default
2022-03-02 15:02:39.582 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.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:39.583 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.582+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-92--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.588 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.587+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-91--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.591 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.591+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-94--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.595 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.594+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-93--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.599 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.598+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-100--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.604 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.603+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-99--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.610 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.609+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-102--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.649 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.628+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-101--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.649 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.632+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-96--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.650 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.634+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-95--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.650 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.636+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"index-98--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.650 INFO 2310 --- [ Thread-7] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:39.641+00:00"},"s":"I", "c":"STORAGE", "id":22237, "ctx":"TimestampMonitor","msg":"Completing drop for ident","attr":{"ident":"collection-97--3213531206115516651","dropTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:39.886 INFO 2310 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data MongoDB repositories in DEFAULT mode.
2022-03-02 15:02:39.999 INFO 2310 --- [ Test worker] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 112 ms. Found 15 MongoDB repository interfaces.
2022-03-02 15:02:40.987 INFO 2310 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 0 (http)
2022-03-02 15:02:41.043 INFO 2310 --- [ Test worker] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2022-03-02 15:02:41.043 INFO 2310 --- [ Test worker] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/9.0.56]
2022-03-02 15:02:41.363 INFO 2310 --- [ Test worker] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2022-03-02 15:02:41.364 INFO 2310 --- [ Test worker] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 2483 ms
2022-03-02 15:02:41.640 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.640+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:41.641 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.641+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"-","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:41.644 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.644+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"}
2022-03-02 15:02:41.645 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.645+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:41.646 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.646+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"}
2022-03-02 15:02:41.647 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.647+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:41.647 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.647+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:41.648 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.648+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
2022-03-02 15:02:41.649 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.649+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":2530,"port":33131,"dbPath":"/tmp/embedmongo-db-9a8cfc50-e4bc-400c-af5f-7723bb9c8a77","architecture":"64-bit","host":"fv-az128-13"}}
2022-03-02 15:02:41.649 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.649+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:41.650 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.649+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:41.650 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.649+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":33131},"security":{"authorization":"disabled"},"storage":{"dbPath":"/tmp/embedmongo-db-9a8cfc50-e4bc-400c-af5f-7723bb9c8a77","journal":{"enabled":false},"syncPeriodSecs":0}}}}
2022-03-02 15:02:41.661 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.658+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:41.661 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:41.658+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:42.477 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.477+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1646233362:477172][2530:0x7fd7b80bec80], txn-recover: [WT_VERB_RECOVERY_ALL] Set global recovery timestamp: (0, 0)"}}
2022-03-02 15:02:42.477 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.477+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1646233362:477611][2530:0x7fd7b80bec80], txn-recover: [WT_VERB_RECOVERY_ALL] Set global oldest timestamp: (0, 0)"}}
2022-03-02 15:02:42.484 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.484+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":826}}
2022-03-02 15:02:42.484 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.484+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:42.493 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.493+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:42.495 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.495+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"}
2022-03-02 15:02:42.497 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.497+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:42.503 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.503+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"admin.system.version","uuidDisposition":"provided","uuid":{"uuid":{"$uuid":"baad8d4d-0d81-4213-bc87-7c67d018ae4d"}},"options":{"uuid":{"$uuid":"baad8d4d-0d81-4213-bc87-7c67d018ae4d"}}}}
2022-03-02 15:02:42.515 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.515+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:42.516 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.516+00:00"},"s":"I", "c":"REPL", "id":20459, "ctx":"initandlisten","msg":"Setting featureCompatibilityVersion","attr":{"newVersion":"5.0"}}
2022-03-02 15:02:42.516 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.516+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:42.517 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.517+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:42.518 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.517+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
2022-03-02 15:02:42.518 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.517+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
2022-03-02 15:02:42.519 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.519+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tmp/embedmongo-db-9a8cfc50-e4bc-400c-af5f-7723bb9c8a77/diagnostic.data"}}
2022-03-02 15:02:42.520 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.519+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"bdd04a0e-01c7-4492-af56-6f45a3786496"}},"options":{"capped":true,"size":10485760}}}
2022-03-02 15:02:42.528 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.528+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:42.529 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.529+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:42.534 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.534+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"8798ed5f-7a15-431f-9342-56dbf9a7d562"}},"options":{}}}
2022-03-02 15:02:42.536 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.536+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:42.536 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.536+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-33131.sock"}}
2022-03-02 15:02:42.538 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.538+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}}
2022-03-02 15:02:42.538 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.538+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":33131,"ssl":"off"}}
2022-03-02 15:02:42.539 INFO 2310 --- [ Test worker] d.f.embed.mongo.MongodExecutable : start MongodConfig{version=Version{5.0.5}, timeout=de.flapdoodle.embed.mongo.config.Timeout@14370602, net=de.flapdoodle.embed.mongo.config.Net@788ea07b, 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@1ace9956, isConfigServer=false, isShardServer=false, processListener=de.flapdoodle.embed.mongo.config.processlistener.NoopProcessListener@208ca545, params={}, args={}, pidFile=mongod.pid, supportConfig=de.flapdoodle.embed.mongo.config.SupportConfig@c27b040}
2022-03-02 15:02:42.552 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.552+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:42.553 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.552+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:42.559 INFO 2310 --- [ Test worker] org.mongodb.driver.cluster : Cluster created with settings {hosts=[localhost:33131], mode=SINGLE, requiredClusterType=UNKNOWN, serverSelectionTimeout='30000 ms'}
2022-03-02 15:02:42.561 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.561+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46804","uuid":"33d82911-c5ab-4603-9a2e-748f1d7348a1","connectionId":1,"connectionCount":1}}
2022-03-02 15:02:42.566 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.566+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn1","msg":"client metadata","attr":{"remote":"127.0.0.1:46804","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:42.568 INFO 2310 --- [localhost:33131] org.mongodb.driver.connection : Opened connection [connectionId{localValue:4, serverValue:1}] to localhost:33131
2022-03-02 15:02:42.568 INFO 2310 --- [localhost:33131] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=localhost:33131, type=STANDALONE, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=7439183}
2022-03-02 15:02:42.569 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.569+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46806","uuid":"cbba64ae-26cd-42de-bcac-04c534261cd2","connectionId":2,"connectionCount":2}}
2022-03-02 15:02:42.571 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.571+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn2","msg":"client metadata","attr":{"remote":"127.0.0.1:46806","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:42.575 INFO 2310 --- [localhost:33131] org.mongodb.driver.connection : Opened connection [connectionId{localValue:5, serverValue:2}] to localhost:33131
2022-03-02 15:02:42.723 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.723+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46808","uuid":"1fa91db7-382e-458e-8b89-08ebf08a84a3","connectionId":3,"connectionCount":3}}
2022-03-02 15:02:42.727 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.726+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn3","msg":"client metadata","attr":{"remote":"127.0.0.1:46808","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:42.728 INFO 2310 --- [ Test worker] org.mongodb.driver.connection : Opened connection [connectionId{localValue:6, serverValue:3}] to localhost:33131
2022-03-02 15:02:42.729 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.729+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"46fc5a9f-9875-46a7-a4d9-a6caa928e21e"}},"options":{}}}
2022-03-02 15:02:42.742 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.742+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:42.743 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.743+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:42.746 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.746+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.public_user","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"d2cee757-37da-43dd-ba29-82c31ef752fb"}},"options":{}}}
2022-03-02 15:02:42.757 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.757+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:42.758 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:42.758+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:44.379 INFO 2310 --- [ Test worker] o.s.s.web.DefaultSecurityFilterChain : Will secure any request with [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@4914ea9a, org.springframework.security.web.context.SecurityContextPersistenceFilter@79008c9a, org.springframework.security.web.header.HeaderWriterFilter@702fa506, org.springframework.web.filter.CorsFilter@25afbf97, org.springframework.security.web.authentication.logout.LogoutFilter@37a801df, org.springframework.security.oauth2.client.web.OAuth2AuthorizationRequestRedirectFilter@3f6d8ad7, org.springframework.security.oauth2.client.web.OAuth2LoginAuthenticationFilter@3a2583ab, delta.codecharacter.server.auth.jwt.JwtRequestFilter@70dd8888, org.springframework.security.web.authentication.ui.DefaultLoginPageGeneratingFilter@1bd3d3d2, org.springframework.security.web.authentication.ui.DefaultLogoutPageGeneratingFilter@1ded9d2f, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@600626be, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@6d8cb82d, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@7acd43f5, org.springframework.security.web.session.SessionManagementFilter@7891d711, org.springframework.security.web.access.ExceptionTranslationFilter@4b4a4abe, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@44d4626d]
2022-03-02 15:02:44.590 INFO 2310 --- [ Test worker] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring TestDispatcherServlet ''
2022-03-02 15:02:44.590 INFO 2310 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Initializing Servlet ''
2022-03-02 15:02:44.593 INFO 2310 --- [ Test worker] o.s.t.web.servlet.TestDispatcherServlet : Completed initialization in 3 ms
2022-03-02 15:02:44.733 INFO 2310 --- [ Test worker] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 36499 (http) with context path ''
2022-03-02 15:02:45.410 INFO 2310 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Attempting to connect to: [localhost:5672]
2022-03-02 15:02:45.421 INFO 2310 --- [ Test worker] o.s.a.r.c.CachingConnectionFactory : Created new connection: rabbitConnectionFactory#34af5db6:0/SimpleConnection@3863acc6 [delegate=amqp://guest@127.0.0.1:5672/, localPort= 37258]
2022-03-02 15:02:45.434 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Starting...
2022-03-02 15:02:45.435 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : BrokerAvailabilityEvent[available=true, SimpleBrokerMessageHandler [org.springframework.messaging.simp.broker.DefaultSubscriptionRegistry@36edad16]]
2022-03-02 15:02:45.435 INFO 2310 --- [ Test worker] o.s.m.s.b.SimpleBrokerMessageHandler : Started.
2022-03-02 15:02:45.446 INFO 2310 --- [ Test worker] d.c.s.n.NotificationIntegrationTest : Started NotificationIntegrationTest in 6.682 seconds (JVM running for 37.199)
2022-03-02 15:02:45.663 INFO 2310 --- [o-auto-1-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2022-03-02 15:02:45.663 INFO 2310 --- [o-auto-1-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2022-03-02 15:02:45.665 INFO 2310 --- [o-auto-1-exec-1] o.s.web.servlet.DispatcherServlet : Completed initialization in 2 ms
2022-03-02 15:02:45.795 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.794+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"conn3","msg":"createCollection","attr":{"namespace":"test.notification","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"fbfd1d3e-f06a-463e-adc4-5eec29ea1656"}},"options":{}}}
2022-03-02 15:02:45.802 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.802+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"conn3","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"test.notification","index":"_id_","commitTimestamp":null}}
2022-03-02 15:02:45.916 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.915+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.user"}}
2022-03-02 15:02:45.916 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.915+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.user","uuid":{"uuid":{"$uuid":"46fc5a9f-9875-46a7-a4d9-a6caa928e21e"}}}}
2022-03-02 15:02:45.916 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.915+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":"46fc5a9f-9875-46a7-a4d9-a6caa928e21e"}},"ident":"index-8--6204212514090451417","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:45.916 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.915+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":"46fc5a9f-9875-46a7-a4d9-a6caa928e21e"}},"ident":"index-9--6204212514090451417","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:45.916 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.915+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.user","ident":"collection-7--6204212514090451417","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:45.917 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.917+00:00"},"s":"I", "c":"COMMAND", "id":518070, "ctx":"conn3","msg":"CMD: drop","attr":{"namespace":"test.notification"}}
2022-03-02 15:02:45.917 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.917+00:00"},"s":"I", "c":"STORAGE", "id":20318, "ctx":"conn3","msg":"Finishing collection drop","attr":{"namespace":"test.notification","uuid":{"uuid":{"$uuid":"fbfd1d3e-f06a-463e-adc4-5eec29ea1656"}}}}
2022-03-02 15:02:45.917 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.917+00:00"},"s":"I", "c":"STORAGE", "id":22206, "ctx":"conn3","msg":"Deferring table drop for index","attr":{"index":"_id_","namespace":"test.notification","uuid":{"uuid":{"$uuid":"fbfd1d3e-f06a-463e-adc4-5eec29ea1656"}},"ident":"index-14--6204212514090451417","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}
2022-03-02 15:02:45.917 INFO 2310 --- [ Thread-11] o.s.b.a.mongo.embedded.EmbeddedMongo : {"t":{"$date":"2022-03-02T15:02:45.917+00:00"},"s":"I", "c":"STORAGE", "id":22214, "ctx":"conn3","msg":"Deferring table drop for collection","attr":{"namespace":"test.notification","ident":"collection-13--6204212514090451417","commitTimestamp":{"$timestamp":{"t":0,"i":0}}}}