2026-03-17T14:14:44.698040Z node 9 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[9:7618227811851763956:2085];send_to=[0:7307199536658146131:7762515];
2026-03-17T14:14:44.698330Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:85;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2026-03-17T14:14:44.699984Z node 10 :METADATA_PROVIDER WARN: log.cpp:841: fline=table_exists.cpp:54;actor=TTableExistsActor;event=undelivered;self_id=[10:7618227810275936282:2085];send_to=[0:7307199536658146131:7762515];
2026-03-17T14:14:44.700176Z node 10 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:85;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=scheme_cache_undelivered_message;
2026-03-17T14:14:44.700804Z node 9 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2026-03-17T14:14:44.700983Z node 9 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2026-03-17T14:14:44.701781Z node 10 :PQ_READ_PROXY DEBUG: caching_service.cpp:44: Direct read cache: : Created
2026-03-17T14:14:44.702142Z node 10 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: LookupError, path: Root/.metadata/script_executions
2026-03-17T14:14:44.717782Z node 9 :IMPORT WARN: schemeshard_import.cpp:466: Table profiles were not loaded
2026-03-17T14:14:44.723402Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2026-03-17T14:14:44.723435Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2026-03-17T14:14:44.732551Z node 9 :HIVE WARN: hive_impl.cpp:797: HIVE#72057594037968897 Handle TEvInterconnect::TEvNodeConnected, NodeId 10 Cookie 10
2026-03-17T14:14:44.732891Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(10, (0,0,0,0)) VolatileState: Connecting -> Connected
2026-03-17T14:14:44.737823Z node 9 :NET_CLASSIFIER WARN: net_classifier.cpp:190: distributable config is empty, broken or outdated, will use file: /home/runner/.ya/build/build_root/8zh7/00332d/r3tmp/yandexwk8Hpi.tmp
2026-03-17T14:14:44.737836Z node 9 :NET_CLASSIFIER WARN: net_classifier.cpp:196: will try to initialize from file: /home/runner/.ya/build/build_root/8zh7/00332d/r3tmp/yandexwk8Hpi.tmp
2026-03-17T14:14:44.737891Z node 9 :NET_CLASSIFIER WARN: net_classifier.cpp:202: successfully initialized from file: /home/runner/.ya/build/build_root/8zh7/00332d/r3tmp/yandexwk8Hpi.tmp
2026-03-17T14:14:44.737923Z node 9 :NET_CLASSIFIER ERROR: net_classifier.cpp:228: got bad distributable configuration
2026-03-17T14:14:44.743529Z INFO: TTestServer started on Port 29610 GrpcPort 15723
PQClient connected to localhost:15723
2026-03-17T14:14:44.799898Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Unknown -> Disconnected
2026-03-17T14:14:44.799936Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Disconnected -> Connecting
2026-03-17T14:14:44.801852Z node 9 :HIVE WARN: node_info.cpp:25: HIVE#72057594037968897 Node(9, (0,0,0,0)) VolatileState: Connecting -> Connected
2026-03-17T14:14:44.802221Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpAlterSubDomain, opId: 281474976720657:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_alter_subdomain.cpp:311)
2026-03-17T14:14:44.811131Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720659:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2026-03-17T14:14:44.858925Z node 9 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2026-03-17T14:14:44.866710Z node 10 :KQP_PROXY WARN: kqp_finalize_script_service.cpp:137: [ScriptExecutions] [TKqpFinalizeScriptService] Failed to check script execution tables existence, scheme status: PathErrorUnknown, path: Root/.metadata/script_executions
2026-03-17T14:14:45.111925Z node 10 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [10:7618227814570903888:2306], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2026-03-17T14:14:45.111948Z node 10 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [10:7618227814570903861:2303], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2026-03-17T14:14:45.111961Z node 10 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:352: [WorkloadService] [Service] Failed to fetch pool: default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2026-03-17T14:14:45.112384Z node 10 :KQP_WORKLOAD_SERVICE WARN: scheme_actors.cpp:225: [WorkloadService] [TPoolFetcherActor] ActorId: [10:7618227814570903899:2308], DatabaseId: /Root, PoolId: default, Failed to fetch pool info, NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2026-03-17T14:14:45.112401Z node 10 :KQP_WORKLOAD_SERVICE WARN: kqp_workload_service.cpp:352: [WorkloadService] [Service] Failed to fetch pool: default, DatabaseId: /Root, status: NOT_FOUND, issues: { <main>: Error: Resource pool default not found or you don't have access permissions }
2026-03-17T14:14:45.114516Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateResourcePool, opId: 281474976715657:3, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:179)
2026-03-17T14:14:45.127936Z node 10 :KQP_WORKLOAD_SERVICE WARN: helpers.h:117: [WorkloadService] [TPoolCreatorActor] ActorId: [10:7618227814570903898:2307], DatabaseId: /Root, PoolId: default, Scheduled retry for error: { <main>: Error: Transaction 281474976715657 completed, doublechecking }
2026-03-17T14:14:45.136992Z node 9 :KQP_COMPILE_ACTOR ERROR: kqp_compile_actor.cpp:627: Compilation failed, self: [9:7618227816146732661:2346], status: SCHEME_ERROR, issues: <main>: Error: Type annotation, code: 1030
    <main>:3:16: Error: At function: KiReadTable!
        <main>:3:16: Error: Cannot find table 'db.[/Root/PQ/Config/V2/Cluster]' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions., code: 2003
2026-03-17T14:14:45.137785Z node 9 :KQP_SESSION WARN: {KQPSA@kqp_session_actor.cpp:2941} SessionId: ydb://session/3?node_id=9&id=ZjhhYjYyZjktYTRkMzJjNWMtMzZjNGFkOTEtNTBjMTk3ZWY=, ActorId: [9:7618227816146732611:2337], ActorState: ExecuteState, LegacyTraceId: 01kky2ca4a0cxbtqpkpnkwm4qp, ReplyQueryCompileError, remove tx status# SCHEME_ERROR issues# { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } tx_id#  trace_id# 
2026-03-17T14:14:45.138103Z node 9 :PERSQUEUE_CLUSTER_TRACKER ERROR: cluster_tracker.cpp:167: failed to list clusters: { Response { QueryIssues { message: "Type annotation" issue_code: 1030 severity: 1 issues { position { row: 3 column: 16 } message: "At function: KiReadTable!" end_position { row: 3 column: 16 } severity: 1 issues { position { row: 3 column: 16 } message: "Cannot find table \'db.[/Root/PQ/Config/V2/Cluster]\' because it does not exist or you do not have access permissions. Please check correctness of table path and user permissions." end_position { row: 3 column: 16 } issue_code: 2003 severity: 1 } } } TxMeta { } EffectivePoolId: "default" } YdbStatus: SCHEME_ERROR ConsumedRu: 1 }
2026-03-17T14:14:45.139270Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720662:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2026-03-17T14:14:45.162550Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720663:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
2026-03-17T14:14:45.187556Z node 9 :FLAT_TX_SCHEMESHARD WARN: schemeshard__operation.cpp:183: Operation part proposed ok, but propose itself is undo unsafe, suboperation type: ESchemeOpCreateTable, opId: 281474976720664:0, at schemeshard:  72057594046644480, first GetDB called at:  (GetDB first called at ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:689)
=== Init DC: UPSERT INTO `/Root/PQ/Config/V2/Cluster` (name, balancer, local, enabled, weight) VALUES ("dc1", "localhost", true, true, 1000), ("dc2", "dc2.logbroker.yandex.net", false, true, 1000);
2026-03-17T14:14:45.214178Z node 10 :TX_PROXY ERROR: schemereq.cpp:631: Actor# [10:7618227814570904029:2199] txid# 281474976715658, issues: { message: "Check failed: path: \'/Root/.metadata/workload_manager/pools/default\', error: path exist, request accepts it (id: [OwnerId: 72057594046644480, LocalPathId: 45], type: EPathTypeResourcePool, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_resource_pool.cpp:92" severity: 1 }
=== CheckClustersList. Subcribe to ClusterTracker from [9:7618227816146733033:3315] 
2026-03-17T14:14:45.700422Z node 9 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2026-03-17T14:14:45.701830Z node 10 :TX_CONVEYOR ERROR: log.cpp:841: fline=service.h:53;problem=unexpected event for task executor;ev_type=NActors::TEvents::TEvWakeup;
2026-03-17T14:14:49.698915Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[9:7618227811851763956:2085];send_to=[0:7307199536658146131:7762515];
2026-03-17T14:14:49.698966Z node 9 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:85;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
2026-03-17T14:14:49.703443Z node 10 :METADATA_PROVIDER ERROR: log.cpp:841: fline=table_exists.cpp:59;actor=TTableExistsActor;event=timeout;self_id=[10:7618227810275936282:2085];send_to=[0:7307199536658146131:7762515];
2026-03-17T14:14:49.703665Z node 10 :METADATA_PROVIDER ERROR: log.cpp:841: fline=accessor_snapshot_base.cpp:85;action=cannot detect path existence;path=//Root/.metadata/initialization/migrations;error=timeout;
=== CheckClustersList. Ok
2026-03-17T14:14:50.468094Z node 9 :PQ_READ_PROXY DEBUG: grpc_pq_schema.cpp:132: new create topic request
2026-03-17T14:14:50.476921Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:3046: [PQ: 72075186224037892] Handle TEvInterconnect::TEvNodeInfo
2026-03-17T14:14:50.477402Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1669: [72075186224037894][] pipe [9:7618227837621569854:3516] connected; active server actors: 1
2026-03-17T14:14:50.477807Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1521: [72075186224037894][rt3.dc1--acc--topic3] updating configuration. Deleted partitions []. Added partitions [1, 0]
2026-03-17T14:14:50.477872Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:3046: [PQ: 72075186224037893] Handle TEvInterconnect::TEvNodeInfo
2026-03-17T14:14:50.478213Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:765: [72075186224037894][rt3.dc1--acc--topic3] Discovered subdomain [OwnerId: 72057594046644480, LocalPathId: 1] state, outOfSpace = 0 at RB 72075186224037894
2026-03-17T14:14:50.478321Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:3078: [PQ: 72075186224037892] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2026-03-17T14:14:50.478372Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:789: [PQ: 72075186224037892] no config, start with empty partitions and default config
2026-03-17T14:14:50.478381Z node 9 :PERSQUEUE NOTICE: pq_impl.cpp:928: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
2026-03-17T14:14:50.478390Z node 9 :PERSQUEUE INFO: pq_impl.cpp:614: [PQ: 72075186224037892] doesn't have tx writes info
2026-03-17T14:14:50.478503Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621569851:3514], now have 1 active actors on pipe
2026-03-17T14:14:50.478508Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621569921:2444], now have 1 active actors on pipe
2026-03-17T14:14:50.478523Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:399: [72075186224037894][rt3.dc1--acc--topic3] TEvClientConnected TabletId 72075186224037892, NodeId 9, Generation 1
2026-03-17T14:14:50.478788Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:3668: [PQ: 72075186224037892] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2026-03-17T14:14:50.478853Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:145: [72075186224037894][rt3.dc1--acc--topic3] BALANCER INIT DONE for rt3.dc1--acc--topic3: (0, 72075186224037893) (1, 72075186224037892) 
2026-03-17T14:14:50.479788Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer.cpp:399: [72075186224037894][rt3.dc1--acc--topic3] TEvClientConnected TabletId 72075186224037893, NodeId 10, Generation 1
2026-03-17T14:14:50.479842Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:1060: [PQ: 72075186224037892] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2026-03-17T14:14:50.479079Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:3078: [PQ: 72075186224037893] Transactions request. From tx_00000000000000000000, To tx_18446744073709551615
2026-03-17T14:14:50.479156Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:789: [PQ: 72075186224037893] no config, start with empty partitions and default config
2026-03-17T14:14:50.479166Z node 10 :PERSQUEUE NOTICE: pq_impl.cpp:928: [PQ: 72075186224037893] disable metering: reason# billing is not enabled in BillingMeteringConfig
2026-03-17T14:14:50.479183Z node 10 :PERSQUEUE INFO: pq_impl.cpp:614: [PQ: 72075186224037893] doesn't have tx writes info
2026-03-17T14:14:50.479289Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037893] server connected, pipe [9:7618227837621569852:3515], now have 1 active actors on pipe
2026-03-17T14:14:50.479293Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037893] server connected, pipe [9:7618227837621569920:2444], now have 1 active actors on pipe
2026-03-17T14:14:50.479663Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:3668: [PQ: 72075186224037893] Send TEvKeyValue::TEvRequest (WRITE_TX_COOKIE)
2026-03-17T14:14:50.480682Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:1060: [PQ: 72075186224037893] Handle TEvKeyValue::TEvResponse (WRITE_TX_COOKIE)
2026-03-17T14:14:50.482564Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037893] server connected, pipe [9:7618227837621569956:3603], now have 1 active actors on pipe
2026-03-17T14:14:50.482479Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621569957:3604], now have 1 active actors on pipe
2026-03-17T14:14:50.482717Z node 9 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:1:Initializer] Start initializing step TInitConfigStep
2026-03-17T14:14:50.482735Z node 9 :PERSQUEUE DEBUG: partition.cpp:1330: [72075186224037892][Partition][1][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2026-03-17T14:14:50.482858Z node 9 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:1:Initializer] Start initializing step TInitInternalFieldsStep
2026-03-17T14:14:50.482917Z node 9 :PERSQUEUE INFO: partition_init.cpp:1175: [72075186224037892][Partition][1][StateInit] bootstrapping 1 [9:7618227837621569962:2445]
2026-03-17T14:14:50.483780Z node 9 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:1:Initializer] Start initializing step TInitFieldsStep
2026-03-17T14:14:50.483786Z node 9 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--acc--topic3:1:Initializer] Initializing completed.
2026-03-17T14:14:50.483793Z node 9 :PERSQUEUE INFO: partition.cpp:713: [72075186224037892][Partition][1][StateInit] init complete for topic 'rt3.dc1--acc--topic3' partition 1 generation 1 [9:7618227837621569962:2445]
2026-03-17T14:14:50.483802Z node 9 :PERSQUEUE DEBUG: partition.cpp:733: [72075186224037892][Partition][1][StateInit] SYNC INIT topic rt3.dc1--acc--topic3 partitition 1 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2026-03-17T14:14:50.483814Z node 9 :PERSQUEUE DEBUG: partition_mlp.cpp:151: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2026-03-17T14:14:50.484557Z node 9 :PERSQUEUE DEBUG: partition.cpp:4532: [72075186224037892][Partition][1][StateIdle] Process pending events. Count 1
2026-03-17T14:14:50.484576Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484580Z node 9 :PERSQUEUE DEBUG: partition.cpp:2445: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2026-03-17T14:14:50.484591Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484593Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484596Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484597Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.484605Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484607Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484608Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484610Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484611Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.484619Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.484622Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.484629Z node 9 :PERSQUEUE DEBUG: partition_mlp.cpp:114: [72075186224037892][Partition][1][StateIdle] Process MLP pending events. Count 0
2026-03-17T14:14:50.484773Z node 9 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.484787Z node 9 :PERSQUEUE DEBUG: partition.cpp:1439: [72075186224037892][Partition][1][StateIdle] Handle TEvPQ::TEvTxCommit Step 1773756890528, TxId 281474976720671
2026-03-17T14:14:50.484790Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484791Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484792Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484796Z node 9 :PERSQUEUE DEBUG: partition.cpp:2509: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2026-03-17T14:14:50.484842Z node 9 :PERSQUEUE DEBUG: partition.cpp:4010: [72075186224037892][Partition][1][StateIdle] Schedule reply tx done 281474976720671
2026-03-17T14:14:50.484845Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.484847Z node 9 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2026-03-17T14:14:50.484851Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.484906Z node 9 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.482783Z node 10 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:0:Initializer] Start initializing step TInitConfigStep
2026-03-17T14:14:50.482817Z node 10 :PERSQUEUE DEBUG: partition.cpp:1330: [72075186224037893][Partition][0][StateInit] HandleOnInit TEvPQ::TEvProposePartitionConfig
2026-03-17T14:14:50.482912Z node 10 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:0:Initializer] Start initializing step TInitInternalFieldsStep
2026-03-17T14:14:50.482968Z node 10 :PERSQUEUE INFO: partition_init.cpp:1175: [72075186224037893][Partition][0][StateInit] bootstrapping 0 [10:7618227836045740865:2387]
2026-03-17T14:14:50.483884Z node 10 :PERSQUEUE DEBUG: partition_init.cpp:86: [rt3.dc1--acc--topic3:0:Initializer] Start initializing step TInitFieldsStep
2026-03-17T14:14:50.483891Z node 10 :PERSQUEUE DEBUG: partition_init.cpp:66: [rt3.dc1--acc--topic3:0:Initializer] Initializing completed.
2026-03-17T14:14:50.483898Z node 10 :PERSQUEUE INFO: partition.cpp:713: [72075186224037893][Partition][0][StateInit] init complete for topic 'rt3.dc1--acc--topic3' partition 0 generation 1 [10:7618227836045740865:2387]
2026-03-17T14:14:50.483905Z node 10 :PERSQUEUE DEBUG: partition.cpp:733: [72075186224037893][Partition][0][StateInit] SYNC INIT topic rt3.dc1--acc--topic3 partitition 0 so 0 endOffset 0 Head Offset 0 PartNo 0 PackedSize 0 count 0 nextOffset 0 batches 0
2026-03-17T14:14:50.483913Z node 10 :PERSQUEUE DEBUG: partition_mlp.cpp:151: [72075186224037893][Partition][0][StateIdle] Initializing MLP Consumers: 0
2026-03-17T14:14:50.484647Z node 10 :PERSQUEUE DEBUG: partition.cpp:4532: [72075186224037893][Partition][0][StateIdle] Process pending events. Count 1
2026-03-17T14:14:50.484657Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484663Z node 10 :PERSQUEUE DEBUG: partition.cpp:2445: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TTransaction[ProposeConfig])
2026-03-17T14:14:50.484677Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484679Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484682Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484685Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.484698Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484700Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484701Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484703Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484705Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.484714Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.484718Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.484728Z node 10 :PERSQUEUE DEBUG: partition_mlp.cpp:114: [72075186224037893][Partition][0][StateIdle] Process MLP pending events. Count 0
2026-03-17T14:14:50.484884Z node 10 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037893][Partition][0][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.484904Z node 10 :PERSQUEUE DEBUG: partition.cpp:1439: [72075186224037893][Partition][0][StateIdle] Handle TEvPQ::TEvTxCommit Step 1773756890528, TxId 281474976720671
2026-03-17T14:14:50.484909Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.484911Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.484913Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.484917Z node 10 :PERSQUEUE DEBUG: partition.cpp:2509: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TTransaction[ProposeConfig])
2026-03-17T14:14:50.484976Z node 10 :PERSQUEUE DEBUG: partition.cpp:4010: [72075186224037893][Partition][0][StateIdle] Schedule reply tx done 281474976720671
2026-03-17T14:14:50.484980Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.484982Z node 10 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037893][Partition][0][StateIdle] Batch completed (1)
2026-03-17T14:14:50.484985Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.485049Z node 10 :PERSQUEUE DEBUG: read.h:275: [72075186224037893][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.485978Z node 10 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037893][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.486049Z node 10 :PERSQUEUE DEBUG: partition_mlp.cpp:151: [72075186224037893][Partition][0][StateIdle] Initializing MLP Consumers: 0
2026-03-17T14:14:50.486090Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037893][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.486100Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.486103Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.486104Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.486106Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.486108Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.486113Z node 10 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037893][Partition][0][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.485914Z node 9 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.486032Z node 9 :PERSQUEUE DEBUG: partition_mlp.cpp:151: [72075186224037892][Partition][1][StateIdle] Initializing MLP Consumers: 0
2026-03-17T14:14:50.486075Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.486092Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.486095Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.486097Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.486100Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.486102Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.486108Z node 9 :PERSQUEUE DEBUG: partition_compaction.cpp:183: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.486286Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:400: [PQ: 72075186224037893] Apply new config PartitionConfig { MaxCountInPartition: 2147483647 MaxSizeInPartition: 1000 LifetimeSeconds: 86400 SourceIdLifetimeSeconds: 1382400 WriteSpeedInBytesPerSecond: 1000 BurstSize: 1000 TotalPartitions: 2 ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } SourceIdMaxCounts: 6000000 } PartitionIds: 0 TopicName: "rt3.dc1--acc--topic3" Version: 0 LocalDC: true RequireAuthWrite: true RequireAuthRead: true Producer: "acc" Ident: "acc" Topic: "topic3" DC: "dc1" FormatVersion: 0 Codecs { Ids: 0 Ids: 10041 Codecs: "raw" Codecs: "CUSTOM" } TopicPath: "/Root/PQ/rt3.dc1--acc--topic3" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 0 Status: Active CreateVersion: 1 TabletId: 0 } Consumers { Name: "first-consumer" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 Type: CONSUMER_TYPE_STREAMING } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 Type: CONSUMER_TYPE_STREAMING } MonitoringProjectId: ""
2026-03-17T14:14:50.486312Z node 10 :PERSQUEUE NOTICE: pq_impl.cpp:928: [PQ: 72075186224037893] disable metering: reason# billing is not enabled in BillingMeteringConfig
2026-03-17T14:14:50.486287Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:400: [PQ: 72075186224037892] Apply new config PartitionConfig { MaxCountInPartition: 2147483647 MaxSizeInPartition: 1000 LifetimeSeconds: 86400 SourceIdLifetimeSeconds: 1382400 WriteSpeedInBytesPerSecond: 1000 BurstSize: 1000 TotalPartitions: 2 ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } ExplicitChannelProfiles { PoolKind: "test" } SourceIdMaxCounts: 6000000 } PartitionIds: 1 TopicName: "rt3.dc1--acc--topic3" Version: 0 LocalDC: true RequireAuthWrite: true RequireAuthRead: true Producer: "acc" Ident: "acc" Topic: "topic3" DC: "dc1" FormatVersion: 0 Codecs { Ids: 0 Ids: 10041 Codecs: "raw" Codecs: "CUSTOM" } TopicPath: "/Root/PQ/rt3.dc1--acc--topic3" YcCloudId: "" YcFolderId: "" YdbDatabaseId: "" YdbDatabasePath: "/Root" Partitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } AllPartitions { PartitionId: 1 Status: Active CreateVersion: 1 TabletId: 0 } Consumers { Name: "first-consumer" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 Type: CONSUMER_TYPE_STREAMING } Consumers { Name: "user" ReadFromTimestampsMs: 0 FormatVersion: 0 Codec { } ServiceType: "data-streams" Version: 0 Generation: 0 Type: CONSUMER_TYPE_STREAMING } MonitoringProjectId: ""
2026-03-17T14:14:50.486311Z node 9 :PERSQUEUE NOTICE: pq_impl.cpp:928: [PQ: 72075186224037892] disable metering: reason# billing is not enabled in BillingMeteringConfig
{ operation { ready: true status: SUCCESS } }
{  }
2026-03-17T14:14:50.489875Z :DEBUG: [] MessageGroupId [src] SessionId [] Write session: try to update token
2026-03-17T14:14:50.490139Z :INFO: [] MessageGroupId [src] SessionId [] Write session: Do CDS request
2026-03-17T14:14:50.490165Z :INFO: [] MessageGroupId [src] SessionId [] Start write session. Will connect to endpoint: localhost:15723
2026-03-17T14:14:50.490545Z :DEBUG: [] MessageGroupId [src] SessionId [] Write session: send init request: init_request { topic: "acc/topic3" message_group_id: "src" }
2026-03-17T14:14:50.490842Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2026-03-17T14:14:50.490856Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 1
2026-03-17T14:14:50.491081Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId:  grpc read done: success: 1 data: init_request { topic: "acc/topic3" message_group_id: "src" }
2026-03-17T14:14:50.491117Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:460: session request cookie: 1 topic: "acc/topic3" message_group_id: "src" from ipv6:[::1]:52028
2026-03-17T14:14:50.491125Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1542: write session:  cookie=1 sessionId= userAgent="pqv1 server" ip=ipv6:[::1]:52028 proto=v1 user=- topic=Topic acc/topic3 in dc dc1 durationSec=0
2026-03-17T14:14:50.491128Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:584: init check schema
2026-03-17T14:14:50.491880Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:645: session v1 cookie: 1 sessionId:  describe result for acl check
2026-03-17T14:14:50.491917Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2026-03-17T14:14:50.491925Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2026-03-17T14:14:50.491928Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2026-03-17T14:14:50.491940Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) StartKqpSession
2026-03-17T14:14:50.492089Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) Select from the table
2026-03-17T14:14:50.530797Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:67: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) RequestPQRB
2026-03-17T14:14:50.530989Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1669: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570032:2451] connected; active server actors: 1
2026-03-17T14:14:50.531000Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__old_chooser_actor.h:80: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) Received partition 1 from PQRB for SourceId=src
2026-03-17T14:14:50.531004Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) Update the table
2026-03-17T14:14:50.531096Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1673: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570032:2451] disconnected.
2026-03-17T14:14:50.531099Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1684: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570032:2451] disconnected; active server actors: 1
2026-03-17T14:14:50.531102Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1709: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570032:2451] disconnected no session
2026-03-17T14:14:50.559791Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2026-03-17T14:14:50.559832Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) ReplyResult: Partition=1, SeqNo=(NULL)
2026-03-17T14:14:50.559838Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [9:7618227837621570001:2451] (SourceId=src, PreferedPartition=(NULL)) Start idle
2026-03-17T14:14:50.559863Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:707: ProceedPartition. session cookie: 1 sessionId:  partition: 1 expectedGeneration: (NULL)
2026-03-17T14:14:50.560304Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:839: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 9, Generation: 1
2026-03-17T14:14:50.560333Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621570053:2451], now have 1 active actors on pipe
2026-03-17T14:14:50.560342Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.560349Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.560382Z node 9 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0 generated for partition 1 topic 'rt3.dc1--acc--topic3' owner src
2026-03-17T14:14:50.560396Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.560401Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.560408Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.560410Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.560415Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.560428Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.560436Z node 9 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2026-03-17T14:14:50.560440Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.560473Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2026-03-17T14:14:50.560499Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.560548Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.560557Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.560582Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.560622Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:884: session inited cookie: 1 partition: 1 MaxSeqNo: 0 sessionId: src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0
2026-03-17T14:14:50.561016Z :INFO: [] MessageGroupId [src] SessionId [] Counters: { Errors: 0 CurrentSessionLifetimeMs: 1773756890561 BytesWritten: 0 MessagesWritten: 0 BytesWrittenCompressed: 0 BytesInflightUncompressed: 0 BytesInflightCompressed: 0 BytesInflightTotal: 0 MessagesInflight: 0 }
2026-03-17T14:14:50.561059Z :INFO: [] MessageGroupId [src] SessionId [] Write session established. Init response: session_id: "src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0" topic: "acc/topic3" cluster: "dc1" partition_id: 1 supported_codecs: CODEC_RAW supported_codecs: CODEC_UNSPECIFIED
2026-03-17T14:14:50.561244Z :INFO: [] MessageGroupId [src] SessionId [src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0] Write session: close. Timeout = 0 ms
2026-03-17T14:14:50.561265Z :INFO: [] MessageGroupId [src] SessionId [src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0] Write session will now close
2026-03-17T14:14:50.561273Z :DEBUG: [] MessageGroupId [src] SessionId [src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0] Write session: aborting
2026-03-17T14:14:50.561505Z :INFO: [] MessageGroupId [src] SessionId [src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0] Write session: gracefully shut down, all writes complete
2026-03-17T14:14:50.561514Z :DEBUG: [] MessageGroupId [src] SessionId [src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0] Write session: destroy
AddTopic: acc/topic3
===Run query:``DECLARE $version as Int64; DECLARE $path AS Utf8; DECLARE $cluster as Utf8; UPSERT INTO `/Root/PQ/Config/V2/Topics` (path, dc) VALUES ($path, $cluster); UPSERT INTO `/Root/PQ/Config/V2/Versions` (name, version) VALUES ("Topics", $version);`` with topic = acc/topic3, dc = unknown
2026-03-17T14:14:50.566414Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 1 sessionId: src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0 grpc read done: success: 0 data: 
2026-03-17T14:14:50.566430Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 1 sessionId: src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0 grpc read failed
2026-03-17T14:14:50.566437Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 1 sessionId: src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0 grpc closed
2026-03-17T14:14:50.566446Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 1 sessionId: src|c2853b5b-f59ca95f-9553e4f5-e2dc08c9_0 is DEAD
2026-03-17T14:14:50.566688Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2026-03-17T14:14:50.566771Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2832: [PQ: 72075186224037892] server disconnected, pipe [9:7618227837621570053:2451] destroyed
2026-03-17T14:14:50.566785Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2026-03-17T14:14:50.566793Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.566797Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.566799Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.566802Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.566804Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.584776Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.584794Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.584797Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.584801Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.584803Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.584872Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.584889Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.584892Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.584895Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.584897Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
===Query complete
2026-03-17T14:14:50.601094Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2026-03-17T14:14:50.601118Z node 9 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:111: new grpc connection
2026-03-17T14:14:50.601126Z node 9 :PQ_READ_PROXY DEBUG: grpc_pq_read.h:133: new session created cookie 1
2026-03-17T14:14:50.601122Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 2
2026-03-17T14:14:50.601181Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:107: new grpc connection
2026-03-17T14:14:50.601183Z node 9 :PQ_WRITE_PROXY DEBUG: grpc_pq_write.h:141: new session created cookie 3
2026-03-17T14:14:50.601442Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId:  grpc read done: success: 1 data: init_request { path: "acc/topic3" producer_id: "A" partition_id: 0 }
2026-03-17T14:14:50.601496Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:460: session request cookie: 2 path: "acc/topic3" producer_id: "A" partition_id: 0 from ipv6:[::1]:52034
2026-03-17T14:14:50.601504Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1542: write session:  cookie=2 sessionId= userAgent="topic server" ip=ipv6:[::1]:52034 proto=topic user=- topic=Topic acc/topic3 in dc dc1 durationSec=0
2026-03-17T14:14:50.601509Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:584: init check schema
2026-03-17T14:14:50.601523Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:488: session to partition: 0
2026-03-17T14:14:50.602868Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:645: session v1 cookie: 2 sessionId:  describe result for acl check
2026-03-17T14:14:50.602914Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2026-03-17T14:14:50.602915Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2026-03-17T14:14:50.602917Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2026-03-17T14:14:50.602923Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) StartKqpSession
2026-03-17T14:14:50.603056Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) Select from the table
2026-03-17T14:14:50.605391Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) Update the table
2026-03-17T14:14:50.609182Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2026-03-17T14:14:50.609201Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) ReplyResult: Partition=0, SeqNo=(NULL)
2026-03-17T14:14:50.609206Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [9:7618227837621570099:2476] (SourceId=A, PreferedPartition=0) Start idle
2026-03-17T14:14:50.609218Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:707: ProceedPartition. session cookie: 2 sessionId:  partition: 0 expectedGeneration: (NULL)
2026-03-17T14:14:50.609619Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037893] server connected, pipe [9:7618227837621570128:2476], now have 1 active actors on pipe
2026-03-17T14:14:50.609696Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:839: TPartitionWriter 72075186224037893 (partition=0) TEvClientConnected Status OK, TabletId: 72075186224037893, NodeId 10, Generation: 1
2026-03-17T14:14:50.609803Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.609824Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.609863Z node 10 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie A|e1b2e22-885d3939-e38e46ef-e900038a_0 generated for partition 0 topic 'rt3.dc1--acc--topic3' owner A
2026-03-17T14:14:50.609886Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.609895Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.609903Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.609906Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.609911Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.609923Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.609932Z node 10 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037893][Partition][0][StateIdle] Batch completed (1)
2026-03-17T14:14:50.609951Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.609977Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyOwnerOk. Partition: 0
2026-03-17T14:14:50.610004Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.610298Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.610316Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.610333Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.610436Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:884: session inited cookie: 2 partition: 0 MaxSeqNo: 0 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0
===Got response: status: SUCCESS init_response { session_id: "A|e1b2e22-885d3939-e38e46ef-e900038a_0" supported_codecs { codecs: 1 codecs: 0 } }
2026-03-17T14:14:50.614543Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0 grpc read done: success: 1 data:  write_request[data omitted]
2026-03-17T14:14:50.614683Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037893 (partition=0) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2026-03-17T14:14:50.615031Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.615067Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.615111Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 0  requestId: <none> cookie: 1
2026-03-17T14:14:50.616462Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037893 (partition=0) Received event: NActors::IEventHandle
2026-03-17T14:14:50.616714Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.616732Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.616851Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 1 partNo : 0 messageNo: 1 size 67 offset: -1
2026-03-17T14:14:50.616872Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 2 partNo : 0 messageNo: 1 size 68 offset: -1
2026-03-17T14:14:50.616879Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 3 partNo : 0 messageNo: 1 size 69 offset: -1
2026-03-17T14:14:50.616888Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 4 partNo : 0 messageNo: 1 size 70 offset: -1
2026-03-17T14:14:50.616896Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 5 partNo : 0 messageNo: 1 size 71 offset: -1
2026-03-17T14:14:50.616906Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 6 partNo : 0 messageNo: 1 size 72 offset: -1
2026-03-17T14:14:50.616911Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 7 partNo : 0 messageNo: 1 size 73 offset: -1
2026-03-17T14:14:50.616917Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 8 partNo : 0 messageNo: 1 size 74 offset: -1
2026-03-17T14:14:50.616930Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 9 partNo : 0 messageNo: 1 size 75 offset: -1
2026-03-17T14:14:50.616935Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037893] got client message topic: rt3.dc1--acc--topic3 partition: 0 SourceId: '\0A' SeqNo: 10 partNo : 0 messageNo: 1 size 76 offset: -1
2026-03-17T14:14:50.616953Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037893][Partition][0][StateIdle] Received TPartition::TEvWrite
2026-03-17T14:14:50.616991Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.617000Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617011Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617014Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617017Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617020Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617025Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617027Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617029Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617031Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617033Z node 10 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.617037Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 10, PendingWrites: 0
2026-03-17T14:14:50.617039Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.617044Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617096Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 1 partNo 0
2026-03-17T14:14:50.617270Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 136 count 1 nextOffset 1 batches 1
2026-03-17T14:14:50.617310Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617328Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 2 partNo 0
2026-03-17T14:14:50.617348Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 241 count 2 nextOffset 2 batches 1
2026-03-17T14:14:50.617353Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617358Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 3 partNo 0
2026-03-17T14:14:50.617364Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 347 count 3 nextOffset 3 batches 1
2026-03-17T14:14:50.617373Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617376Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 4 partNo 0
2026-03-17T14:14:50.617382Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 4 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 454 count 4 nextOffset 4 batches 1
2026-03-17T14:14:50.617386Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617390Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 5 partNo 0
2026-03-17T14:14:50.617395Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 5 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 562 count 5 nextOffset 5 batches 1
2026-03-17T14:14:50.617400Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617403Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 6 partNo 0
2026-03-17T14:14:50.617409Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 6 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 671 count 6 nextOffset 6 batches 1
2026-03-17T14:14:50.617412Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617419Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 7 partNo 0
2026-03-17T14:14:50.617430Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 7 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 781 count 7 nextOffset 7 batches 1
2026-03-17T14:14:50.617436Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617439Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 8 partNo 0
2026-03-17T14:14:50.617443Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 8 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 892 count 8 nextOffset 8 batches 1
2026-03-17T14:14:50.617447Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617451Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 9 partNo 0
2026-03-17T14:14:50.617459Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 9 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 1004 count 9 nextOffset 9 batches 1
2026-03-17T14:14:50.617469Z node 10 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.617473Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob processing sourceId '\0A' seqNo 10 partNo 0
2026-03-17T14:14:50.617479Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 part blob complete sourceId '\0A' seqNo 10 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 1117 count 10 nextOffset 10 batches 1
2026-03-17T14:14:50.617484Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 10
2026-03-17T14:14:50.617487Z node 10 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037893][Partition][0][StateIdle] Batch completed (10)
2026-03-17T14:14:50.617492Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.617563Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:1711: [72075186224037893][Partition][0][StateIdle] Add new write blob: topic 'rt3.dc1--acc--topic3' partition 0 compactOffset 0,10 HeadOffset 0 endOffset 0 curOffset 10 d0000000000_00000000000000000000_00000_0000000010_00000? size 892 WTime 1773756890617
2026-03-17T14:14:50.617614Z node 10 :PERSQUEUE DEBUG: read.h:275: [72075186224037893][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.617633Z node 10 :PERSQUEUE DEBUG: read.h:313: [72075186224037893][PQCacheProxy]CacheProxy. Passthrough blob. Partition 0 offset 0 partNo 0 count 10 size 892
2026-03-17T14:14:50.619023Z node 10 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 0 offset 0 count 10 size 892 actorID [10:7618227836045740853:2387]
2026-03-17T14:14:50.619056Z node 10 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037893][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.619069Z node 10 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037893' partition 0 offset 0 partno 0 count 10 parts 0 suffix '63' size 892
2026-03-17T14:14:50.619098Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037893][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 735 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.619109Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619120Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2026-03-17T14:14:50.619127Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619133Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2026-03-17T14:14:50.619136Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619139Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2026-03-17T14:14:50.619142Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619146Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 4, partNo: 0, Offset: 3 is stored on disk
2026-03-17T14:14:50.619148Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619153Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 5, partNo: 0, Offset: 4 is stored on disk
2026-03-17T14:14:50.619155Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619159Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 6, partNo: 0, Offset: 5 is stored on disk
2026-03-17T14:14:50.619162Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619166Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 7, partNo: 0, Offset: 6 is stored on disk
2026-03-17T14:14:50.619169Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619173Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 8, partNo: 0, Offset: 7 is stored on disk
2026-03-17T14:14:50.619176Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619182Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 9, partNo: 0, Offset: 8 is stored on disk
2026-03-17T14:14:50.619185Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037893][Partition][0][StateIdle] TPartition::ReplyWrite. Partition: 0
2026-03-17T14:14:50.619191Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037893][Partition][0][StateIdle] Answering for message sourceid: '\0A', Topic: 'rt3.dc1--acc--topic3', Partition: 0, SeqNo: 10, partNo: 0, Offset: 9 is stored on disk
2026-03-17T14:14:50.619223Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.619236Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:976: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.619241Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.619245Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.619248Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.619250Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.619253Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.619255Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.619266Z node 10 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037893][Partition][0][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.619294Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 1  requestId: <none> cookie: 1
2026-03-17T14:14:50.619334Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037893][Partition][0][StateIdle] read cookie 3 Topic 'rt3.dc1--acc--topic3' partition 0 user user offset 0 partno 0 count 1 size 1024000 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.619356Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037893][Partition][0][StateIdle] read cookie 3 added 1 blobs, size 892 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.619359Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037893][Partition][0][StateIdle] Reading cookie 3. Send blob request.
2026-03-17T14:14:50.619370Z node 10 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 10 parts_count 0 source 1 size 892 accessed 0 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.619424Z node 10 :PERSQUEUE DEBUG: read.h:126: [72075186224037893][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2026-03-17T14:14:50.619435Z node 10 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037893' partition 0 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.619438Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.619409Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037893 (partition=0) Received event: NActors::IEventHandle
2026-03-17T14:14:50.619447Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 872 from pos 0 cbcount 10
2026-03-17T14:14:50.619472Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--acc--topic3' partition 0 user user readTimeStamp done, result 1773756890616 queuesize 1 startOffset 0
2026-03-17T14:14:50.619477Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.619481Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:976: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.619494Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037893][Partition][0][StateIdle] read cookie 4 Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer offset 0 partno 0 count 1 size 1024000 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.619511Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037893][Partition][0][StateIdle] read cookie 4 added 1 blobs, size 892 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.619517Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037893][Partition][0][StateIdle] Reading cookie 4. Send blob request.
2026-03-17T14:14:50.619522Z node 10 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 10 parts_count 0 source 1 size 892 accessed 1 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.619530Z node 10 :PERSQUEUE DEBUG: read.h:126: [72075186224037893][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2026-03-17T14:14:50.619537Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.619538Z node 10 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037893' partition 0 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.619542Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 872 from pos 0 cbcount 10
2026-03-17T14:14:50.619548Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--acc--topic3' partition 0 user first-consumer readTimeStamp done, result 1773756890616 queuesize 0 startOffset 0
===Got response: status: SUCCESS write_response { acks { seq_no: 1 written { } } acks { seq_no: 2 written { offset: 1 } } acks { seq_no: 3 written { offset: 2 } } acks { seq_no: 4 written { offset: 3 } } acks { seq_no: 5 written { offset: 4 } } acks { seq_no: 6 written { offset: 5 } } acks { seq_no: 7 written { offset: 6 } } acks { seq_no: 8 written { offset: 7 } } acks { seq_no: 9 written { offset: 8 } } acks { seq_no: 10 written { offset: 9 } } write_statistics { persisting_time { nanos: 2000000 } min_queue_wait_time { } max_queue_wait_time { } partition_quota_wait_time { } topic_quota_wait_time { } } }
2026-03-17T14:14:50.621053Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId:  grpc read done: success: 1 data: init_request { path: "acc/topic3" producer_id: "B" partition_id: 1 }
2026-03-17T14:14:50.621110Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:460: session request cookie: 3 path: "acc/topic3" producer_id: "B" partition_id: 1 from ipv6:[::1]:52034
2026-03-17T14:14:50.621117Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:1542: write session:  cookie=3 sessionId= userAgent="topic server" ip=ipv6:[::1]:52034 proto=topic user=- topic=Topic acc/topic3 in dc dc1 durationSec=0
2026-03-17T14:14:50.621123Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:584: init check schema
2026-03-17T14:14:50.621140Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:488: session to partition: 1
2026-03-17T14:14:50.621724Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:645: session v1 cookie: 3 sessionId:  describe result for acl check
2026-03-17T14:14:50.621774Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:62: TTableHelper SelectQuery: --!syntax_v1
DECLARE $Hash AS Uint32; DECLARE $Topic AS Utf8; DECLARE $SourceId AS Utf8;
SELECT Partition, CreateTime, AccessTime, SeqNo FROM `/Root/PQ/SourceIdMeta2` WHERE Hash == $Hash AND Topic == $Topic AND SourceId == $SourceId;
2026-03-17T14:14:50.621795Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:63: TTableHelper UpdateQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;DECLARE $SeqNo AS Uint64;
UPSERT INTO `/Root/PQ/SourceIdMeta2` (Hash, Topic, SourceId, CreateTime, AccessTime, Partition, SeqNo) VALUES ($Hash, $Topic, $SourceId, $CreateTime, $AccessTime, $Partition, $SeqNo);
2026-03-17T14:14:50.621798Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__table_helper.h:64: TTableHelper UpdateAccessTimeQuery: --!syntax_v1
DECLARE $SourceId AS Utf8; DECLARE $Topic AS Utf8; DECLARE $Hash AS Uint32; DECLARE $Partition AS Uint32; DECLARE $CreateTime AS Uint64; DECLARE $AccessTime AS Uint64;
UPDATE `/Root/PQ/SourceIdMeta2` SET AccessTime = $AccessTime WHERE Hash = $Hash AND Topic = $Topic AND SourceId = $SourceId AND Partition = $Partition;
2026-03-17T14:14:50.621804Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:111: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) StartKqpSession
2026-03-17T14:14:50.621949Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:142: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) Select from the table
2026-03-17T14:14:50.624031Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:174: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) Update the table
2026-03-17T14:14:50.628587Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:183: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) HandleUpdate PartitionPersisted=0 Status=SUCCESS
2026-03-17T14:14:50.628608Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:305: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) ReplyResult: Partition=1, SeqNo=(NULL)
2026-03-17T14:14:50.628612Z node 9 :PQ_PARTITION_CHOOSER DEBUG: partition_chooser_impl__abstract_chooser_actor.h:268: TPartitionChooser [9:7618227837621570131:2477] (SourceId=B, PreferedPartition=1) Start idle
2026-03-17T14:14:50.628622Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:707: ProceedPartition. session cookie: 3 sessionId:  partition: 1 expectedGeneration: (NULL)
2026-03-17T14:14:50.628835Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621570163:2477], now have 1 active actors on pipe
2026-03-17T14:14:50.628833Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:839: TPartitionWriter 72075186224037892 (partition=1) TEvClientConnected Status OK, TabletId: 72075186224037892, NodeId 9, Generation: 1
2026-03-17T14:14:50.628849Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.628855Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.628889Z node 9 :PERSQUEUE INFO: ownerinfo.cpp:30: new Cookie B|5d076d70-ca047b69-96b8127a-524f2c18_0 generated for partition 1 topic 'rt3.dc1--acc--topic3' owner B
2026-03-17T14:14:50.628902Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.628906Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.628912Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.628915Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.628920Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.628931Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.628934Z node 9 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2026-03-17T14:14:50.628937Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.628989Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:37: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyOwnerOk. Partition: 1
2026-03-17T14:14:50.629013Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.629078Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.629091Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.629119Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 0
===Got response: status: SUCCESS init_response { session_id: "B|5d076d70-ca047b69-96b8127a-524f2c18_0" partition_id: 1 supported_codecs { codecs: 1 codecs: 0 } }
2026-03-17T14:14:50.629164Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:884: session inited cookie: 3 partition: 1 MaxSeqNo: 0 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0
2026-03-17T14:14:50.630046Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0 grpc read done: success: 1 data:  write_request[data omitted]
2026-03-17T14:14:50.630163Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::NPQ::TEvPartitionWriter::TEvWriteRequest
2026-03-17T14:14:50.630202Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.630208Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.630233Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 1
2026-03-17T14:14:50.630249Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2026-03-17T14:14:50.630270Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.630273Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.630385Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 1 partNo : 0 messageNo: 1 size 68 offset: -1
2026-03-17T14:14:50.630393Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 2 partNo : 0 messageNo: 1 size 69 offset: -1
2026-03-17T14:14:50.630399Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 3 partNo : 0 messageNo: 1 size 70 offset: -1
2026-03-17T14:14:50.630405Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 4 partNo : 0 messageNo: 1 size 71 offset: -1
2026-03-17T14:14:50.630412Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 5 partNo : 0 messageNo: 1 size 72 offset: -1
2026-03-17T14:14:50.630417Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 6 partNo : 0 messageNo: 1 size 73 offset: -1
2026-03-17T14:14:50.630422Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 7 partNo : 0 messageNo: 1 size 74 offset: -1
2026-03-17T14:14:50.630427Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 8 partNo : 0 messageNo: 1 size 75 offset: -1
2026-03-17T14:14:50.630434Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 9 partNo : 0 messageNo: 1 size 76 offset: -1
2026-03-17T14:14:50.630439Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2077: [PQ: 72075186224037892] got client message topic: rt3.dc1--acc--topic3 partition: 1 SourceId: '\0B' SeqNo: 10 partNo : 0 messageNo: 1 size 77 offset: -1
2026-03-17T14:14:50.630451Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:636: [72075186224037892][Partition][1][StateIdle] Received TPartition::TEvWrite
2026-03-17T14:14:50.630478Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.630481Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630491Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630494Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630498Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630501Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630503Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630505Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630508Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630510Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630513Z node 9 :PERSQUEUE DEBUG: partition.cpp:2452: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TMessage)
2026-03-17T14:14:50.630516Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 10, PendingWrites: 0
2026-03-17T14:14:50.630518Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.630524Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630554Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 1 partNo 0
2026-03-17T14:14:50.630605Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 1 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 137 count 1 nextOffset 1 batches 1
2026-03-17T14:14:50.630613Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630618Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 2 partNo 0
2026-03-17T14:14:50.630623Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 2 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 243 count 2 nextOffset 2 batches 1
2026-03-17T14:14:50.630629Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630633Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 3 partNo 0
2026-03-17T14:14:50.630638Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 3 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 350 count 3 nextOffset 3 batches 1
2026-03-17T14:14:50.630642Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630645Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 4 partNo 0
2026-03-17T14:14:50.630651Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 4 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 458 count 4 nextOffset 4 batches 1
2026-03-17T14:14:50.630654Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630657Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 5 partNo 0
2026-03-17T14:14:50.630663Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 5 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 567 count 5 nextOffset 5 batches 1
2026-03-17T14:14:50.630666Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630669Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 6 partNo 0
2026-03-17T14:14:50.630673Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 6 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 677 count 6 nextOffset 6 batches 1
2026-03-17T14:14:50.630676Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630679Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 7 partNo 0
2026-03-17T14:14:50.630684Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 7 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 788 count 7 nextOffset 7 batches 1
2026-03-17T14:14:50.630687Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630690Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 8 partNo 0
2026-03-17T14:14:50.630694Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 8 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 900 count 8 nextOffset 8 batches 1
2026-03-17T14:14:50.630697Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630700Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 9 partNo 0
2026-03-17T14:14:50.630705Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 9 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 1013 count 9 nextOffset 9 batches 1
2026-03-17T14:14:50.630709Z node 9 :PERSQUEUE DEBUG: partition.cpp:2516: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TMessage)
2026-03-17T14:14:50.630712Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1348: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob processing sourceId '\0B' seqNo 10 partNo 0
2026-03-17T14:14:50.630716Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1452: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 part blob complete sourceId '\0B' seqNo 10 partNo 0 FormedBlobsCount 0 NewHead: Offset 0 PartNo 0 PackedSize 1127 count 10 nextOffset 10 batches 1
2026-03-17T14:14:50.630719Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 10
2026-03-17T14:14:50.630722Z node 9 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] Batch completed (10)
2026-03-17T14:14:50.630725Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.630784Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:1711: [72075186224037892][Partition][1][StateIdle] Add new write blob: topic 'rt3.dc1--acc--topic3' partition 1 compactOffset 0,10 HeadOffset 0 endOffset 0 curOffset 10 d0000000001_00000000000000000000_00000_0000000010_00000? size 902 WTime 1773756890630
2026-03-17T14:14:50.630821Z node 9 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.630834Z node 9 :PERSQUEUE DEBUG: read.h:313: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough blob. Partition 1 offset 0 partNo 0 count 10 size 902
2026-03-17T14:14:50.632012Z node 9 :PERSQUEUE DEBUG: cache_eviction.h:311: Caching head blob in L1. Partition 1 offset 0 count 10 size 902 actorID [9:7618227837621569903:2445]
2026-03-17T14:14:50.632057Z node 9 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.632083Z node 9 :PERSQUEUE DEBUG: pq_l2_cache.cpp:129: PQ Cache (L2). Adding blob. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 10 parts 0 suffix '63' size 902
2026-03-17T14:14:50.632086Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:492: [72075186224037892][Partition][1][StateIdle] Received TPartition::Handle TEvHandleWriteResponse.
2026-03-17T14:14:50.632118Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 745 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.632136Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632146Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 1, partNo: 0, Offset: 0 is stored on disk
2026-03-17T14:14:50.632154Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632158Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 2, partNo: 0, Offset: 1 is stored on disk
2026-03-17T14:14:50.632161Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632165Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 3, partNo: 0, Offset: 2 is stored on disk
2026-03-17T14:14:50.632167Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632171Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 4, partNo: 0, Offset: 3 is stored on disk
2026-03-17T14:14:50.632174Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632178Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 5, partNo: 0, Offset: 4 is stored on disk
2026-03-17T14:14:50.632181Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632186Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 6, partNo: 0, Offset: 5 is stored on disk
2026-03-17T14:14:50.632190Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632194Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 7, partNo: 0, Offset: 6 is stored on disk
2026-03-17T14:14:50.632197Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632202Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 8, partNo: 0, Offset: 7 is stored on disk
2026-03-17T14:14:50.632204Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632208Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 9, partNo: 0, Offset: 8 is stored on disk
2026-03-17T14:14:50.632210Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:60: [72075186224037892][Partition][1][StateIdle] TPartition::ReplyWrite. Partition: 1
2026-03-17T14:14:50.632215Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:365: [72075186224037892][Partition][1][StateIdle] Answering for message sourceid: '\0B', Topic: 'rt3.dc1--acc--topic3', Partition: 1, SeqNo: 10, partNo: 0, Offset: 9 is stored on disk
2026-03-17T14:14:50.632241Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user user readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.632254Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:976: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user user send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.632260Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.632268Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.632272Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.632274Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.632276Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.632278Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.632286Z node 9 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.632309Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 1  requestId: <none> cookie: 1
2026-03-17T14:14:50.632352Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NKikimr::TEvPersQueue::TEvResponse
2026-03-17T14:14:50.632355Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][1][StateIdle] read cookie 3 Topic 'rt3.dc1--acc--topic3' partition 1 user user offset 0 partno 0 count 1 size 1024000 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.632372Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 3 added 1 blobs, size 902 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.632375Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 3. Send blob request.
2026-03-17T14:14:50.632384Z node 9 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 1 offset 0 partno 0 count 10 parts_count 0 source 1 size 902 accessed 0 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.632446Z node 9 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 3. All 1 blobs are from cache.
2026-03-17T14:14:50.632453Z node 9 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.632460Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.632470Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 882 from pos 0 cbcount 10
2026-03-17T14:14:50.632487Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--acc--topic3' partition 1 user user readTimeStamp done, result 1773756890630 queuesize 1 startOffset 0
2026-03-17T14:14:50.632492Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:934: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer readTimeStamp for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 0 rrg 0
2026-03-17T14:14:50.632496Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:976: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer send read request for offset 0 initiated  queuesize 0 startOffset 0 ReadingTimestamp 1 rrg 0
2026-03-17T14:14:50.632510Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][1][StateIdle] read cookie 4 Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer offset 0 partno 0 count 1 size 1024000 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.632525Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 4 added 1 blobs, size 902 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.632531Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 4. Send blob request.
2026-03-17T14:14:50.632536Z node 9 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 1 offset 0 partno 0 count 10 parts_count 0 source 1 size 902 accessed 1 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.632543Z node 9 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 4. All 1 blobs are from cache.
2026-03-17T14:14:50.632547Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.632552Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 882 from pos 0 cbcount 10
2026-03-17T14:14:50.632556Z node 9 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.632564Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1026: Topic 'rt3.dc1--acc--topic3' partition 1 user first-consumer readTimeStamp done, result 1773756890630 queuesize 0 startOffset 0
===Got response: status: SUCCESS write_response { acks { seq_no: 1 written { } } acks { seq_no: 2 written { offset: 1 } } acks { seq_no: 3 written { offset: 2 } } acks { seq_no: 4 written { offset: 3 } } acks { seq_no: 5 written { offset: 4 } } acks { seq_no: 6 written { offset: 5 } } acks { seq_no: 7 written { offset: 6 } } acks { seq_no: 8 written { offset: 7 } } acks { seq_no: 9 written { offset: 8 } } acks { seq_no: 10 written { offset: 9 } } partition_id: 1 write_statistics { persisting_time { nanos: 1000000 } min_queue_wait_time { } max_queue_wait_time { } partition_quota_wait_time { } topic_quota_wait_time { } } }
2026-03-17T14:14:50.633173Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer  session  grpc read done: success# 1, data# { init_request { topics_read_settings { path: "acc/topic3" } consumer: "user" } }
2026-03-17T14:14:50.633246Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:944: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 read init: from# ipv6:[::1]:52034, request# { init_request { topics_read_settings { path: "acc/topic3" } consumer: "user" } }
2026-03-17T14:14:50.633356Z node 9 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:41: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 auth for : user
2026-03-17T14:14:50.633606Z node 9 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:142: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 Handle describe topics response
2026-03-17T14:14:50.633641Z node 9 :PQ_READ_PROXY DEBUG: read_init_auth_actor.cpp:68: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 auth is DEAD
2026-03-17T14:14:50.633667Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:1061: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 auth ok: topics# 1, initDone# 0
2026-03-17T14:14:50.634009Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:1232: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 register session: topic# rt3.dc1--acc--topic3
2026-03-17T14:14:50.634138Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1669: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570171:2475] connected; active server actors: 1
2026-03-17T14:14:50.634164Z node 9 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1720: [72075186224037894][rt3.dc1--acc--topic3] consumer "user" register session for pipe [9:7618227837621570171:2475] session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.634178Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037894][rt3.dc1--acc--topic3] consumer user register readable partition 0
===Got response: 2026-03-17T14:14:50.634197Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037894][rt3.dc1--acc--topic3] consumer user family created family=1 (Status=Free, Partitions=[0])
2026-03-17T14:14:50.634205Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:639: [72075186224037894][rt3.dc1--acc--topic3] consumer user register readable partition 1
status: SUCCESS init_response { session_id: "shared/user_9_1_13779178472965367076_v1" }
2026-03-17T14:14:50.634210Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:669: [72075186224037894][rt3.dc1--acc--topic3] consumer user family created family=2 (Status=Free, Partitions=[1])
2026-03-17T14:14:50.634217Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:871: [72075186224037894][rt3.dc1--acc--topic3] consumer user register reading session ReadingSession "shared/user_9_1_13779178472965367076_v1" (Sender=[9:7618227837621570095:2475], Pipe=[9:7618227837621570171:2475], Partitions=[], ActiveFamilyCount=0)
2026-03-17T14:14:50.634224Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1187: [72075186224037894][rt3.dc1--acc--topic3] consumer user rebalancing was scheduled
2026-03-17T14:14:50.634234Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1259: [72075186224037894][rt3.dc1--acc--topic3] consumer user balancing. Sessions=1, Families=2, UnreadableFamilies=2 [2 (1), 1 (0), ], RequireBalancing=0 []
2026-03-17T14:14:50.634242Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1306: [72075186224037894][rt3.dc1--acc--topic3] consumer user balancing family=2 (Status=Free, Partitions=[1]) for ReadingSession "shared/user_9_1_13779178472965367076_v1" (Sender=[9:7618227837621570095:2475], Pipe=[9:7618227837621570171:2475], Partitions=[], ActiveFamilyCount=0)
2026-03-17T14:14:50.634259Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037894][rt3.dc1--acc--topic3] consumer user family 2 status Active partitions [1] session "shared/user_9_1_13779178472965367076_v1" sender [9:7618227837621570095:2475] lock partition 1 for ReadingSession "shared/user_9_1_13779178472965367076_v1" (Sender=[9:7618227837621570095:2475], Pipe=[9:7618227837621570171:2475], Partitions=[], ActiveFamilyCount=1) generation 1 step 1
2026-03-17T14:14:50.634279Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1306: [72075186224037894][rt3.dc1--acc--topic3] consumer user balancing family=1 (Status=Free, Partitions=[0]) for ReadingSession "shared/user_9_1_13779178472965367076_v1" (Sender=[9:7618227837621570095:2475], Pipe=[9:7618227837621570171:2475], Partitions=[], ActiveFamilyCount=1)
2026-03-17T14:14:50.634284Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:549: [72075186224037894][rt3.dc1--acc--topic3] consumer user family 1 status Active partitions [0] session "shared/user_9_1_13779178472965367076_v1" sender [9:7618227837621570095:2475] lock partition 0 for ReadingSession "shared/user_9_1_13779178472965367076_v1" (Sender=[9:7618227837621570095:2475], Pipe=[9:7618227837621570171:2475], Partitions=[], ActiveFamilyCount=2) generation 1 step 2
2026-03-17T14:14:50.634289Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1326: [72075186224037894][rt3.dc1--acc--topic3] consumer user start rebalancing. familyCount=2, sessionCount=1, desiredFamilyCount=2, allowPlusOne=0
2026-03-17T14:14:50.634295Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1404: [72075186224037894][rt3.dc1--acc--topic3] consumer user balancing duration: 0.000057s
2026-03-17T14:14:50.634522Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:1354: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 from= ipv6:[::1]:52034 user=- topic=Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root assign: record# { Partition: 1 TabletId: 72075186224037892 Topic: "rt3.dc1--acc--topic3" Generation: 1 Step: 1 Session: "shared/user_9_1_13779178472965367076_v1" ClientId: "user" PipeClient { RawX1: 7618227837621570171 RawX2: 4503638282078635 } Path: "/Root/PQ/rt3.dc1--acc--topic3" }
2026-03-17T14:14:50.634561Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:1354: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 from= ipv6:[::1]:52034 user=- topic=Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root assign: record# { Partition: 0 TabletId: 72075186224037893 Topic: "rt3.dc1--acc--topic3" Generation: 1 Step: 2 Session: "shared/user_9_1_13779178472965367076_v1" ClientId: "user" PipeClient { RawX1: 7618227837621570171 RawX2: 4503638282078635 } Path: "/Root/PQ/rt3.dc1--acc--topic3" }
2026-03-17T14:14:50.634581Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.634583Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1204: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 INITING TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1)
2026-03-17T14:14:50.634614Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1204: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 INITING TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2)
2026-03-17T14:14:50.634630Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 5af3519e-7cbbbae1-9cc8f620-37bbbb28
2026-03-17T14:14:50.634667Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037892] server connected, pipe [9:7618227837621570175:2486], now have 1 active actors on pipe
2026-03-17T14:14:50.634667Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1022: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037892 Generation: 1, pipe: [9:7618227837621570175:2486]
2026-03-17T14:14:50.634682Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.634688Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.634702Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:1764: [PQ: 72075186224037892] Created session shared/user_9_1_13779178472965367076_v1 on pipe: [9:7618227837621570175:2486]
2026-03-17T14:14:50.634727Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.634731Z node 9 :PERSQUEUE DEBUG: partition.cpp:2438: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2026-03-17T14:14:50.634738Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.634742Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.634742Z node 9 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_9_1_13779178472965367076_v1:1 with generation 1
2026-03-17T14:14:50.634745Z node 9 :PERSQUEUE DEBUG: partition.cpp:2502: [72075186224037892][Partition][1][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2026-03-17T14:14:50.634764Z node 9 :PERSQUEUE DEBUG: partition.cpp:3940: [72075186224037892][Partition][1][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 1 user user session is set to 0 (startOffset 0) session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.634766Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.634767Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.634768Z node 9 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037892][Partition][1][StateIdle] Batch completed (1)
2026-03-17T14:14:50.634771Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.634786Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 904c9326-9b3b65f1-bfa7bf10-22af0190
2026-03-17T14:14:50.634810Z node 9 :PERSQUEUE DEBUG: read.h:275: [72075186224037892][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.634831Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1022: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) pipe restart attempt 0 pipe creation result: OK TabletId: 72075186224037893 Generation: 1, pipe: [9:7618227837621570176:2487]
2026-03-17T14:14:50.634867Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2807: [PQ: 72075186224037893] server connected, pipe [9:7618227837621570176:2487], now have 1 active actors on pipe
2026-03-17T14:14:50.635006Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.635019Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.635028Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:1764: [PQ: 72075186224037893] Created session shared/user_9_1_13779178472965367076_v1 on pipe: [9:7618227837621570176:2487]
2026-03-17T14:14:50.635050Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.635059Z node 10 :PERSQUEUE DEBUG: partition.cpp:2438: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxEvent(TEvPQ::TEvSetClientInfo)
2026-03-17T14:14:50.635065Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 1, PendingWrites: 0
2026-03-17T14:14:50.635068Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.635072Z node 10 :PERSQUEUE DEBUG: partition.cpp:2502: [72075186224037893][Partition][0][StateIdle] TPartition::ProcessUserActionAndTxPendingCommit(TEvPQ::TEvSetClientInfo)
2026-03-17T14:14:50.635087Z node 10 :PERSQUEUE DEBUG: partition.cpp:3940: [72075186224037893][Partition][0][StateIdle] Topic 'rt3.dc1--acc--topic3' partition 0 user user session is set to 0 (startOffset 0) session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.635095Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 1
2026-03-17T14:14:50.635098Z node 10 :PERSQUEUE DEBUG: partition.cpp:2371: [72075186224037893][Partition][0][StateIdle] Batch completed (1)
2026-03-17T14:14:50.635101Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.635145Z node 10 :PERSQUEUE DEBUG: read.h:275: [72075186224037893][PQCacheProxy]CacheProxy. Passthrough write request to KV
2026-03-17T14:14:50.635142Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635182Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 71234ad7-c5cc007e-57c4fb9b-4666dd44
2026-03-17T14:14:50.635288Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635301Z node 10 :PQ_READ_PROXY DEBUG: caching_service.cpp:283: Direct read cache: registered server session: shared/user_9_1_13779178472965367076_v1:2 with generation 1
2026-03-17T14:14:50.635308Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# d1255afa-c0ee2d3b-3cc3518a-18fe9b3d
2026-03-17T14:14:50.635347Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635361Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 33b1e8ad-31d311c3-44cde94b-d8a70169
2026-03-17T14:14:50.635455Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635474Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 73e5bd2b-d3c5d2d5-5eb2443d-87f05adb
2026-03-17T14:14:50.635514Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635537Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 4499ff6b-372e1fcf-1659d883-706443d2
2026-03-17T14:14:50.635562Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635577Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 9edb07ea-1393d24b-81a82b91-78ecc853
2026-03-17T14:14:50.635586Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635601Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 1b5c52c6-1aecc5df-2b51819f-eacc220e
2026-03-17T14:14:50.635613Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { read_request { bytes_size: 256 } }
2026-03-17T14:14:50.635618Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1857: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got read request: guid# 20b324b9-a9b165d-3f1fb3af-2028496
2026-03-17T14:14:50.635762Z node 9 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037892][Partition][1][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.635804Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037892][Partition][1][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.635820Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.635827Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.635829Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.635831Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.635833Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.635840Z node 9 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037892][Partition][1][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.635867Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 2
2026-03-17T14:14:50.635929Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:903: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 10 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 SizeLag: 902 WriteTimestampEstimateMS: 1773756890632 ClientHasAnyCommits: false } Cookie: 2 }
2026-03-17T14:14:50.635943Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:631: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 INIT DONE TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) EndOffset 10 readOffset 0 committedOffset 0
2026-03-17T14:14:50.635979Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1453: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 sending to client partition status
2026-03-17T14:14:50.636048Z node 10 :PERSQUEUE DEBUG: partition.cpp:2182: [72075186224037893][Partition][0][StateIdle] Received TEvKeyValue::TEvResponse
2026-03-17T14:14:50.636083Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:572: [72075186224037893][Partition][0][StateIdle] TPartition::HandleWriteResponse writeNewSize# 0 WriteNewSizeFromSupportivePartitions# 0
2026-03-17T14:14:50.636094Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.636097Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.636098Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.636101Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.636103Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.636108Z node 10 :PERSQUEUE DEBUG: partition_compaction.cpp:193: [72075186224037893][Partition][0][StateIdle] No data for blobs compaction
2026-03-17T14:14:50.636118Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 0  requestId: <none> cookie: 2
===Expect 1st start part, Got response: status: SUCCESS start_partition_session_request { partition_session { partition_session_id: 1 path: "acc/topic3" partition_id: 1 } partition_offsets { end: 10 } }
2026-03-17T14:14:50.636230Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:903: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) initDone 0 event { CmdGetClientOffsetResult { Offset: 0 EndOffset: 10 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 SizeLag: 892 WriteTimestampEstimateMS: 1773756890618 ClientHasAnyCommits: false } Cookie: 2 }
2026-03-17T14:14:50.636243Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:631: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 INIT DONE TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) EndOffset 10 readOffset 0 committedOffset 0
2026-03-17T14:14:50.636259Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1453: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 sending to client partition status
===Expect 2nd start part, Got response: status: SUCCESS start_partition_session_request { partition_session { partition_session_id: 2 path: "acc/topic3" } partition_offsets { end: 10 } }
2026-03-17T14:14:50.637439Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1 } }
2026-03-17T14:14:50.637512Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1), readOffset# 0, commitOffset# (empty maybe)
2026-03-17T14:14:50.637533Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 1124134 } }
2026-03-17T14:14:50.637541Z node 9 :PQ_READ_PROXY WARN: read_session_actor.cpp:544: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got irrelevant StartRead from client: partition# 1124134, offset# 0
2026-03-17T14:14:50.637568Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1084: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 Start reading TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) EndOffset 10 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2026-03-17T14:14:50.637584Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1008: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) ready for read with readOffset 0 endOffset 10
2026-03-17T14:14:50.637623Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2350: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1), readOffset# 0, endOffset# 10, WTime# 1773756890630, sizeLag# 902
2026-03-17T14:14:50.637632Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2361: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TEvPartitionReady. Aval parts: 1
2026-03-17T14:14:50.637648Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2284: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 performing read request: guid# 3f4465ff-52c84d8d-46a320a2-9bc169a3, from# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1), count# 12, size# 1082, partitionsAsked# 1, maxTimeLag# 0ms
2026-03-17T14:14:50.637683Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1458: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 READ FROM TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) maxCount 12 maxSize 1082 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 10 ClientCommitOffset 0 committedOffset 0 Guid 3f4465ff-52c84d8d-46a320a2-9bc169a3
2026-03-17T14:14:50.637722Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.637735Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037892] got client message batch for topic 'rt3.dc1--acc--topic3' partition 1
2026-03-17T14:14:50.637779Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037892][Partition][1][StateIdle] read cookie 5 Topic 'rt3.dc1--acc--topic3' partition 1 user user offset 0 partno 0 count 12 size 1082 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.637809Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037892][Partition][1][StateIdle] read cookie 5 added 1 blobs, size 902 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.637819Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037892][Partition][1][StateIdle] Reading cookie 5. Send blob request.
2026-03-17T14:14:50.637834Z node 9 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 1 offset 0 partno 0 count 10 parts_count 0 source 1 size 902 accessed 2 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.637852Z node 9 :PERSQUEUE DEBUG: read.h:126: [72075186224037892][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2026-03-17T14:14:50.637863Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.637873Z node 9 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 882 from pos 0 cbcount 10
2026-03-17T14:14:50.637876Z node 9 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037892' partition 1 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.638020Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 1 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.638214Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:903: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) initDone 1 event { CmdReadResult { MaxOffset: 10 Result { Offset: 0 Data: "... 68 bytes ..." SourceId: "\000B" SeqNo: 1 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 1 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 69 bytes ..." SourceId: "\000B" SeqNo: 2 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 2 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 70 bytes ..." SourceId: "\000B" SeqNo: 3 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 3 PartitionKey: "" ExplicitHash: "" } Result { Offset: 3 Data: "... 71 bytes ..." SourceId: "\000B" SeqNo: 4 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 4 PartitionKey: "" ExplicitHash: "" } Result { Offset: 4 Data: "... 72 bytes ..." SourceId: "\000B" SeqNo: 5 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 5 PartitionKey: "" ExplicitHash: "" } Result { Offset: 5 Data: "... 73 bytes ..." SourceId: "\000B" SeqNo: 6 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 6 PartitionKey: "" ExplicitHash: "" } Result { Offset: 6 Data: "... 74 bytes ..." SourceId: "\000B" SeqNo: 7 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 7 PartitionKey: "" ExplicitHash: "" } Result { Offset: 7 Data: "... 75 bytes ..." SourceId: "\000B" SeqNo: 8 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 8 Data: "... 76 bytes ..." SourceId: "\000B" SeqNo: 9 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 9 PartitionKey: "" ExplicitHash: "" } Result { Offset: 9 Data: "... 77 bytes ..." SourceId: "\000B" SeqNo: 10 WriteTimestampMS: 1773756890630 CreateTimestampMS: 1773756890629 UncompressedSize: 10 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 18446744073709551423 RealReadOffset: 9 WaitQuotaTimeMs: 0 EndOffset: 10 StartOffset: 0 } Cookie: 0 }
2026-03-17T14:14:50.638285Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:814: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) isMemoryLimitReached 0 EndOffset 10 ReadOffset 10 read result size 10
2026-03-17T14:14:50.638295Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1338: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) wait data in partition inited, cookie 1 from offset 10
2026-03-17T14:14:50.638303Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:828: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 after read state TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1) EndOffset 10 ReadOffset 10 ReadGuid 3f4465ff-52c84d8d-46a320a2-9bc169a3 has messages 1
2026-03-17T14:14:50.638338Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1958: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 read done: guid# 3f4465ff-52c84d8d-46a320a2-9bc169a3, partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 1(assignId:1), size# 444
2026-03-17T14:14:50.638359Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2120: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 response to read: guid# 3f4465ff-52c84d8d-46a320a2-9bc169a3
2026-03-17T14:14:50.638463Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2163: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 Process answer. Aval parts: 0
2026-03-17T14:14:50.685089Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.685109Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.685112Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.685116Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.685119Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.685862Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.685882Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.685885Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.685888Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.685891Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
Got read response { status: SUCCESS read_response { partition_data { partition_session_id: 1 batches { message_data { seq_no: 1 created_at { seconds: 1773756890 nanos: 629000000 } data: "y" uncompressed_size: 1 message_group_id: "B" } message_data { offset: 1 seq_no: 2 created_at { seconds: 1773756890 nanos: 629000000 } data: "yy" uncompressed_size: 2 message_group_id: "B" } message_data { offset: 2 seq_no: 3 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyy" uncompressed_size: 3 message_group_id: "B" } message_data { offset: 3 seq_no: 4 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyy" uncompressed_size: 4 message_group_id: "B" } message_data { offset: 4 seq_no: 5 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyy" uncompressed_size: 5 message_group_id: "B" } message_data { offset: 5 seq_no: 6 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyyy" uncompressed_size: 6 message_group_id: "B" } message_data { offset: 6 seq_no: 7 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyyyy" uncompressed_size: 7 message_group_id: "B" } message_data { offset: 7 seq_no: 8 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyyyyy" uncompressed_size: 8 message_group_id: "B" } message_data { offset: 8 seq_no: 9 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyyyyyy" uncompressed_size: 9 message_group_id: "B" } message_data { offset: 9 seq_no: 10 created_at { seconds: 1773756890 nanos: 629000000 } data: "yyyyyyyyyy" uncompressed_size: 10 message_group_id: "B" } producer_id: "B" write_session_meta { key: "_ip" value: "ipv6:[::1]:52034" } write_session_meta { key: "ident" value: "acc" } write_session_meta { key: "logtype" value: "topic3" } write_session_meta { key: "server" value: "ipv6:[::1]:52034" } codec: 10042 written_at { seconds: 1773756890 nanos: 630000000 } } } bytes_size: 444 } }
2026-03-17T14:14:50.742278Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 1, data# { start_partition_session_response { partition_session_id: 2 } }
2026-03-17T14:14:50.742338Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:551: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 got StartRead from client: partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2), readOffset# 0, commitOffset# (empty maybe)
2026-03-17T14:14:50.742365Z node 9 :PQ_READ_PROXY INFO: partition_actor.cpp:1084: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 Start reading TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) EndOffset 10 readOffset 0 committedOffset 0 clientCommitOffset (empty maybe) clientReadOffset 0
2026-03-17T14:14:50.742374Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1008: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) ready for read with readOffset 0 endOffset 10
2026-03-17T14:14:50.742428Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2350: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 partition ready for read: partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2), readOffset# 0, endOffset# 10, WTime# 1773756890616, sizeLag# 892
2026-03-17T14:14:50.742441Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2361: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TEvPartitionReady. Aval parts: 1
2026-03-17T14:14:50.742461Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2284: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 performing read request: guid# af0234a6-780c9702-2ac34092-a9d1132d, from# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2), count# 12, size# 1070, partitionsAsked# 1, maxTimeLag# 0ms
2026-03-17T14:14:50.742492Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1458: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 READ FROM TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) maxCount 12 maxSize 1070 maxTimeLagMs 0 readTimestampMs 0 readOffset 0 EndOffset 10 ClientCommitOffset 0 committedOffset 0 Guid af0234a6-780c9702-2ac34092-a9d1132d
2026-03-17T14:14:50.745565Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:148: Handle TEvRequest topic: 'rt3.dc1--acc--topic3' requestId: <none>
2026-03-17T14:14:50.745593Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2718: [PQ: 72075186224037893] got client message batch for topic 'rt3.dc1--acc--topic3' partition 0
2026-03-17T14:14:50.745653Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:891: [72075186224037893][Partition][0][StateIdle] read cookie 5 Topic 'rt3.dc1--acc--topic3' partition 0 user user offset 0 partno 0 count 12 size 1070 endOffset 10 max time lag 0ms effective offset 0
2026-03-17T14:14:50.745686Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1116: [72075186224037893][Partition][0][StateIdle] read cookie 5 added 1 blobs, size 892 count 10 last offset 0, current partition end offset: 10
2026-03-17T14:14:50.745697Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:1132: [72075186224037893][Partition][0][StateIdle] Reading cookie 5. Send blob request.
2026-03-17T14:14:50.745711Z node 10 :PERSQUEUE DEBUG: cache_eviction.h:487: Got data from cache. Partition 0 offset 0 partno 0 count 10 parts_count 0 source 1 size 892 accessed 2 times before, last time 2026-03-17T14:14:50.000000Z
2026-03-17T14:14:50.745723Z node 10 :PERSQUEUE DEBUG: read.h:126: [72075186224037893][PQCacheProxy]Reading cookie 5. All 1 blobs are from cache.
2026-03-17T14:14:50.745734Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:580: FormAnswer for 1 blobs
2026-03-17T14:14:50.745768Z node 10 :PERSQUEUE DEBUG: partition_read.cpp:500: FormAnswer processing batch offset 0 totakecount 10 count 10 size 872 from pos 0 cbcount 10
2026-03-17T14:14:50.745937Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:183: Answer ok topic: 'rt3.dc1--acc--topic3' partition: 0 messageNo: 0  requestId: <none> cookie: 0
2026-03-17T14:14:50.746034Z node 10 :PERSQUEUE DEBUG: pq_l2_cache.cpp:201: PQ Cache (L2). Touched. Tablet '72075186224037893' partition 0 offset 0 partno 0 count 10 parts 0 suffix '63'
2026-03-17T14:14:50.746335Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:903: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) initDone 1 event { CmdReadResult { MaxOffset: 10 Result { Offset: 0 Data: "... 67 bytes ..." SourceId: "\000A" SeqNo: 1 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 1 PartitionKey: "" ExplicitHash: "" } Result { Offset: 1 Data: "... 68 bytes ..." SourceId: "\000A" SeqNo: 2 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 2 PartitionKey: "" ExplicitHash: "" } Result { Offset: 2 Data: "... 69 bytes ..." SourceId: "\000A" SeqNo: 3 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 3 PartitionKey: "" ExplicitHash: "" } Result { Offset: 3 Data: "... 70 bytes ..." SourceId: "\000A" SeqNo: 4 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 4 PartitionKey: "" ExplicitHash: "" } Result { Offset: 4 Data: "... 71 bytes ..." SourceId: "\000A" SeqNo: 5 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 5 PartitionKey: "" ExplicitHash: "" } Result { Offset: 5 Data: "... 72 bytes ..." SourceId: "\000A" SeqNo: 6 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 6 PartitionKey: "" ExplicitHash: "" } Result { Offset: 6 Data: "... 73 bytes ..." SourceId: "\000A" SeqNo: 7 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 7 PartitionKey: "" ExplicitHash: "" } Result { Offset: 7 Data: "... 74 bytes ..." SourceId: "\000A" SeqNo: 8 WriteTimestampMS: 1773756890616 CreateTimestampMS: 1773756890610 UncompressedSize: 8 PartitionKey: "" ExplicitHash: "" } Result { Offset: 8 Data: "... 75 bytes ..." SourceId: "\000A" SeqNo: 9 WriteTimestampMS: 1773756890617 CreateTimestampMS: 1773756890610 UncompressedSize: 9 PartitionKey: "" ExplicitHash: "" } Result { Offset: 9 Data: "... 76 bytes ..." SourceId: "\000A" SeqNo: 10 WriteTimestampMS: 1773756890617 CreateTimestampMS: 1773756890610 UncompressedSize: 10 PartitionKey: "" ExplicitHash: "" } BlobsFromDisk: 0 BlobsFromCache: 1 SizeLag: 18446744073709551423 RealReadOffset: 9 WaitQuotaTimeMs: 0 EndOffset: 10 StartOffset: 0 } Cookie: 0 }
2026-03-17T14:14:50.746400Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:814: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) isMemoryLimitReached 0 EndOffset 10 ReadOffset 10 read result size 10
2026-03-17T14:14:50.746404Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:1338: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) wait data in partition inited, cookie 1 from offset 10
2026-03-17T14:14:50.746411Z node 9 :PQ_READ_PROXY DEBUG: partition_actor.cpp:828: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 after read state TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2) EndOffset 10 ReadOffset 10 ReadGuid af0234a6-780c9702-2ac34092-a9d1132d has messages 1
2026-03-17T14:14:50.746473Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:1958: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 read done: guid# af0234a6-780c9702-2ac34092-a9d1132d, partition# TopicId: Topic rt3.dc1--acc--topic3 in dc dc1 in database: Root, partition 0(assignId:2), size# 551
2026-03-17T14:14:50.746490Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2120: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 response to read: guid# af0234a6-780c9702-2ac34092-a9d1132d
2026-03-17T14:14:50.746701Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:2163: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 Process answer. Aval parts: 0
Got read response { status: SUCCESS read_response { partition_data { partition_session_id: 2 batches { message_data { seq_no: 1 created_at { seconds: 1773756890 nanos: 610000000 } data: "x" uncompressed_size: 1 message_group_id: "A" } message_data { offset: 1 seq_no: 2 created_at { seconds: 1773756890 nanos: 610000000 } data: "xx" uncompressed_size: 2 message_group_id: "A" } message_data { offset: 2 seq_no: 3 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxx" uncompressed_size: 3 message_group_id: "A" } message_data { offset: 3 seq_no: 4 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxx" uncompressed_size: 4 message_group_id: "A" } message_data { offset: 4 seq_no: 5 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxx" uncompressed_size: 5 message_group_id: "A" } message_data { offset: 5 seq_no: 6 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxxx" uncompressed_size: 6 message_group_id: "A" } message_data { offset: 6 seq_no: 7 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxxxx" uncompressed_size: 7 message_group_id: "A" } message_data { offset: 7 seq_no: 8 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxxxxx" uncompressed_size: 8 message_group_id: "A" } producer_id: "A" write_session_meta { key: "_ip" value: "ipv6:[::1]:52034" } write_session_meta { key: "ident" value: "acc" } write_session_meta { key: "logtype" value: "topic3" } write_session_meta { key: "server" value: "ipv6:[::1]:52034" } codec: 1 written_at { seconds: 1773756890 nanos: 616000000 } } batches { message_data { offset: 8 seq_no: 9 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxxxxxx" uncompressed_size: 9 message_group_id: "A" } message_data { offset: 9 seq_no: 10 created_at { seconds: 1773756890 nanos: 610000000 } data: "xxxxxxxxxx" uncompressed_size: 10 message_group_id: "A" } producer_id: "A" write_session_meta { key: "_ip" value: "ipv6:[::1]:52034" } write_session_meta { key: "ident" value: "acc" } write_session_meta { key: "logtype" value: "topic3" } write_session_meta { key: "server" value: "ipv6:[::1]:52034" } codec: 1 written_at { seconds: 1773756890 nanos: 617000000 } } } bytes_size: 551 } }
2026-03-17T14:14:50.751278Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2832: [PQ: 72075186224037893] server disconnected, pipe [9:7618227837621570128:2476] destroyed
2026-03-17T14:14:50.751306Z node 10 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037893][Partition][0][StateIdle] TPartition::DropOwner.
2026-03-17T14:14:50.751318Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.750676Z node 9 :PQ_READ_PROXY DEBUG: read_session_actor.cpp:122: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read done: success# 0, data# {  }
2026-03-17T14:14:50.751321Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.750691Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:125: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc read failed
2026-03-17T14:14:50.751324Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.750698Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:92: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 grpc closed
2026-03-17T14:14:50.751328Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.750719Z node 9 :PQ_READ_PROXY INFO: read_session_actor.cpp:383: session cookie 1 consumer shared/user session shared/user_9_1_13779178472965367076_v1 is DEAD
2026-03-17T14:14:50.751330Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.750894Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 2 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0 grpc read done: success: 0 data: 
2026-03-17T14:14:50.750897Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 2 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0 grpc read failed
2026-03-17T14:14:50.750903Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 2 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0 grpc closed
2026-03-17T14:14:50.750908Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 2 sessionId: A|e1b2e22-885d3939-e38e46ef-e900038a_0 is DEAD
2026-03-17T14:14:50.751063Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037893 (partition=0) Received event: NActors::TEvents::TEvPoison
2026-03-17T14:14:50.751083Z node 9 :PQ_WRITE_PROXY DEBUG: write_session_actor.cpp:254: session v1 cookie: 3 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0 grpc read done: success: 0 data: 
2026-03-17T14:14:50.751084Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:256: session v1 cookie: 3 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0 grpc read failed
2026-03-17T14:14:50.751088Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:232: session v1 cookie: 3 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0 grpc closed
2026-03-17T14:14:50.751091Z node 9 :PQ_WRITE_PROXY INFO: write_session_actor.cpp:304: session v1 cookie: 3 sessionId: B|5d076d70-ca047b69-96b8127a-524f2c18_0 is DEAD
2026-03-17T14:14:50.751180Z node 9 :PQ_WRITE_PROXY DEBUG: writer.cpp:562: TPartitionWriter 72075186224037892 (partition=1) Received event: NActors::TEvents::TEvPoison
2026-03-17T14:14:50.751268Z node 9 :PERSQUEUE_READ_BALANCER DEBUG: read_balancer__balancing.cpp:1673: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570171:2475] disconnected.
2026-03-17T14:14:50.751273Z node 9 :PERSQUEUE_READ_BALANCER INFO: read_balancer__balancing.cpp:1684: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570171:2475] disconnected; active server actors: 1
2026-03-17T14:14:50.751277Z node 9 :PERSQUEUE_READ_BALANCER NOTICE: read_balancer__balancing.cpp:1693: [72075186224037894][rt3.dc1--acc--topic3] pipe [9:7618227837621570171:2475] client user disconnected session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.751612Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2832: [PQ: 72075186224037892] server disconnected, pipe [9:7618227837621570163:2477] destroyed
2026-03-17T14:14:50.751622Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2324: [PQ: 72075186224037892] Destroy direct read session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.751635Z node 9 :PERSQUEUE DEBUG: pq_impl.cpp:2832: [PQ: 72075186224037892] server disconnected, pipe [9:7618227837621570175:2486] destroyed
2026-03-17T14:14:50.751643Z node 9 :PERSQUEUE DEBUG: partition_write.cpp:140: [72075186224037892][Partition][1][StateIdle] TPartition::DropOwner.
2026-03-17T14:14:50.751650Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.751654Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.751657Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.751660Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.751662Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.751678Z node 9 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.753876Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2324: [PQ: 72075186224037893] Destroy direct read session shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.753906Z node 10 :PERSQUEUE DEBUG: pq_impl.cpp:2832: [PQ: 72075186224037893] server disconnected, pipe [9:7618227837621570176:2487] destroyed
2026-03-17T14:14:50.753927Z node 10 :PQ_READ_PROXY DEBUG: caching_service.cpp:139: Direct read cache: server session deregistered: shared/user_9_1_13779178472965367076_v1
2026-03-17T14:14:50.786366Z node 10 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037893][Partition][0][StateIdle] Process user action and tx events
2026-03-17T14:14:50.786389Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.786392Z node 10 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037893][Partition][0][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.786396Z node 10 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037893][Partition][0][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.786399Z node 10 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037893][Partition][0][StateIdle] Try persist
2026-03-17T14:14:50.786238Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.786260Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.786265Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.786270Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.786273Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.890082Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.890103Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.890107Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.890111Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.890115Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
2026-03-17T14:14:50.998300Z node 9 :PERSQUEUE DEBUG: partition.cpp:2358: [72075186224037892][Partition][1][StateIdle] Process user action and tx events
2026-03-17T14:14:50.998323Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.998326Z node 9 :PERSQUEUE DEBUG: partition.cpp:2366: [72075186224037892][Partition][1][StateIdle] Process user action and tx pending commits
2026-03-17T14:14:50.998336Z node 9 :PERSQUEUE DEBUG: partition.cpp:2417: [72075186224037892][Partition][1][StateIdle] Events: 0, PendingCommits: 0, PendingWrites: 0
2026-03-17T14:14:50.998340Z node 9 :PERSQUEUE DEBUG: partition.cpp:2376: [72075186224037892][Partition][1][StateIdle] Try persist
