2025-12-12T11:57:40.737440Z :DEBUG: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session: try to update token
2025-12-12T11:57:40.737642Z :INFO: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Start write session. Will connect to nodeId: 0
2025-12-12T11:57:40.744314Z :DEBUG: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session: write to message_group: test-message_group_id
2025-12-12T11:57:40.744384Z :DEBUG: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session: send init request: init_request { path: "sdk_tests/TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction/test-topic" message_group_id: "test-message_group_id" }
2025-12-12T11:57:40.744539Z :DEBUG: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session: OnWriteDone gRpcStatusCode: 0
2025-12-12T11:57:40.753706Z :DEBUG: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session: OnReadDone gRpcStatusCode: 0
2025-12-12T11:57:40.753729Z :INFO: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Counters: { Errors: 0 CurrentSessionLifetimeMs: 1765540660753 BytesWritten: 0 MessagesWritten: 0 BytesWrittenCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-12-12T11:57:40.753755Z :INFO: [local]  TraceId []  SessionId []  MessageGroupId [test-message_group_id] Write session established. Init response: session_id: "test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0"
2025-12-12T11:57:40.753759Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  MessageGroupId [test-message_group_id] Write session: set DirectWriteToPartitionId 0
2025-12-12T11:57:40.753789Z :INFO: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [0] Get partition location async, partition 0, delay 0.000000s
2025-12-12T11:57:40.753856Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [0]  Getting partition location, partition 0
2025-12-12T11:57:40.755964Z :INFO: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [0] Got PartitionLocation response. Status SUCCESS, proto:
partition {
  active: true
  partition_location {
    node_id: 1
    generation: 1
  }
}
2025-12-12T11:57:40.755984Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [0] GetPreferredEndpoint: partitionId 0, partitionNodeId 1 exists in the endpoint pool.
2025-12-12T11:57:40.755991Z :INFO: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Start write session. Will connect to nodeId: 1
2025-12-12T11:57:40.756322Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session: OnReadDone gRpcStatusCode: 1, Msg: CANCELLED, Details: , InternalError: 0
2025-12-12T11:57:40.756471Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session: direct write to partition: 0, generation 1
2025-12-12T11:57:40.756505Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session: send init request: init_request { path: "sdk_tests/TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction/test-topic" partition_with_generation { generation: 1 } }
2025-12-12T11:57:40.756627Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session: OnWriteDone gRpcStatusCode: 0
2025-12-12T11:57:40.757862Z :DEBUG: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session: OnReadDone gRpcStatusCode: 0
2025-12-12T11:57:40.757895Z :INFO: [local]  TraceId []  SessionId [test-message_group_id|dc7ae77d-d1a56d5e-50e999fd-24e8e9d9_0]  PartitionId [0]  Generation [1] Write session established. Init response: session_id: "3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0"
2025-12-12T11:57:40.757901Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: set DirectWriteToPartitionId 0
2025-12-12T11:57:40.757973Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write 1 messages with Id from 1 to 1
2025-12-12T11:57:40.758004Z :INFO: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: close. Timeout 18446744073709.551615s
2025-12-12T11:57:40.758222Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: try to update token
2025-12-12T11:57:40.758241Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Send 1 message(s) (0 left), first sequence number is 1
2025-12-12T11:57:40.760106Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: OnReadDone gRpcStatusCode: 0
2025-12-12T11:57:40.760160Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session got write response: acks { seq_no: 1 written { } } write_statistics { persisting_time { nanos: 1000000 } min_queue_wait_time { } max_queue_wait_time { } partition_quota_wait_time { } topic_quota_wait_time { } }
2025-12-12T11:57:40.760194Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] OnAck: seqNo=1, txId=?
2025-12-12T11:57:40.760199Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: acknoledged message 1
2025-12-12T11:57:40.858109Z :INFO: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session will now close
2025-12-12T11:57:40.858134Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: aborting
2025-12-12T11:57:40.858364Z :INFO: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: gracefully shut down, all writes complete
2025-12-12T11:57:40.858376Z :DEBUG: [local]  TraceId []  SessionId [3b7faa00-ac5819e1-a9b22fba-7303f668|cab2cf05-aaa24c87-96d060d2-ebb7559_0]  PartitionId [0]  Generation [1] Write session: destroy
2025-12-12T11:57:40.861121Z :INFO: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Starting read session
2025-12-12T11:57:40.861136Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Starting single session
2025-12-12T11:57:40.861287Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] In Reconnect, ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-12T11:57:40.861290Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] New values: ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-12T11:57:40.861294Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Reconnecting session to cluster  in 0.000000s
2025-12-12T11:57:40.861475Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Successfully connected. Initializing session
2025-12-12T11:57:40.862175Z :INFO: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Got InitResponse. ReadSessionId: TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1
2025-12-12T11:57:40.862192Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] In ContinueReadingDataImpl, ReadSizeBudget = 52428800, ReadSizeServerDelta = 0
2025-12-12T11:57:40.862268Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 52428800
2025-12-12T11:57:40.863485Z :INFO: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Confirm partition stream create. Partition stream id: 1. Cluster: "-". Topic: "sdk_tests/TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction/test-topic". Partition: 0. Read offset: (NULL)
2025-12-12T11:57:40.863557Z :DEBUG: [local] 0x000073AEFF74A110 TDirectReadSessionManager ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 StartPartitionSession 1 nodeId=1
2025-12-12T11:57:40.863569Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 Reconnecting direct read session to node 1 in 0.000000s
2025-12-12T11:57:40.863672Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 AddPartitionSession 1
2025-12-12T11:57:40.863675Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 SendStartRequestImpl partitionSession.State=0 delayedCall=0
2025-12-12T11:57:40.863678Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 SendStartRequestImpl bail out 1
2025-12-12T11:57:40.863701Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 Successfully connected. Initializing session
2025-12-12T11:57:40.863748Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 XXXXX subsession send message = init_request { session_id: "TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1" topics_read_settings { path: "sdk_tests/TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction/test-topic" } consumer: "TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer" }
2025-12-12T11:57:40.864167Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 XXXXX subsession got message = status: SUCCESS init_response { }
2025-12-12T11:57:40.864209Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 Got InitResponse 
2025-12-12T11:57:40.864213Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 SendStartRequestImpl partitionSession.State=0 delayedCall=0
2025-12-12T11:57:40.864225Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 SendStartRequestImpl send request start_direct_read_partition_session_request { partition_session_id: 1 generation: 1 }
2025-12-12T11:57:40.864229Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 XXXXX subsession send message = start_direct_read_partition_session_request { partition_session_id: 1 generation: 1 }
2025-12-12T11:57:40.864526Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 XXXXX subsession got message = status: SUCCESS start_direct_read_partition_session_response { partition_session_id: 1 generation: 1 }
2025-12-12T11:57:40.864536Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 Got StartDirectReadPartitionSessionResponse partition_session_id: 1 generation: 1
2025-12-12T11:57:40.864600Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 XXXXX subsession got message = DirectReadResponse partitionSessionId = 1 partitionId = 0 directReadId = 1 firstOffset = 0 lastOffset = 0
2025-12-12T11:57:40.864605Z :DEBUG: [local] 0x000073AEFFBC3CA0 TDirectReadSession ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 NodeId=1 Waiting for NextDirectReadId=1. Got DirectReadResponse 1
2025-12-12T11:57:40.864662Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Got ReadResponse, serverBytesSize = 159, now ReadSizeBudget = 0, ReadSizeServerDelta = 52428641
2025-12-12T11:57:40.864677Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] In ContinueReadingDataImpl, ReadSizeBudget = 0, ReadSizeServerDelta = 52428641
2025-12-12T11:57:40.864731Z :DEBUG: [local] Decompression task done. Partition/PartitionSessionId: 1 (0-0)
2025-12-12T11:57:40.864744Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Returning serverBytesSize = 159 to budget
2025-12-12T11:57:40.864748Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] In ContinueReadingDataImpl, ReadSizeBudget = 159, ReadSizeServerDelta = 52428641
2025-12-12T11:57:40.864823Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] After sending read request: ReadSizeBudget = 0, ReadSizeServerDelta = 52428800
2025-12-12T11:57:40.864856Z :DEBUG: [local] Take Data. Partition 0. Read: {0, 0} (0-0)
2025-12-12T11:57:40.864871Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] The application data is transferred to the client. Number of messages 1, size 7 bytes
2025-12-12T11:57:40.864877Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Returning serverBytesSize = 0 to budget
2025-12-12T11:57:40.864988Z :INFO: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Closing read session. Close timeout: 0.000000s
2025-12-12T11:57:40.864994Z :INFO: [local] Read/commit by partition streams (cluster:topic:partition:stream-id:read-offset:committed-offset): -:sdk_tests/TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction/test-topic:0:1:0:0
2025-12-12T11:57:40.864998Z :INFO: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Counters: { Errors: 0 CurrentSessionLifetimeMs: 3 BytesRead: 7 MessagesRead: 1 BytesReadCompressed: 27 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2025-12-12T11:57:40.865012Z :NOTICE: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Close with zero timeout " }
2025-12-12T11:57:40.865016Z :DEBUG: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] [] Abort session to cluster
2025-12-12T11:57:40.865138Z :DEBUG: [local] 0x000073AEFF74A110 TDirectReadSessionManager ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 Close
2025-12-12T11:57:40.865193Z :DEBUG: [local] 0x000073AEFF74A110 TDirectReadSessionManager ServerSessionId=TxUsageTable-DirectRead_Offsets_Cannot_Be_Promoted_When_Reading_In_A_Transaction-test-consumer_1_11_13649627272515058841_v1 Close
2025-12-12T11:57:40.865202Z :NOTICE: [local] [local] [f37bee8b-96efded0-9b90d6c5-3fc92f4e] Aborting read session. Description: SessionClosed { Status: ABORTED Issues: "<main>: Error: Aborted " }
