Skip to content

Add Created timestamp to StreamEvent#515

Merged
alexeyzimarev merged 2 commits intodevfrom
feat/stream-event-created
Mar 10, 2026
Merged

Add Created timestamp to StreamEvent#515
alexeyzimarev merged 2 commits intodevfrom
feat/stream-event-created

Conversation

@alexeyzimarev
Copy link
Contributor

Summary

  • Add DateTime Created field to the StreamEvent record struct, populated from the underlying store's timestamp data
  • All store implementations (KurrentDB, PostgreSQL, SQL Server, SQLite, Redis, ElasticSearch, InMemory) now pass the creation timestamp through to StreamEvent
  • Update serialisation docs to document the source generator for type registration and the EVTC001 diagnostic analyzer

Test plan

  • Solution builds with zero errors across all target frameworks
  • Verify existing tests pass (no breaking change — Created defaults to default)
  • Verify StreamEvent.Created is populated when reading from each store

🤖 Generated with Claude Code

Expose the event creation timestamp through StreamEvent so consumers
can access it when reading streams. All store implementations already
had this data available but were not passing it through.

Also update the serialisation docs to document the source generator
and EVTC001 analyzer for type registration.

Co-Authored-By: Claude Opus 4.6 <noreply@anthropic.com>
@qodo-free-for-open-source-projects
Copy link
Contributor

Review Summary by Qodo

Add Created timestamp to StreamEvent across all stores

✨ Enhancement

Grey Divider

Walkthroughs

Description
• Add Created timestamp field to StreamEvent record struct
• Populate timestamp from all store implementations (KurrentDB, PostgreSQL, SQL Server, SQLite,
  Redis, ElasticSearch, InMemory)
• Update serialization docs to recommend source generator approach
• Include diagnostic analyzer (EVTC001) documentation for type registration
Diagram
flowchart LR
  StreamEvent["StreamEvent record"]
  Created["Created DateTime field"]
  Stores["Store implementations"]
  KurrentDB["KurrentDB"]
  SQL["SQL variants"]
  Redis["Redis"]
  ES["ElasticSearch"]
  InMemory["InMemory"]
  
  StreamEvent -- "adds field" --> Created
  Stores -- "populate timestamp" --> KurrentDB
  Stores -- "populate timestamp" --> SQL
  Stores -- "populate timestamp" --> Redis
  Stores -- "populate timestamp" --> ES
  Stores -- "populate timestamp" --> InMemory
Loading

Grey Divider

File Changes

1. src/Core/src/Eventuous.Persistence/StreamEvent.cs ✨ Enhancement +1/-0

Add Created timestamp field to StreamEvent

• Add DateTime Created parameter to StreamEvent record struct with default value
• Parameter positioned before FromArchive parameter
• Allows consumers to access event creation timestamp

src/Core/src/Eventuous.Persistence/StreamEvent.cs


2. src/KurrentDB/src/Eventuous.KurrentDB/KurrentDBEventStore.cs ✨ Enhancement +2/-1

Populate Created from KurrentDB events

• Pass resolvedEvent.Event.Created to StreamEvent constructor
• Extracts creation timestamp from KurrentDB event object

src/KurrentDB/src/Eventuous.KurrentDB/KurrentDBEventStore.cs


3. src/Relational/src/Eventuous.Sql.Base/SqlEventStoreBase.cs ✨ Enhancement +1/-1

Populate Created from SQL persisted events

• Pass evt.Created to StreamEvent constructor in ToStreamEvent method
• Applies to all SQL-based stores (PostgreSQL, SQL Server, SQLite)

src/Relational/src/Eventuous.Sql.Base/SqlEventStoreBase.cs


View more (4)
4. src/Redis/src/Eventuous.Redis/RedisStore.cs ✨ Enhancement +1/-1

Populate Created from Redis stream entries

• Parse evt[Created] field and pass to StreamEvent constructor
• Uses DateTime.Parse with CultureInfo.InvariantCulture for consistency

src/Redis/src/Eventuous.Redis/RedisStore.cs


5. src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs ✨ Enhancement +2/-1

Populate Created from ElasticSearch documents

• Pass x.Created to StreamEvent constructor in search results mapping
• Extracts creation timestamp from ElasticSearch persisted events

src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs


6. src/Testing/src/Eventuous.Testing/InMemoryEventStore.cs ✨ Enhancement +5/-3

Populate Created in InMemory store

• Capture DateTime.UtcNow at append time for consistent timestamps
• Pass creation timestamp to StreamEvent constructor in both single and batch append methods
• Use same timestamp for all events appended in single operation

src/Testing/src/Eventuous.Testing/InMemoryEventStore.cs


7. docs/src/content/docs/persistence/serialisation.md 📝 Documentation +18/-21

Update serialization docs for source generator

• Restructure type registration section to prioritize source generator approach
• Document automatic type discovery and module initializer generation
• Add tip about EVTC001 diagnostic analyzer for missing [EventType] attributes
• Rename reflection-based approach to secondary option with note about source generator preference
• Remove manual module initializer example code

docs/src/content/docs/persistence/serialisation.md


Grey Divider

Qodo Logo

@qodo-free-for-open-source-projects
Copy link
Contributor

qodo-free-for-open-source-projects bot commented Mar 10, 2026

Code Review by Qodo

🐞 Bugs (2) 📘 Rule violations (0) 📎 Requirement gaps (0)

Grey Divider


Action required

1. Elastic uses local time🐞 Bug ✓ Correctness
Description
ElasticEventStore persists PersistedEvent.Created using DateTime.Now, and this PR now passes
that value into StreamEvent.Created, making timestamps inconsistent with the UTC timestamps used
by other stores and by code that compares against DateTime.UtcNow (e.g., gap detection). This can
skew time-based logic such as subscription gap-age calculations and any consumer logic expecting UTC
semantics.
Code

src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs[R90-91]

+                    x.StreamPosition,
+                    x.Created
Evidence
The PR change wires PersistedEvent.Created into StreamEvent.Created for ElasticSearch reads.
However, ElasticSearch writes PersistedEvent.Created using local time (DateTime.Now), while
other stores pass UTC timestamps and core subscription code compares Created values against
DateTime.UtcNow, so Elastic-originated Created values will be offset by local timezone and can
produce incorrect time comparisons.

src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs[83-94]
src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs[24-35]
src/Postgres/src/Eventuous.Postgresql/PostgresStore.cs[68-78]
src/Relational/src/Eventuous.Sql.Base/Subscriptions/SqlSubscriptionBase.cs[187-201]

Agent prompt
The issue below was found during a code review. Follow the provided context and guidance below and implement a solution

## Issue description
ElasticSearch store currently writes `PersistedEvent.Created` using `DateTime.Now` (local time). With the PR now mapping that into `StreamEvent.Created`, Created becomes inconsistent with other stores (which use UTC) and breaks UTC-based time comparisons (e.g., subscription gap age logic).
### Issue Context
Other stores pass UTC timestamps (e.g., Postgres/SqlServer), and subscription logic compares `Created` against `DateTime.UtcNow`.
### Fix Focus Areas
- src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs[24-35]
- src/Experimental/src/Eventuous.ElasticSearch/Store/ElasticEventStore.cs[83-94]
### Expected fix
- Change `DateTime.Now` to `DateTime.UtcNow` when creating `PersistedEvent`.
- (Optional/defensive) ensure `x.Created` is treated as UTC consistently if documents may already contain local/unspecified kinds.

ⓘ Copy this prompt and use it to remediate the issue with your preferred AI generation tools



Remediation recommended

2. Redis Created kind lost 🐞 Bug ✓ Correctness
Description
RedisStore writes the created timestamp as DateTime.UtcNow.ToString(CultureInfo.InvariantCulture)
and parses it back with DateTime.Parse, which omits timezone/Kind in the serialized value and
yields a DateTime that is semantically ambiguous (typically Kind=Unspecified). This makes
StreamEvent.Created inconsistent with UTC-based stores and can lead to incorrect UTC comparisons
or downstream assumptions about DateTime.Kind.
Code

src/Redis/src/Eventuous.Redis/RedisStore.cs[130]

+            => new(Guid.Parse(evt[MessageId].ToString()), payload, meta ?? new Metadata(), ContentType, evt.Id.ToLong(), DateTime.Parse(evt[Created]!, CultureInfo.InvariantCulture));
Evidence
The write side serializes the timestamp without an explicit round-trip format, and the read side
parses it without any DateTimeStyles to preserve/assume UTC. As a result, Redis-originated Created
timestamps will not reliably preserve UTC semantics, while core logic elsewhere compares Created
against DateTime.UtcNow.

src/Redis/src/Eventuous.Redis/RedisStore.cs[62-68]
src/Redis/src/Eventuous.Redis/RedisStore.cs[129-131]
src/Relational/src/Eventuous.Sql.Base/Subscriptions/SqlSubscriptionBase.cs[197-200]

Agent prompt
The issue below was found during a code review. Follow the provided context and guidance below and implement a solution

## Issue description
RedisStore round-trips `created` via `DateTime.UtcNow.ToString(CultureInfo.InvariantCulture)` + `DateTime.Parse(...)`. This does not preserve timezone/Kind information, so `StreamEvent.Created` becomes semantically ambiguous (often `Kind=Unspecified`) and inconsistent with other UTC-based stores.
### Issue Context
Other stores pass UTC timestamps, and some logic compares `Created` against `DateTime.UtcNow`.
### Fix Focus Areas
- src/Redis/src/Eventuous.Redis/RedisStore.cs[62-68]
- src/Redis/src/Eventuous.Redis/RedisStore.cs[129-131]
### Expected fix
- Write `created` using an explicit round-trip UTC format, e.g. `DateTime.UtcNow.ToString(&amp;quot;O&amp;quot;, CultureInfo.InvariantCulture)` (or store ticks/epoch milliseconds).
- Parse using `DateTime.ParseExact(..., &amp;quot;O&amp;quot;, ..., DateTimeStyles.RoundtripKind)` or `DateTimeStyles.AssumeUniversal | DateTimeStyles.AdjustToUniversal`.
- Keep behavior backward compatible if existing Redis streams already contain older formats (e.g., try parse with multiple formats and fall back safely).

ⓘ Copy this prompt and use it to remediate the issue with your preferred AI generation tools


Grey Divider

ⓘ The new review experience is currently in Beta. Learn more

Grey Divider

Qodo Logo

@cloudflare-workers-and-pages
Copy link

Deploying eventuous-main with  Cloudflare Pages  Cloudflare Pages

Latest commit: 1fc19de
Status: ✅  Deploy successful!
Preview URL: https://198ebb30.eventuous-main.pages.dev
Branch Preview URL: https://feat-stream-event-created.eventuous-main.pages.dev

View logs

Copy link

@chatgpt-codex-connector chatgpt-codex-connector bot left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

💡 Codex Review

Here are some automated review suggestions for this pull request.

Reviewed commit: 1fc19de286

ℹ️ About Codex in GitHub

Your team has set up Codex to review pull requests in this repo. Reviews are triggered when you

  • Open a pull request for review
  • Mark a draft as ready
  • Comment "@codex review".

If Codex has suggestions, it will comment; otherwise it will react with 👍.

Codex can also answer questions or update the PR. Try commenting "@codex address that feedback".

Metadata Metadata,
string ContentType,
long Revision,
DateTime Created = default,

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

P1 Badge Preserve StreamEvent constructor ABI compatibility

Avoid changing the primary constructor signature of this public record struct in-place: adding Created here changes the generated .ctor from 6 to 7 parameters, so binaries compiled against previous versions will still call the old signature and can fail with MissingMethodException at runtime until they are rebuilt. This is especially problematic for package consumers who upgrade the library without recompiling all dependent assemblies, and it contradicts the “no breaking change” intent.

Useful? React with 👍 / 👎.

@github-actions
Copy link

github-actions bot commented Mar 10, 2026

Test Results

 59 files  + 39   59 suites  +39   38m 6s ⏱️ + 22m 52s
344 tests + 10  343 ✅ +  9  0 💤 ±0  1 ❌ +1 
996 runs  +651  995 ✅ +650  0 💤 ±0  1 ❌ +1 

For more details on these failures, see this check.

Results for commit 86fc4be. ± Comparison against base commit 71036e9.

♻️ This comment has been updated with latest results.

All other stores use UTC timestamps; ElasticSearch was using local time.

Co-Authored-By: Claude Opus 4.6 <noreply@anthropic.com>
@qodo-free-for-open-source-projects
Copy link
Contributor

CI Feedback 🧐

A test triggered by this PR failed. Here is an AI-generated analysis of the failure:

Action: Build and test (8.0)

Failed stage: Run tests [❌]

Failed test name: ShouldProjectImported(MongoProjectionOptions { CollectionName = test })

Failure summary:

The action failed because the test ShouldProjectImported(MongoProjectionOptions { CollectionName =
test }) in Eventuous.Tests.Projections.MongoDB.dll failed due to its MongoDB Testcontainers
dependency crashing.
- The MongoDB container
ef222c6e87c1106b20297b22ac8937f2a55f1361b2731b8b647353d71e12efbf exited with code 48, which
corresponds to a MongoDB startup failure.
- Container logs show SocketException / Address already in
use when trying to bind 0.0.0.0:27017, indicating a port collision on 27017 during container startup
(Error setting up transport layer ... Address already in use).
- Because the container was not
running, the test threw ContainerNotRunningException, which surfaced as
TUnit.Engine.Exceptions.TestFailedException, causing the overall test run to exit with non-success
code 2.

Relevant error logs:
1:  Runner name: 'github-hetzner-runner-22900360812-66445074295-cpx52-nbg1'
2:  Runner group name: 'Default'
...

716:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 08s)
717:  [�[32m+24�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 08s)
718:  �[m/home/ubuntu/_work/eventuous/eventuous/src/RabbitMq/test/Eventuous.Tests.RabbitMq/bin/Debug CI/net8.0/Eventuous.Tests.RabbitMq.dll (net8.0|x64) �[32mpassed�[m �[90m(1m 10s 373ms)�[m
719:  [�[32m+5�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)(1m 09s)
720:  [�[32m+3�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.SqlServer.dll (net8.0|x64)(1m 07s)
721:  [�[32m+7�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.KurrentDB.dll (net8.0|x64)(1m 10s)
722:  [�[32m+0�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.GooglePubSub.dll (net8.0|x64)(1m 10s)
723:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 10s)
724:  [�[32m+25�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 10s)
725:  [�[32m+5�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)(1m 10s)
726:  [�[32m+3�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.SqlServer.dll (net8.0|x64)(1m 09s)
727:  [�[32m+8�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.KurrentDB.dll (net8.0|x64)(1m 11s)
728:  [�[32m+1�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.GooglePubSub.dll (net8.0|x64)(1m 11s)
729:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 11s)
730:  [�[32m+25�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 11s)
731:  �[31mfailed�[m ShouldProjectImported(MongoProjectionOptions { CollectionName = test })�[90m �[90m(0ms)�[m
732:  ContainerNotRunningException: Container ef222c6e87c1106b20297b22ac8937f2a55f1361b2731b8b647353d71e12efbf exited with code 48.
733:  Stdout: 
734:  {"t":{"$date":"2026-03-10T11:33:52.234+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":3308180877}}
735:  about to fork child process, waiting until server is ready for connections.
736:  forked process: 28
737:  {"t":{"$date":"2026-03-10T11:33:52.242+00:00"},"s":"I",  "c":"CONTROL",  "id":20698,   "ctx":"main","msg":"***** SERVER RESTARTED *****"}
738:  {"t":{"$date":"2026-03-10T11:33:52.245+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
739:  {"t":{"$date":"2026-03-10T11:33:52.251+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
740:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
741:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":28,"port":27017,"dbPath":"/data/db","architecture":"64-bit","host":"ef222c6e87c1"}}
742:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":23403,   "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"8.2.5","gitVersion":"a471a13094434666c48a1f75451f2efa49f8f5df","openSSLVersion":"OpenSSL 3.0.13 30 Jan 2024","modules":[],"allocator":"tcmalloc-google","environment":{"distmod":"ubuntu2404","distarch":"x86_64","target_arch":"x86_64"}}}}
743:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":51765,   "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"24.04"}}}
744:  {"t":{"$date":"2026-03-10T11:33:52.252+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":27017,"tls":{"mode":"disabled"}},"processManagement":{"fork":true,"pidFilePath":"/tmp/docker-entrypoint-temp-mongod.pid"},"systemLog":{"destination":"file","logAppend":true,"path":"/proc/1/fd/1"}}}}
745:  {"t":{"$date":"2026-03-10T11:33:52.253+00:00"},"s":"I",  "c":"NETWORK",  "id":4648601, "ctx":"initandlisten","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set at least one of the related parameters","attr":{"relatedParameters":["tcpFastOpenServer","tcpFastOpenClient","tcpFastOpenQueueSize"]}}
746:  {"t":{"$date":"2026-03-10T11:33:52.254+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"]}
747:  {"t":{"$date":"2026-03-10T11:33:52.254+00:00"},"s":"I",  "c":"STORAGE",  "id":22315,   "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=11214M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,remove=true,path=journal,compressor=snappy),builtin_extension_config=(zstd=(compression_level=6)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,live_restore_progress:1,backup:0,checkpoint:0,compact:0,eviction:0,fileops:0,history_store:0,live_restore:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],prefetch=(available=true,default=false),"}}
748:  {"t":{"$date":"2026-03-10T11:33:52.266+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":266116,"thread":"28:0x7f0184f1d400","session_name":"wiredtiger_open","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"opening the WiredTiger library"}}}
...

750:  {"t":{"$date":"2026-03-10T11:33:52.320+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":320635,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting WiredTiger utility threads"}}}
751:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323289,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting WiredTiger recovery"}}}
752:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323563,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"scanning metadata to find the largest file ID"}}}
753:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323598,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"largest file ID found in the metadata 0"}}}
754:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323614,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery log replay has successfully finished and ran for 0 milliseconds"}}}
755:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323637,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1000000,"category_id":33,"verbose_level":"DEBUG_1","verbose_level_id":1,"msg":"Set global recovery timestamp: (0, 0)"}}}
756:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323649,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1000000,"category_id":33,"verbose_level":"DEBUG_1","verbose_level_id":1,"msg":"Set global oldest timestamp: (0, 0)"}}}
757:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323690,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1493201,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery was completed successfully and took 0ms, including 0ms for the log replay, 0ms for the rollback to stable, and 0ms for the checkpoint."}}}
758:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323715,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1493201,"category_id":33,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery was completed successfully and took 0ms, including 0ms for the log replay, 0ms for the rollback to stable, and 0ms for the checkpoint."}}}
759:  {"t":{"$date":"2026-03-10T11:33:52.327+00:00"},"s":"I",  "c":"WTEVICT",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":327589,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_EVICTION","log_id":1000000,"category_id":15,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting eviction threads"}}}
760:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":331160,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"WiredTiger utility threads started successfully"}}}
761:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":331203,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"the WiredTiger library has successfully opened"}}}
762:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"STORAGE",  "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":77}}
763:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"RECOVERY", "id":23987,   "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
764:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"STORAGE",  "id":9086700, "ctx":"initandlisten","msg":"WiredTiger session cache max value has been set","attr":{"sessionCacheMax":16500}}
765:  {"t":{"$date":"2026-03-10T11:33:52.334+00:00"},"s":"I",  "c":"STORAGE",  "id":10158000,"ctx":"initandlisten","msg":"Opening spill WiredTiger","attr":{"config":"create,cache_size=586M,session_max=1024,eviction=(threads_min=1,threads_max=1),eviction_dirty_target=29MB,eviction_dirty_trigger=468MB,eviction_updates_trigger=468MB,config_base=false,statistics=(fast),log=(enabled=false),builtin_extension_config=(zstd=(compression_level=-7)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,live_restore_progress:1,backup:0,checkpoint:0,compact:0,eviction:0,fileops:0,history_store:0,live_restore:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],"}}
766:  {"t":{"$date":"2026-03-10T11:33:52.342+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":340278,"thread":"28:0x7f0184f1d400","session_name":"wiredtiger_open","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"opening the WiredTiger library"}}}
...

795:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"REPL",     "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"8.2","context":"startup"}}
796:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"STORAGE",  "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
797:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"STORAGE",  "id":10682200,"ctx":"initandlisten","msg":"Dropping spill idents","attr":{"numIdents":0}}
798:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"CONTROL",  "id":6608200, "ctx":"initandlisten","msg":"Initializing cluster server parameters from disk"}
799:  {"t":{"$date":"2026-03-10T11:33:52.402+00:00"},"s":"I",  "c":"CONTROL",  "id":20536,   "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
800:  {"t":{"$date":"2026-03-10T11:33:52.402+00:00"},"s":"I",  "c":"FTDC",     "id":20625,   "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/data/db/diagnostic.data"}}
801:  {"t":{"$date":"2026-03-10T11:33:52.404+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"0da524d5-c789-441b-8515-3a2b2e6387b1"}},"options":{"capped":true,"size":10485760}}}
802:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"0da524d5-c789-441b-8515-3a2b2e6387b1"}},"namespace":"local.startup_log","index":"_id_","ident":"index-9c78bc10-9796-47c3-ae3a-a3d446cdf1d3","collectionIdent":"collection-3feaa191-d1ee-4c8a-bd52-288c60f3f628","commitTimestamp":null}}
803:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"REPL",     "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigReplicationDisabled","oldState":"ConfigPreStart"}}
804:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"STORAGE",  "id":22262,   "ctx":"initandlisten","msg":"Timestamp monitor starting"}
805:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"STORAGE",  "id":7333401, "ctx":"initandlisten","msg":"Starting the DiskSpaceMonitor"}
806:  {"t":{"$date":"2026-03-10T11:33:52.417+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"a201f414-95c8-4bb9-8401-696af0833aa3"}},"options":{}}}
807:  {"t":{"$date":"2026-03-10T11:33:52.418+00:00"},"s":"I",  "c":"NETWORK",  "id":23015,   "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-27017.sock"}}
808:  {"t":{"$date":"2026-03-10T11:33:52.419+00:00"},"s":"I",  "c":"NETWORK",  "id":23015,   "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1:27017"}}
809:  {"t":{"$date":"2026-03-10T11:33:52.419+00:00"},"s":"I",  "c":"NETWORK",  "id":23016,   "ctx":"listener","msg":"Waiting for connections","attr":{"port":27017,"ssl":"off"}}
810:  {"t":{"$date":"2026-03-10T11:33:52.419+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"}}
811:  {"t":{"$date":"2026-03-10T11:33:52.420+00:00"},"s":"I",  "c":"CONTROL",  "id":8423403, "ctx":"initandlisten","msg":"mongod startup complete","attr":{"Summary of time elapsed":{"Startup from clean shutdown?":true,"Statistics":{"setUpPeriodicRunnerMillis":0,"setUpOCSPMillis":0,"setUpTransportLayerMillis":1,"initSyncCrashRecoveryMillis":0,"createLockFileMillis":0,"getStorageEngineMetadataMillis":0,"createStorageEngineMillis":125,"writePIDMillis":0,"writeNewMetadataMillis":0,"initializeFCVForIndexMillis":0,"dropAbandonedIdentsMillis":0,"standaloneClusterParamsMillis":1,"userAndRolesGraphMillis":0,"waitForMajorityServiceMillis":0,"startUpReplCoordMillis":0,"recoverChangeStreamMillis":0,"logStartupOptionsMillis":0,"startUpTransportLayerMillis":1,"initAndListenTotalMillis":167}}}}
...

845:  {"t":{"$date":"2026-03-10T11:33:53.568+00:00"},"s":"I",  "c":"NETWORK",  "id":6788700, "ctx":"conn8","msg":"Received first command on ingress connection since session start or auth handshake","attr":{"elapsedMillis":6}}
846:  admin> | | | | {"t":{"$date":"2026-03-10T11:33:53.749+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"conn8","msg":"createCollection","attr":{"namespace":"admin.system.users","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"options":{}}}
847:  {"t":{"$date":"2026-03-10T11:33:53.799+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"conn8","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"namespace":"admin.system.users","index":"_id_","ident":"index-b44a2847-beae-46e1-b01a-31f707f512f9","collectionIdent":"collection-fc31101f-bc0d-4892-8e3e-037dd9199e6e","commitTimestamp":null}}
848:  {"t":{"$date":"2026-03-10T11:33:53.799+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"conn8","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"namespace":"admin.system.users","index":"user_1_db_1","ident":"index-5a26a76a-cc86-4f70-bd40-7f4b136793cf","collectionIdent":"collection-fc31101f-bc0d-4892-8e3e-037dd9199e6e","commitTimestamp":null}}
849:  { ok: 1 }
850:  admin> {"t":{"$date":"2026-03-10T11:33:53.808+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn6","msg":"Connection ended","attr":{"remote":"127.0.0.1:60238","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"c6fb3f89-087d-4ab9-8591-b534bb1fb3a5"}},"connectionId":6,"connectionCount":3}}
851:  {"t":{"$date":"2026-03-10T11:33:53.816+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn5","msg":"Connection ended","attr":{"remote":"127.0.0.1:60216","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"9c44f813-0878-4fe6-aa13-4cd51cd1835d"}},"connectionId":5,"connectionCount":2}}
852:  {"t":{"$date":"2026-03-10T11:33:53.818+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn7","msg":"Connection ended","attr":{"remote":"127.0.0.1:60258","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"68b5db1d-310f-405f-8881-9c650c758a01"}},"connectionId":7,"connectionCount":1}}
853:  {"t":{"$date":"2026-03-10T11:33:53.818+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn8","msg":"Connection ended","attr":{"remote":"127.0.0.1:60268","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"a7abedde-3fbd-4b09-9308-53611af85f00"}},"connectionId":8,"connectionCount":0}}
854:  /usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/*
855:  {"t":{"$date":"2026-03-10T11:33:53.873+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":2237125458}}
856:  {"t":{"$date":"2026-03-10T11:33:53.878+00:00"},"s":"I",  "c":"CONTROL",  "id":20698,   "ctx":"main","msg":"***** SERVER RESTARTED *****"}
857:  {"t":{"$date":"2026-03-10T11:33:53.883+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
858:  {"t":{"$date":"2026-03-10T11:33:53.884+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
859:  Killing process with pid: 28
860:  {"t":{"$date":"2026-03-10T11:33:53.887+00:00"},"s":"I",  "c":"CONTROL",  "id":23377,   "ctx":"SignalHandler","msg":"Received signal","attr":{"signal":15,"error":"Terminated"}}
861:  {"t":{"$date":"2026-03-10T11:33:53.887+00:00"},"s":"I",  "c":"CONTROL",  "id":23378,   "ctx":"SignalHandler","msg":"Signal was sent by kill(2)","attr":{"pid":119,"uid":999}}
...

930:  {"t":{"$date":"2026-03-10T11:33:53.992+00:00"},"s":"I",  "c":"WTEVICT",  "id":22430,   "ctx":"thread41","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142433,"ts_usec":992409,"thread":"28:0x7f017f6f06c0","session_name":"eviction-server","category":"WT_VERB_EVICTION","log_id":1000000,"category_id":15,"verbose_level":"INFO","verbose_level_id":0,"msg":"eviction thread exiting"}}}
931:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"STORAGE",  "id":4795901, "ctx":"SignalHandler","msg":"WiredTiger closed","attr":{"durationMillis":42}}
932:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"STORAGE",  "id":22279,   "ctx":"SignalHandler","msg":"shutdown: removing fs lock..."}
933:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"-",        "id":4784931, "ctx":"SignalHandler","msg":"Dropping the scope cache for shutdown"}
934:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"FTDC",     "id":20626,   "ctx":"SignalHandler","msg":"Shutting down full-time diagnostic data capture"}
935:  MongoDB init process complete; ready for start up.
936:  {"t":{"$date":"2026-03-10T11:33:54.928+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":4255525087}}
937:  {"t":{"$date":"2026-03-10T11:33:54.936+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
938:  {"t":{"$date":"2026-03-10T11:33:54.937+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
939:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
940:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":1,"port":27017,"dbPath":"/data/db","architecture":"64-bit","host":"ef222c6e87c1"}}
941:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":23403,   "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"8.2.5","gitVersion":"a471a13094434666c48a1f75451f2efa49f8f5df","openSSLVersion":"OpenSSL 3.0.13 30 Jan 2024","modules":[],"allocator":"tcmalloc-google","environment":{"distmod":"ubuntu2404","distarch":"x86_64","target_arch":"x86_64"}}}}
942:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":51765,   "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"24.04"}}}
943:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":21951,   "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"net":{"bindIp":"*"},"security":{"authorization":"enabled"}}}}
944:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"NETWORK",  "id":4648601, "ctx":"initandlisten","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set at least one of the related parameters","attr":{"relatedParameters":["tcpFastOpenServer","tcpFastOpenClient","tcpFastOpenQueueSize"]}}
945:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"E",  "c":"CONTROL",  "id":20568,   "ctx":"initandlisten","msg":"Error setting up transport layer","attr":{"error":{"code":9001,"codeName":"SocketException","errmsg":"0.0.0.0:27017 :: caused by :: setup bind :: caused by :: Address already in use"}}}
946:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"REPL",     "id":4784900, "ctx":"initandlisten","msg":"Stepping down the ReplicationCoordinator for shutdown","attr":{"waitTimeMillis":15000}}
947:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"REPL",     "id":4794602, "ctx":"initandlisten","msg":"Attempting to enter quiesce mode"}
948:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"-",        "id":6371601, "ctx":"initandlisten","msg":"Shutting down the FLE Crud thread pool"}
949:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"COMMAND",  "id":4784901, "ctx":"initandlisten","msg":"Shutting down the MirrorMaestro"}
950:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"SHARDING", "id":4784902, "ctx":"initandlisten","msg":"Shutting down the WaitForMajorityService"}
951:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"NETWORK",  "id":4784905, "ctx":"initandlisten","msg":"Shutting down the global connection pool"}
952:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"NETWORK",  "id":4784918, "ctx":"initandlisten","msg":"Shutting down the ReplicaSetMonitor"}
953:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"SHARDING", "id":9439300, "ctx":"initandlisten","msg":"Shutting down the filtering metadata cache"}
954:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"CONTROL",  "id":4784928, "ctx":"initandlisten","msg":"Shutting down the TTL monitor"}
955:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"CONTROL",  "id":4784929, "ctx":"initandlisten","msg":"Acquiring the global lock for shutdown"}
956:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"-",        "id":4784931, "ctx":"initandlisten","msg":"Dropping the scope cache for shutdown"}
957:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"-",        "id":10175800,"ctx":"initandlisten","msg":"Shutting down the standalone executor"}
958:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"CONTROL",  "id":20565,   "ctx":"initandlisten","msg":"Now exiting"}
959:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"CONTROL",  "id":8423404, "ctx":"initandlisten","msg":"mongod shutdown complete","attr":{"Summary of time elapsed":{"Statistics":{"enterTerminalShutdownMillis":0,"stepDownReplCoordMillis":0,"quiesceModeMillis":0,"stopFLECrudMillis":0,"shutDownMirrorMaestroMillis":0,"shutDownWaitForMajorityServiceMillis":0,"shutDownGlobalConnectionPoolMillis":0,"shutDownSearchTaskExecutorsMillis":0,"shutDownReplicaSetMonitorMillis":0,"shutDownTTLMonitorMillis":0,"shutDownExpiredDocumentRemoverMillis":0,"shutDownOtelMetricsMillis":0,"shutDownFTDCMillis":0,"shutDownReplicaSetNodeExecutorMillis":0,"shutDownOCSPMillis":0,"shutdownTaskTotalMillis":0}}}}
960:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"CONTROL",  "id":23138,   "ctx":"initandlisten","msg":"Shutting down","attr":{"exitCode":48}}
961:  from /home/ubuntu/_work/eventuous/eventuous/src/Mongo/test/Eventuous.Tests.Projections.MongoDB/bin/Debug CI/net8.0/Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)
962:  �[31m  TUnit.Engine.Exceptions.TestFailedException: ContainerNotRunningException: Container ef222c6e87c1106b20297b22ac8937f2a55f1361b2731b8b647353d71e12efbf exited with code 48.
963:  Stdout: 
964:  {"t":{"$date":"2026-03-10T11:33:52.234+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":3308180877}}
965:  about to fork child process, waiting until server is ready for connections.
966:  forked process: 28
967:  {"t":{"$date":"2026-03-10T11:33:52.242+00:00"},"s":"I",  "c":"CONTROL",  "id":20698,   "ctx":"main","msg":"***** SERVER RESTARTED *****"}
968:  {"t":{"$date":"2026-03-10T11:33:52.245+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
969:  {"t":{"$date":"2026-03-10T11:33:52.251+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
970:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
971:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":28,"port":27017,"dbPath":"/data/db","architecture":"64-bit","host":"ef222c6e87c1"}}
972:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":23403,   "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"8.2.5","gitVersion":"a471a13094434666c48a1f75451f2efa49f8f5df","openSSLVersion":"OpenSSL 3.0.13 30 Jan 2024","modules":[],"allocator":"tcmalloc-google","environment":{"distmod":"ubuntu2404","distarch":"x86_64","target_arch":"x86_64"}}}}
973:  {"t":{"$date":"2026-03-10T11:33:52.252+00:00"},"s":"I",  "c":"CONTROL",  "id":51765,   "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"24.04"}}}
974:  {"t":{"$date":"2026-03-10T11:33:52.252+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":27017,"tls":{"mode":"disabled"}},"processManagement":{"fork":true,"pidFilePath":"/tmp/docker-entrypoint-temp-mongod.pid"},"systemLog":{"destination":"file","logAppend":true,"path":"/proc/1/fd/1"}}}}
975:  {"t":{"$date":"2026-03-10T11:33:52.253+00:00"},"s":"I",  "c":"NETWORK",  "id":4648601, "ctx":"initandlisten","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set at least one of the related parameters","attr":{"relatedParameters":["tcpFastOpenServer","tcpFastOpenClient","tcpFastOpenQueueSize"]}}
976:  {"t":{"$date":"2026-03-10T11:33:52.254+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"]}
977:  {"t":{"$date":"2026-03-10T11:33:52.254+00:00"},"s":"I",  "c":"STORAGE",  "id":22315,   "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=11214M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,remove=true,path=journal,compressor=snappy),builtin_extension_config=(zstd=(compression_level=6)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,live_restore_progress:1,backup:0,checkpoint:0,compact:0,eviction:0,fileops:0,history_store:0,live_restore:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],prefetch=(available=true,default=false),"}}
978:  {"t":{"$date":"2026-03-10T11:33:52.266+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":266116,"thread":"28:0x7f0184f1d400","session_name":"wiredtiger_open","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"opening the WiredTiger library"}}}
...

980:  {"t":{"$date":"2026-03-10T11:33:52.320+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":320635,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting WiredTiger utility threads"}}}
981:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323289,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting WiredTiger recovery"}}}
982:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323563,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"scanning metadata to find the largest file ID"}}}
983:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323598,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"largest file ID found in the metadata 0"}}}
984:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323614,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery log replay has successfully finished and ran for 0 milliseconds"}}}
985:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323637,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1000000,"category_id":33,"verbose_level":"DEBUG_1","verbose_level_id":1,"msg":"Set global recovery timestamp: (0, 0)"}}}
986:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323649,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1000000,"category_id":33,"verbose_level":"DEBUG_1","verbose_level_id":1,"msg":"Set global oldest timestamp: (0, 0)"}}}
987:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323690,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY","log_id":1493201,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery was completed successfully and took 0ms, including 0ms for the log replay, 0ms for the rollback to stable, and 0ms for the checkpoint."}}}
988:  {"t":{"$date":"2026-03-10T11:33:52.323+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":323715,"thread":"28:0x7f0184f1d400","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","log_id":1493201,"category_id":33,"verbose_level":"INFO","verbose_level_id":0,"msg":"recovery was completed successfully and took 0ms, including 0ms for the log replay, 0ms for the rollback to stable, and 0ms for the checkpoint."}}}
989:  {"t":{"$date":"2026-03-10T11:33:52.327+00:00"},"s":"I",  "c":"WTEVICT",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":327589,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_EVICTION","log_id":1000000,"category_id":15,"verbose_level":"INFO","verbose_level_id":0,"msg":"starting eviction threads"}}}
990:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":331160,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"WiredTiger utility threads started successfully"}}}
991:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":331203,"thread":"28:0x7f0184f1d400","session_name":"connection","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"the WiredTiger library has successfully opened"}}}
992:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"STORAGE",  "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":77}}
993:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"RECOVERY", "id":23987,   "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}}
994:  {"t":{"$date":"2026-03-10T11:33:52.331+00:00"},"s":"I",  "c":"STORAGE",  "id":9086700, "ctx":"initandlisten","msg":"WiredTiger session cache max value has been set","attr":{"sessionCacheMax":16500}}
995:  {"t":{"$date":"2026-03-10T11:33:52.334+00:00"},"s":"I",  "c":"STORAGE",  "id":10158000,"ctx":"initandlisten","msg":"Opening spill WiredTiger","attr":{"config":"create,cache_size=586M,session_max=1024,eviction=(threads_min=1,threads_max=1),eviction_dirty_target=29MB,eviction_dirty_trigger=468MB,eviction_updates_trigger=468MB,config_base=false,statistics=(fast),log=(enabled=false),builtin_extension_config=(zstd=(compression_level=-7)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,live_restore_progress:1,backup:0,checkpoint:0,compact:0,eviction:0,fileops:0,history_store:0,live_restore:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],"}}
996:  {"t":{"$date":"2026-03-10T11:33:52.342+00:00"},"s":"I",  "c":"WTRECOV",  "id":22430,   "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142432,"ts_usec":340278,"thread":"28:0x7f0184f1d400","session_name":"wiredtiger_open","category":"WT_VERB_RECOVERY","log_id":1000000,"category_id":32,"verbose_level":"INFO","verbose_level_id":0,"msg":"opening the WiredTiger library"}}}
...

1025:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"REPL",     "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"8.2","context":"startup"}}
1026:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"STORAGE",  "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"}
1027:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"STORAGE",  "id":10682200,"ctx":"initandlisten","msg":"Dropping spill idents","attr":{"numIdents":0}}
1028:  {"t":{"$date":"2026-03-10T11:33:52.401+00:00"},"s":"I",  "c":"CONTROL",  "id":6608200, "ctx":"initandlisten","msg":"Initializing cluster server parameters from disk"}
1029:  {"t":{"$date":"2026-03-10T11:33:52.402+00:00"},"s":"I",  "c":"CONTROL",  "id":20536,   "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"}
1030:  {"t":{"$date":"2026-03-10T11:33:52.402+00:00"},"s":"I",  "c":"FTDC",     "id":20625,   "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/data/db/diagnostic.data"}}
1031:  {"t":{"$date":"2026-03-10T11:33:52.404+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"initandlisten","msg":"createCollection","attr":{"namespace":"local.startup_log","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"0da524d5-c789-441b-8515-3a2b2e6387b1"}},"options":{"capped":true,"size":10485760}}}
1032:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"initandlisten","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"0da524d5-c789-441b-8515-3a2b2e6387b1"}},"namespace":"local.startup_log","index":"_id_","ident":"index-9c78bc10-9796-47c3-ae3a-a3d446cdf1d3","collectionIdent":"collection-3feaa191-d1ee-4c8a-bd52-288c60f3f628","commitTimestamp":null}}
1033:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"REPL",     "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigReplicationDisabled","oldState":"ConfigPreStart"}}
1034:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"STORAGE",  "id":22262,   "ctx":"initandlisten","msg":"Timestamp monitor starting"}
1035:  {"t":{"$date":"2026-03-10T11:33:52.413+00:00"},"s":"I",  "c":"STORAGE",  "id":7333401, "ctx":"initandlisten","msg":"Starting the DiskSpaceMonitor"}
1036:  {"t":{"$date":"2026-03-10T11:33:52.417+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"LogicalSessionCacheRefresh","msg":"createCollection","attr":{"namespace":"config.system.sessions","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"a201f414-95c8-4bb9-8401-696af0833aa3"}},"options":{}}}
1037:  {"t":{"$date":"2026-03-10T11:33:52.418+00:00"},"s":"I",  "c":"NETWORK",  "id":23015,   "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-27017.sock"}}
1038:  {"t":{"$date":"2026-03-10T11:33:52.419+00:00"},"s":"I",  "c":"NETWORK",  "id":23015,   "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1:27017"}}
1039:  {"t":{"$date":"2026-03-10T11:33:52.419+00:00"},"s":"I",  "c":"NETWORK",  "id":23016,   "ctx":"listener","msg":"Waiting for connections","attr":{"port":27017,"ssl":"off"}}
1040:  {"t":{"$date":"2026-03-10T11:33:52.419+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"}}
1041:  {"t":{"$date":"2026-03-10T11:33:52.420+00:00"},"s":"I",  "c":"CONTROL",  "id":8423403, "ctx":"initandlisten","msg":"mongod startup complete","attr":{"Summary of time elapsed":{"Startup from clean shutdown?":true,"Statistics":{"setUpPeriodicRunnerMillis":0,"setUpOCSPMillis":0,"setUpTransportLayerMillis":1,"initSyncCrashRecoveryMillis":0,"createLockFileMillis":0,"getStorageEngineMetadataMillis":0,"createStorageEngineMillis":125,"writePIDMillis":0,"writeNewMetadataMillis":0,"initializeFCVForIndexMillis":0,"dropAbandonedIdentsMillis":0,"standaloneClusterParamsMillis":1,"userAndRolesGraphMillis":0,"waitForMajorityServiceMillis":0,"startUpReplCoordMillis":0,"recoverChangeStreamMillis":0,"logStartupOptionsMillis":0,"startUpTransportLayerMillis":1,"initAndListenTotalMillis":167}}}}
...

1075:  {"t":{"$date":"2026-03-10T11:33:53.568+00:00"},"s":"I",  "c":"NETWORK",  "id":6788700, "ctx":"conn8","msg":"Received first command on ingress connection since session start or auth handshake","attr":{"elapsedMillis":6}}
1076:  admin> | | | | {"t":{"$date":"2026-03-10T11:33:53.749+00:00"},"s":"I",  "c":"STORAGE",  "id":20320,   "ctx":"conn8","msg":"createCollection","attr":{"namespace":"admin.system.users","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"options":{}}}
1077:  {"t":{"$date":"2026-03-10T11:33:53.799+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"conn8","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"namespace":"admin.system.users","index":"_id_","ident":"index-b44a2847-beae-46e1-b01a-31f707f512f9","collectionIdent":"collection-fc31101f-bc0d-4892-8e3e-037dd9199e6e","commitTimestamp":null}}
1078:  {"t":{"$date":"2026-03-10T11:33:53.799+00:00"},"s":"I",  "c":"INDEX",    "id":20345,   "ctx":"conn8","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"9e9df9c9-6119-4186-a5c0-ba46508fd497"}},"namespace":"admin.system.users","index":"user_1_db_1","ident":"index-5a26a76a-cc86-4f70-bd40-7f4b136793cf","collectionIdent":"collection-fc31101f-bc0d-4892-8e3e-037dd9199e6e","commitTimestamp":null}}
1079:  { ok: 1 }
1080:  admin> {"t":{"$date":"2026-03-10T11:33:53.808+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn6","msg":"Connection ended","attr":{"remote":"127.0.0.1:60238","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"c6fb3f89-087d-4ab9-8591-b534bb1fb3a5"}},"connectionId":6,"connectionCount":3}}
1081:  {"t":{"$date":"2026-03-10T11:33:53.816+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn5","msg":"Connection ended","attr":{"remote":"127.0.0.1:60216","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"9c44f813-0878-4fe6-aa13-4cd51cd1835d"}},"connectionId":5,"connectionCount":2}}
1082:  {"t":{"$date":"2026-03-10T11:33:53.818+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn7","msg":"Connection ended","attr":{"remote":"127.0.0.1:60258","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"68b5db1d-310f-405f-8881-9c650c758a01"}},"connectionId":7,"connectionCount":1}}
1083:  {"t":{"$date":"2026-03-10T11:33:53.818+00:00"},"s":"I",  "c":"NETWORK",  "id":22944,   "ctx":"conn8","msg":"Connection ended","attr":{"remote":"127.0.0.1:60268","isLoadBalanced":false,"uuid":{"uuid":{"$uuid":"a7abedde-3fbd-4b09-9308-53611af85f00"}},"connectionId":8,"connectionCount":0}}
1084:  /usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/*
1085:  {"t":{"$date":"2026-03-10T11:33:53.873+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":2237125458}}
1086:  {"t":{"$date":"2026-03-10T11:33:53.878+00:00"},"s":"I",  "c":"CONTROL",  "id":20698,   "ctx":"main","msg":"***** SERVER RESTARTED *****"}
1087:  {"t":{"$date":"2026-03-10T11:33:53.883+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
1088:  {"t":{"$date":"2026-03-10T11:33:53.884+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
1089:  Killing process with pid: 28
1090:  {"t":{"$date":"2026-03-10T11:33:53.887+00:00"},"s":"I",  "c":"CONTROL",  "id":23377,   "ctx":"SignalHandler","msg":"Received signal","attr":{"signal":15,"error":"Terminated"}}
1091:  {"t":{"$date":"2026-03-10T11:33:53.887+00:00"},"s":"I",  "c":"CONTROL",  "id":23378,   "ctx":"SignalHandler","msg":"Signal was sent by kill(2)","attr":{"pid":119,"uid":999}}
...

1160:  {"t":{"$date":"2026-03-10T11:33:53.992+00:00"},"s":"I",  "c":"WTEVICT",  "id":22430,   "ctx":"thread41","msg":"WiredTiger message","attr":{"message":{"ts_sec":1773142433,"ts_usec":992409,"thread":"28:0x7f017f6f06c0","session_name":"eviction-server","category":"WT_VERB_EVICTION","log_id":1000000,"category_id":15,"verbose_level":"INFO","verbose_level_id":0,"msg":"eviction thread exiting"}}}
1161:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"STORAGE",  "id":4795901, "ctx":"SignalHandler","msg":"WiredTiger closed","attr":{"durationMillis":42}}
1162:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"STORAGE",  "id":22279,   "ctx":"SignalHandler","msg":"shutdown: removing fs lock..."}
1163:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"-",        "id":4784931, "ctx":"SignalHandler","msg":"Dropping the scope cache for shutdown"}
1164:  {"t":{"$date":"2026-03-10T11:33:54.004+00:00"},"s":"I",  "c":"FTDC",     "id":20626,   "ctx":"SignalHandler","msg":"Shutting down full-time diagnostic data capture"}
1165:  MongoDB init process complete; ready for start up.
1166:  {"t":{"$date":"2026-03-10T11:33:54.928+00:00"},"s":"I",  "c":"-",        "id":8991200, "ctx":"main","msg":"Shuffling initializers","attr":{"seed":4255525087}}
1167:  {"t":{"$date":"2026-03-10T11:33:54.936+00:00"},"s":"I",  "c":"CONTROL",  "id":97374,   "ctx":"main","msg":"Automatically disabling TLS 1.0 and TLS 1.1, to force-enable TLS 1.1 specify --sslDisabledProtocols 'TLS1_0'; to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"}
1168:  {"t":{"$date":"2026-03-10T11:33:54.937+00:00"},"s":"I",  "c":"NETWORK",  "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":27},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":27},"outgoing":{"minWireVersion":6,"maxWireVersion":27},"isInternalClient":true}}}
1169:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":5945603, "ctx":"main","msg":"Multi threading initialized"}
1170:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":1,"port":27017,"dbPath":"/data/db","architecture":"64-bit","host":"ef222c6e87c1"}}
1171:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":23403,   "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"8.2.5","gitVersion":"a471a13094434666c48a1f75451f2efa49f8f5df","openSSLVersion":"OpenSSL 3.0.13 30 Jan 2024","modules":[],"allocator":"tcmalloc-google","environment":{"distmod":"ubuntu2404","distarch":"x86_64","target_arch":"x86_64"}}}}
1172:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":51765,   "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Ubuntu","version":"24.04"}}}
1173:  {"t":{"$date":"2026-03-10T11:33:54.938+00:00"},"s":"I",  "c":"CONTROL",  "id":21951,   "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"net":{"bindIp":"*"},"security":{"authorization":"enabled"}}}}
1174:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"NETWORK",  "id":4648601, "ctx":"initandlisten","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set at least one of the related parameters","attr":{"relatedParameters":["tcpFastOpenServer","tcpFastOpenClient","tcpFastOpenQueueSize"]}}
1175:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"E",  "c":"CONTROL",  "id":20568,   "ctx":"initandlisten","msg":"Error setting up transport layer","attr":{"error":{"code":9001,"codeName":"SocketException","errmsg":"0.0.0.0:27017 :: caused by :: setup bind :: caused by :: Address already in use"}}}
1176:  {"t":{"$date":"2026-03-10T11:33:54.939+00:00"},"s":"I",  "c":"REPL",     "id":4784900, "ctx":"initandlisten","msg":"Stepping down the ReplicationCoordinator for shutdown","attr":{"waitTimeMillis":15000}}
...

1203:  Server Version: 29.3.0
1204:  Kernel Version: 5.15.0-170-generic
1205:  API Version: 1.54
1206:  Operating System: Ubuntu 22.04.5 LTS
1207:  Total Memory: 22.90 GB
1208:  [testcontainers.org 00:00:45.17] Docker image kurrentplatform/kurrentdb:25.1.3 created
1209:  [testcontainers.org 00:00:45.44] Docker container 4df2436713d4 created
1210:  [testcontainers.org 00:00:45.45] Start Docker container 4df2436713d4
1211:  [testcontainers.org 00:00:45.78] Wait for Docker container 4df2436713d4 to complete readiness checks
1212:  [testcontainers.org 00:00:51.80] Docker container 4df2436713d4 ready
1213:  [testcontainers.org 00:01:06.53] Docker image mongo:8 created
1214:  [testcontainers.org 00:01:06.69] Docker container ef222c6e87c1 created
1215:  [testcontainers.org 00:01:06.69] Start Docker container ef222c6e87c1
1216:  [testcontainers.org 00:01:07.02] Wait for Docker container ef222c6e87c1 to complete readiness checks
1217:  [testcontainers.org 00:01:10.28] Delete Docker container 4df2436713d4
1218:  Error output
1219:  �[m[�[32m+5�[m/�[31mx1�[m/�[33m?0�[m] Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)(1m 11s)
...

1252:  [�[32m+1�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.GooglePubSub.dll (net8.0|x64)(1m 26s)
1253:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 26s)
1254:  [�[32m+28�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 26s)
1255:  [�[32m+7�[m/�[31mx1�[m/�[33m?0�[m] Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)(1m 28s)
1256:  [�[32m+8�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.SqlServer.dll (net8.0|x64)(1m 27s)
1257:  [�[32m+13�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.KurrentDB.dll (net8.0|x64)(1m 29s)
1258:  [�[32m+1�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.GooglePubSub.dll (net8.0|x64)(1m 29s)
1259:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 29s)
1260:  [�[32m+29�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 29s)
1261:  [�[32m+7�[m/�[31mx1�[m/�[33m?0�[m] Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64)(1m 31s)
1262:  [�[32m+10�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.SqlServer.dll (net8.0|x64)(1m 30s)
1263:  [�[32m+14�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.KurrentDB.dll (net8.0|x64)(1m 32s)
1264:  [�[32m+1�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.GooglePubSub.dll (net8.0|x64)(1m 32s)
1265:  [�[32m+43�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64)(1m 32s)
1266:  [�[32m+29�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.Postgres.dll (net8.0|x64)(1m 32s)
1267:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Mongo/test/Eventuous.Tests.Projections.MongoDB/bin/Debug CI/net8.0/Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64) �[31mfailed with 1 error(s)�[m �[90m(1m 32s 336ms)�[m
1268:  [�[32m+10�[m/�[31mx0�[m/�[33m?0�[m] Eventuous.Tests.SqlServer.dll (net8.0|x64)(1m 30s)
...

1414:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_45.4179728.trx
1415:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_45.5837099.trx
1416:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_46.2308387.trx
1417:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_46.3273647.trx
1418:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_46.4581036.trx
1419:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_32_58.3937699.trx
1420:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_33_31.7380073.trx
1421:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_33_42.5327429.trx
1422:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_33_54.1620588.trx
1423:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_34_16.9065564.trx
1424:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_34_45.6331854.trx
1425:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_34_45.9290861.trx
1426:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_35_21.8712675.trx
1427:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_35_45.9948108.trx
1428:  - /home/ubuntu/_work/eventuous/eventuous/test-results/net8.0/_github-hetzner-runner-22900360812-66445074295_2026-03-10_11_36_08.4768430.trx
1429:  �[31mTest run summary: Failed!
1430:  �[m/home/ubuntu/_work/eventuous/eventuous/src/GooglePubSub/test/Eventuous.Tests.GooglePubSub/bin/Debug CI/net8.0/Eventuous.Tests.GooglePubSub.dll (net8.0|x64) �[32mpassed�[m �[90m(2m 01s 841ms)�[m
1431:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Core/test/Eventuous.Tests.Application/bin/Debug CI/net8.0/Eventuous.Tests.Application.dll (net8.0|x64) �[32mpassed�[m �[90m(576ms)�[m
1432:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Gateway/test/Eventuous.Tests.Gateway/bin/Debug CI/net8.0/Eventuous.Tests.Gateway.dll (net8.0|x64) �[32mpassed�[m �[90m(411ms)�[m
1433:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Redis/test/Eventuous.Tests.Redis/bin/Debug CI/net8.0/Eventuous.Tests.Redis.dll (net8.0|x64) �[32mpassed�[m �[90m(47s 901ms)�[m
1434:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Mongo/test/Eventuous.Tests.Projections.MongoDB/bin/Debug CI/net8.0/Eventuous.Tests.Projections.MongoDB.dll (net8.0|x64) �[31mfailed with 1 error(s)�[m �[90m(1m 32s 336ms)�[m
1435:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Extensions/test/Eventuous.Tests.DependencyInjection/bin/Debug CI/net8.0/Eventuous.Tests.DependencyInjection.dll (net8.0|x64) �[32mpassed�[m �[90m(420ms)�[m
1436:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Sqlite/test/Eventuous.Tests.Sqlite/bin/Debug CI/net8.0/Eventuous.Tests.Sqlite.dll (net8.0|x64) �[32mpassed�[m �[90m(13s 491ms)�[m
1437:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Experimental/test/Eventuous.Tests.Spyglass/bin/Debug CI/net8.0/Eventuous.Tests.Spyglass.dll (net8.0|x64) �[32mpassed�[m �[90m(715ms)�[m
1438:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Postgres/test/Eventuous.Tests.Postgres/bin/Debug CI/net8.0/Eventuous.Tests.Postgres.dll (net8.0|x64) �[32mpassed�[m �[90m(2m 02s 132ms)�[m
1439:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Azure/test/Eventuous.Tests.Azure.ServiceBus/bin/Debug CI/net8.0/Eventuous.Tests.Azure.ServiceBus.dll (net8.0|x64) �[32mpassed�[m �[90m(2m 38s 085ms)�[m
1440:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Core/test/Eventuous.Tests.Subscriptions/bin/Debug CI/net8.0/Eventuous.Tests.Subscriptions.dll (net8.0|x64) �[32mpassed�[m �[90m(893ms)�[m
1441:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Extensions/test/Eventuous.Tests.Extensions.AspNetCore.Analyzers/bin/Debug CI/net8.0/Eventuous.Tests.Extensions.AspNetCore.Analyzers.dll (net8.0|x64) �[32mpassed�[m �[90m(1s 146ms)�[m
1442:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Extensions/test/Eventuous.Tests.Extensions.AspNetCore/bin/Debug CI/net8.0/Eventuous.Tests.Extensions.AspNetCore.dll (net8.0|x64) �[32mpassed�[m �[90m(1s 152ms)�[m
1443:  �[m/home/ubuntu/_work/eventuous/eventuous/src/KurrentDB/test/Eventuous.Tests.KurrentDB/bin/Debug CI/net8.0/Eventuous.Tests.KurrentDB.dll (net8.0|x64) �[32mpassed�[m �[90m(3m 02s 203ms)�[m
1444:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Core/test/Eventuous.Tests.Shared.Analyzers/bin/Debug CI/net8.0/Eventuous.Tests.Shared.Analyzers.dll (net8.0|x64) �[32mpassed�[m �[90m(1s 188ms)�[m
1445:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Experimental/test/Eventuous.Tests.Spyglass.Generators/bin/Debug CI/net8.0/Eventuous.Tests.Spyglass.Generators.dll (net8.0|x64) �[32mpassed�[m �[90m(808ms)�[m
1446:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Kafka/test/Eventuous.Tests.Kafka/bin/Debug CI/net8.0/Eventuous.Tests.Kafka.dll (net8.0|x64) �[32mpassed�[m �[90m(58s 722ms)�[m
1447:  �[m/home/ubuntu/_work/eventuous/eventuous/src/Core/test/Eventuous.Tests/bin/Debug CI/net8.0/Eventuous.Tests.dll (net8.0|x64) �[32mpassed�[m �[90m(580ms)�[m
1448:  �[m/home/ubuntu/_work/eventuous/eventuous/src/SqlServer/test/Eventuous.Tests.SqlServer/bin/Debug CI/net8.0/Eventuous.Tests.SqlServer.dll (net8.0|x64) �[32mpassed�[m �[90m(3m 22s 076ms)�[m
1449:  �[m/home/ubuntu/_work/eventuous/eventuous/src/RabbitMq/test/Eventuous.Tests.RabbitMq/bin/Debug CI/net8.0/Eventuous.Tests.RabbitMq.dll (net8.0|x64) �[32mpassed�[m �[90m(1m 10s 373ms)�[m
1450:  �[m  total: 345
1451:  �[31m  failed: 1
1452:  �[m  succeeded: 344
1453:  skipped: 0
1454:  duration: 3m 25s 025ms
1455:  Test run completed with non-success exit code: 2 (see: https://aka.ms/testingplatform/exitcodes)
1456:  ##[error]Process completed with exit code 2.
1457:  ##[group]Run actions/upload-artifact@v7

@alexeyzimarev alexeyzimarev merged commit 3e3e7eb into dev Mar 10, 2026
3 of 10 checks passed
@alexeyzimarev alexeyzimarev deleted the feat/stream-event-created branch March 10, 2026 11:45
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Labels

None yet

Projects

None yet

Development

Successfully merging this pull request may close these issues.

1 participant